builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0349 starttime: 1490799360.26 results: success (0) buildid: 20170329064223 builduid: 29f3aeb65f074e0eb7850850a042e173 revision: 6ea713ccc9abea93126423fefb855d0e051c95e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:00.255905) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:00.256269) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2017-03-29 07:56:00.256535) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2017-03-29 07:56:03.437992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:03.438296) ========= 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.4tNOSSatOM/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.jZgxcOgazK/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-03-29 07:56:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-03-29 07:56:03 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137824 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:03.606548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:03.607284) ========= 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.4tNOSSatOM/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.jZgxcOgazK/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.026002 ========= master_lag: 0.90 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:04.530163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 07:56:04.530528) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6ea713ccc9abea93126423fefb855d0e051c95e2 --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 6ea713ccc9abea93126423fefb855d0e051c95e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4tNOSSatOM/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.jZgxcOgazK/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-03-29 07:56:04,638 truncating revision to first 12 chars 2017-03-29 07:56:04,639 Setting DEBUG logging. 2017-03-29 07:56:04,639 attempt 1/10 2017-03-29 07:56:04,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6ea713ccc9ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 07:56:05,976 unpacking tar archive at: mozilla-central-6ea713ccc9ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.610628 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 07:56:06.167878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:06.168211) ========= script_repo_revision: 6ea713ccc9abea93126423fefb855d0e051c95e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:06.168679) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:06.168949) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 07:56:06.188311) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-29 07:56:06.188653) ========= /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.4tNOSSatOM/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.jZgxcOgazK/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:56:06 INFO - MultiFileLogger online at 20170329 07:56:06 in /builds/slave/test 07:56:06 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 07:56:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:56:06 INFO - {'append_to_log': False, 07:56:06 INFO - 'base_work_dir': '/builds/slave/test', 07:56:06 INFO - 'blob_upload_branch': 'Firefox', 07:56:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:56:06 INFO - 'branch': 'Firefox', 07:56:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:06 INFO - 'config_files': ('talos/mac_config.py',), 07:56:06 INFO - 'default_actions': ('clobber', 07:56:06 INFO - 'read-buildbot-config', 07:56:06 INFO - 'download-and-extract', 07:56:06 INFO - 'populate-webroot', 07:56:06 INFO - 'create-virtualenv', 07:56:06 INFO - 'install', 07:56:06 INFO - 'run-tests'), 07:56:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:06 INFO - 'download_minidump_stackwalk': True, 07:56:06 INFO - 'download_symbols': 'ondemand', 07:56:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:56:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:56:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:56:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:56:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:06 INFO - 'gecko_profile': False, 07:56:06 INFO - 'gecko_profile_interval': 0, 07:56:06 INFO - 'installer_path': 'installer.exe', 07:56:06 INFO - 'log_level': 'info', 07:56:06 INFO - 'log_name': 'talos', 07:56:06 INFO - 'log_to_console': True, 07:56:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:56:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:56:06 INFO - 'opt_config_files': (), 07:56:06 INFO - 'pip_index': False, 07:56:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:06 INFO - 'cmd': ('bash', 07:56:06 INFO - '-c', 07:56:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:06 INFO - 'enabled': True, 07:56:06 INFO - 'halt_on_failure': False, 07:56:06 INFO - 'name': 'check_screen_resolution'},), 07:56:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:06 INFO - 'cmd': ('bash', 07:56:06 INFO - '-c', 07:56:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:06 INFO - 'enabled': True, 07:56:06 INFO - 'halt_on_failure': False, 07:56:06 INFO - 'name': 'check_screen_resolution'},), 07:56:06 INFO - 'run_cmd_checks_enabled': True, 07:56:06 INFO - 'suite': 'dromaeojs', 07:56:06 INFO - 'system_bits': '32', 07:56:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:06 INFO - 'title': 't-yosemite-r7-0349', 07:56:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:06 INFO - 'use_talos_json': True, 07:56:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:56:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:06 INFO - 'work_dir': 'build'} 07:56:06 INFO - [mozharness: 2017-03-29 14:56:06.403535Z] Running clobber step. 07:56:06 INFO - Running pre-action listener: _resource_record_pre_action 07:56:06 INFO - Running main action method: clobber 07:56:06 INFO - rmtree: /builds/slave/test/build 07:56:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:56:08 INFO - Running post-action listener: _resource_record_post_action 07:56:08 INFO - [mozharness: 2017-03-29 14:56:08.907135Z] Finished clobber step (success) 07:56:08 INFO - [mozharness: 2017-03-29 14:56:08.907298Z] Running read-buildbot-config step. 07:56:08 INFO - Running pre-action listener: _resource_record_pre_action 07:56:08 INFO - Running main action method: read_buildbot_config 07:56:08 INFO - Using buildbot properties: 07:56:08 INFO - { 07:56:08 INFO - "project": "", 07:56:08 INFO - "product": "firefox", 07:56:08 INFO - "script_repo_revision": "production", 07:56:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:56:08 INFO - "repository": "", 07:56:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:56:08 INFO - "buildid": "20170329064223", 07:56:08 INFO - "pgo_build": "False", 07:56:08 INFO - "basedir": "/builds/slave/test", 07:56:08 INFO - "buildnumber": 1, 07:56:08 INFO - "slavename": "t-yosemite-r7-0349", 07:56:08 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:56:08 INFO - "platform": "yosemite_r7", 07:56:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:56:08 INFO - "branch": "mozilla-central", 07:56:08 INFO - "repo_path": "mozilla-central", 07:56:08 INFO - "revision": "6ea713ccc9abea93126423fefb855d0e051c95e2", 07:56:08 INFO - "stage_platform": "macosx64", 07:56:08 INFO - "builduid": "29f3aeb65f074e0eb7850850a042e173", 07:56:08 INFO - "slavebuilddir": "test" 07:56:08 INFO - } 07:56:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:56:08 INFO - Running post-action listener: _resource_record_post_action 07:56:08 INFO - [mozharness: 2017-03-29 14:56:08.910655Z] Finished read-buildbot-config step (success) 07:56:08 INFO - [mozharness: 2017-03-29 14:56:08.910763Z] Running download-and-extract step. 07:56:08 INFO - Running pre-action listener: _resource_record_pre_action 07:56:08 INFO - Running main action method: download_and_extract 07:56:08 INFO - mkdir: /builds/slave/test/build/tests 07:56: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')]} 07:56:08 INFO - trying https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:56:08 INFO - Downloading https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:56:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:56:10 INFO - Downloaded 1325 bytes. 07:56:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:56:10 INFO - Using the following test package requirements: 07:56:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:56:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:56:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:56:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:56:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'jsshell-mac.zip'], 07:56:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:56:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:56:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:56:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:56:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:56:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:56:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:56:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:56:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:56:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:56:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:56:11 INFO - Expected file size: 52511516 07:56:11 INFO - Obtained file size: 52511516 07:56:28 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:56:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:56:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:56:28 INFO - Fetch https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:56:30 INFO - Expected file size: 52511516 07:56:30 INFO - Obtained file size: 52511516 07:56:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:56:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:56:45 INFO - Fetch https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:56:47 INFO - Expected file size: 14404171 07:56:47 INFO - Obtained file size: 14404171 07:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:47 INFO - trying https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:56:47 INFO - Downloading https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:56:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:56:49 INFO - Downloaded 75573517 bytes. 07:56:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:56:49 INFO - mkdir: /builds/slave/test/properties 07:56:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:56:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:56:49 INFO - Contents: 07:56:49 INFO - build_url:https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:56:50 INFO - Running post-action listener: _resource_record_post_action 07:56:50 INFO - Running post-action listener: set_extra_try_arguments 07:56:50 INFO - [mozharness: 2017-03-29 14:56:50.110344Z] Finished download-and-extract step (success) 07:56:50 INFO - [mozharness: 2017-03-29 14:56:50.110496Z] Running populate-webroot step. 07:56:50 INFO - Running pre-action listener: _resource_record_pre_action 07:56:50 INFO - Running main action method: populate_webroot 07:56:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:56:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:56:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:56:50 INFO - '--tppagecycles', 07:56:50 INFO - '7'], 07:56:50 INFO - 'tests': ['tsvgm']}}, 07:56:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:56:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:56:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:56:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:56:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['damp', 'tps']}, 07:56:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'tests': ['damp', 'tps']}, 07:56:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['dromaeo_dom']}, 07:56:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:56:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['basic_compositor_video']}, 07:56:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:56:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['a11yr', 07:56:50 INFO - 'ts_paint', 07:56:50 INFO - 'tpaint', 07:56:50 INFO - 'sessionrestore', 07:56:50 INFO - 'sessionrestore_no_auto_restore', 07:56:50 INFO - 'tabpaint']}, 07:56:50 INFO - 'other-e10s': {'tests': ['a11yr', 07:56:50 INFO - 'ts_paint', 07:56:50 INFO - 'tpaint', 07:56:50 INFO - 'sessionrestore', 07:56:50 INFO - 'sessionrestore_no_auto_restore', 07:56:50 INFO - 'tabpaint']}, 07:56:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:56:50 INFO - 'ts_paint', 07:56:50 INFO - 'tpaint', 07:56:50 INFO - 'sessionrestore', 07:56:50 INFO - 'sessionrestore_no_auto_restore', 07:56:50 INFO - 'tabpaint']}, 07:56:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:56:50 INFO - 'ts_paint', 07:56:50 INFO - 'tpaint', 07:56:50 INFO - 'sessionrestore', 07:56:50 INFO - 'sessionrestore_no_auto_restore', 07:56:50 INFO - 'tabpaint']}, 07:56:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['a11yr', 07:56:50 INFO - 'ts_paint', 07:56:50 INFO - 'tpaint', 07:56:50 INFO - 'sessionrestore', 07:56:50 INFO - 'sessionrestore_no_auto_restore', 07:56:50 INFO - 'tabpaint']}, 07:56:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['a11yr', 07:56:50 INFO - 'ts_paint', 07:56:50 INFO - 'tpaint', 07:56:50 INFO - 'sessionrestore', 07:56:50 INFO - 'sessionrestore_no_auto_restore', 07:56:50 INFO - 'tabpaint']}, 07:56:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['tsvgx', 07:56:50 INFO - 'tsvgr_opacity', 07:56:50 INFO - 'tart', 07:56:50 INFO - 'tscrollx', 07:56:50 INFO - 'cart', 07:56:50 INFO - 'tsvg_static']}, 07:56:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:56:50 INFO - 'tsvgr_opacity', 07:56:50 INFO - 'tart', 07:56:50 INFO - 'tscrollx', 07:56:50 INFO - 'cart', 07:56:50 INFO - 'tsvg_static']}, 07:56:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'talos_options': ['--disable-e10s'], 07:56:50 INFO - 'tests': ['tp5o']}, 07:56:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:56:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'talos_options': ['--disable-e10s', 07:56:50 INFO - '--xperf_path', 07:56:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:50 INFO - 'tests': ['tp5n']}, 07:56:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:56:50 INFO - 'talos_options': ['--xperf_path', 07:56:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:50 INFO - 'tests': ['tp5n']}}, 07:56:50 INFO - 'talos.zip': {'path': '', 07:56:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:56:50 INFO - Running post-action listener: _resource_record_post_action 07:56:50 INFO - [mozharness: 2017-03-29 14:56:50.128651Z] Finished populate-webroot step (success) 07:56:50 INFO - [mozharness: 2017-03-29 14:56:50.128768Z] Running create-virtualenv step. 07:56:50 INFO - Running pre-action listener: _resource_record_pre_action 07:56:50 INFO - Running main action method: create_virtualenv 07:56:50 INFO - Creating virtualenv /builds/slave/test/build/venv 07:56:50 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 07:56:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:56:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:56:50 INFO - Using real prefix '/tools/python27' 07:56:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:56:50 INFO - Installing distribute.............................................................................................................................................................................................done. 07:56:53 INFO - Installing pip.................done. 07:56:53 INFO - Return code: 0 07:56:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:56:54 INFO - Reading from file tmpfile_stdout 07:56:54 INFO - Output received: 07:56:54 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) 07:56:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:54 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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:56:54 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 07:56:54 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 07:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:54 INFO - 'HOME': '/Users/cltbld', 07:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:54 INFO - 'LOGNAME': 'cltbld', 07:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:54 INFO - 'MOZ_NO_REMOTE': '1', 07:56:54 INFO - 'NO_EM_RESTART': '1', 07:56:54 INFO - 'PAGER': '/bin/cat', 07:56:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:54 INFO - 'PWD': '/builds/slave/test', 07:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:54 INFO - 'SHELL': '/bin/bash', 07:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:56:54 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:54 INFO - 'USER': 'cltbld', 07:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:54 INFO - 'XPC_FLAGS': '0x0', 07:56:54 INFO - 'XPC_SERVICE_NAME': '0', 07:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:54 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 07:56:54 INFO - Cleaning up... 07:56:54 INFO - Return code: 0 07:56:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:54 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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:56:54 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 07:56:54 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 07:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:54 INFO - 'HOME': '/Users/cltbld', 07:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:54 INFO - 'LOGNAME': 'cltbld', 07:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:54 INFO - 'MOZ_NO_REMOTE': '1', 07:56:54 INFO - 'NO_EM_RESTART': '1', 07:56:54 INFO - 'PAGER': '/bin/cat', 07:56:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:54 INFO - 'PWD': '/builds/slave/test', 07:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:54 INFO - 'SHELL': '/bin/bash', 07:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:56:54 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:54 INFO - 'USER': 'cltbld', 07:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:54 INFO - 'XPC_FLAGS': '0x0', 07:56:54 INFO - 'XPC_SERVICE_NAME': '0', 07:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:54 INFO - Downloading/unpacking psutil>=3.1.1 07:56:54 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 07:56:54 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 07:56:54 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 07:56:54 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 07:56:54 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 07:56:54 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 07:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:56:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:56:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:56:58 INFO - Installing collected packages: psutil 07:56:58 INFO - Running setup.py install for psutil 07:56:58 INFO - building 'psutil._psutil_osx' extension 07:56:58 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 07:56:58 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 07:56:59 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 07:56:59 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 07:56:59 INFO - building 'psutil._psutil_posix' extension 07:56:59 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 07:56:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:56:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:56:59 INFO - ^ 07:56:59 INFO - 1 warning generated. 07:56:59 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 07:56:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:56:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:56:59 INFO - Successfully installed psutil 07:56:59 INFO - Cleaning up... 07:56:59 INFO - Return code: 0 07:56:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:56:59 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')]} 07:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:59 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')]} 07:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:00 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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:00 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 07:57:00 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 07:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:00 INFO - 'HOME': '/Users/cltbld', 07:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:00 INFO - 'LOGNAME': 'cltbld', 07:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:00 INFO - 'MOZ_NO_REMOTE': '1', 07:57:00 INFO - 'NO_EM_RESTART': '1', 07:57:00 INFO - 'PAGER': '/bin/cat', 07:57:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:00 INFO - 'PWD': '/builds/slave/test', 07:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:00 INFO - 'SHELL': '/bin/bash', 07:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:00 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:00 INFO - 'USER': 'cltbld', 07:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:00 INFO - 'XPC_FLAGS': '0x0', 07:57:00 INFO - 'XPC_SERVICE_NAME': '0', 07:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:57:00 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 07:57:00 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 07:57:00 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 07:57:00 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 07:57:00 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 07:57:00 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 07:57:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:57:03 INFO - Installing collected packages: mozsystemmonitor 07:57:03 INFO - Running setup.py install for mozsystemmonitor 07:57:03 INFO - Successfully installed mozsystemmonitor 07:57:03 INFO - Cleaning up... 07:57:03 INFO - Return code: 0 07:57:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:03 INFO - 'HOME': '/Users/cltbld', 07:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:03 INFO - 'LOGNAME': 'cltbld', 07:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:03 INFO - 'MOZ_NO_REMOTE': '1', 07:57:03 INFO - 'NO_EM_RESTART': '1', 07:57:03 INFO - 'PAGER': '/bin/cat', 07:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:03 INFO - 'PWD': '/builds/slave/test', 07:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:03 INFO - 'SHELL': '/bin/bash', 07:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:03 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:03 INFO - 'USER': 'cltbld', 07:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:03 INFO - 'XPC_FLAGS': '0x0', 07:57:03 INFO - 'XPC_SERVICE_NAME': '0', 07:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:04 INFO - Downloading/unpacking jsonschema==2.5.1 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:57:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:57:06 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 07:57:06 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 07:57:06 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 07:57:06 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 07:57:06 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 07:57:06 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 07:57:07 INFO - Downloading functools32-3.2.3-2.tar.gz 07:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:57:07 INFO - warning: no files found matching '*.txt' 07:57:07 INFO - no previously-included directories found matching 'build' 07:57:07 INFO - no previously-included directories found matching 'dist' 07:57:07 INFO - no previously-included directories found matching '.git*' 07:57:07 INFO - Installing collected packages: jsonschema, functools32 07:57:07 INFO - Running setup.py install for functools32 07:57:07 INFO - warning: no files found matching '*.txt' 07:57:07 INFO - no previously-included directories found matching 'build' 07:57:07 INFO - no previously-included directories found matching 'dist' 07:57:07 INFO - no previously-included directories found matching '.git*' 07:57:07 INFO - Successfully installed jsonschema functools32 07:57:07 INFO - Cleaning up... 07:57:07 INFO - Return code: 0 07:57:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:07 INFO - 'HOME': '/Users/cltbld', 07:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:07 INFO - 'LOGNAME': 'cltbld', 07:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:07 INFO - 'MOZ_NO_REMOTE': '1', 07:57:07 INFO - 'NO_EM_RESTART': '1', 07:57:07 INFO - 'PAGER': '/bin/cat', 07:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:07 INFO - 'PWD': '/builds/slave/test', 07:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:07 INFO - 'SHELL': '/bin/bash', 07:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:07 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:07 INFO - 'USER': 'cltbld', 07:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:07 INFO - 'XPC_FLAGS': '0x0', 07:57:07 INFO - 'XPC_SERVICE_NAME': '0', 07:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:57:07 INFO - Cleaning up... 07:57:07 INFO - Return code: 0 07:57:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:07 INFO - 'HOME': '/Users/cltbld', 07:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:07 INFO - 'LOGNAME': 'cltbld', 07:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:07 INFO - 'MOZ_NO_REMOTE': '1', 07:57:07 INFO - 'NO_EM_RESTART': '1', 07:57:07 INFO - 'PAGER': '/bin/cat', 07:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:07 INFO - 'PWD': '/builds/slave/test', 07:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:07 INFO - 'SHELL': '/bin/bash', 07:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:07 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:07 INFO - 'USER': 'cltbld', 07:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:07 INFO - 'XPC_FLAGS': '0x0', 07:57:07 INFO - 'XPC_SERVICE_NAME': '0', 07:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:08 INFO - Downloading/unpacking blobuploader==1.2.4 07:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:10 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:11 INFO - Downloading docopt-0.6.1.tar.gz 07:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:12 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:12 INFO - Running setup.py install for blobuploader 07:57:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:12 INFO - Running setup.py install for requests 07:57:12 INFO - Running setup.py install for docopt 07:57:12 INFO - Successfully installed blobuploader requests docopt 07:57:12 INFO - Cleaning up... 07:57:12 INFO - Return code: 0 07:57:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:12 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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:12 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 07:57:12 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 07:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:12 INFO - 'HOME': '/Users/cltbld', 07:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:12 INFO - 'LOGNAME': 'cltbld', 07:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:12 INFO - 'MOZ_NO_REMOTE': '1', 07:57:12 INFO - 'NO_EM_RESTART': '1', 07:57:12 INFO - 'PAGER': '/bin/cat', 07:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:12 INFO - 'PWD': '/builds/slave/test', 07:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:12 INFO - 'SHELL': '/bin/bash', 07:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:12 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:12 INFO - 'USER': 'cltbld', 07:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:12 INFO - 'XPC_FLAGS': '0x0', 07:57:12 INFO - 'XPC_SERVICE_NAME': '0', 07:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-7_t7nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-IogAud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-xes4IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-4w_7Cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ju1VUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-aDx929-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-GeC5PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-cvKe1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-qv6uH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-45daKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-4t0elA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-7Ryhat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-_A7ZM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-cvfxry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-zhlqLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ybnKY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-lENxxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:14 INFO - Running setup.py install for manifestparser 07:57:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozcrash 07:57:15 INFO - Running setup.py install for mozdebug 07:57:15 INFO - Running setup.py install for mozdevice 07:57:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozfile 07:57:15 INFO - Running setup.py install for mozhttpd 07:57:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozinfo 07:57:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozInstall 07:57:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozleak 07:57:16 INFO - Running setup.py install for mozlog 07:57:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for moznetwork 07:57:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozprocess 07:57:16 INFO - Running setup.py install for mozprofile 07:57:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozrunner 07:57:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:17 INFO - Running setup.py install for mozscreenshot 07:57:17 INFO - Running setup.py install for moztest 07:57:17 INFO - Running setup.py install for mozversion 07:57:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:17 INFO - Cleaning up... 07:57:17 INFO - Return code: 0 07:57:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:17 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')]} 07:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:17 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')]} 07:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:17 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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:17 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 07:57:17 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 07:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:17 INFO - 'HOME': '/Users/cltbld', 07:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:17 INFO - 'LOGNAME': 'cltbld', 07:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:17 INFO - 'MOZ_NO_REMOTE': '1', 07:57:17 INFO - 'NO_EM_RESTART': '1', 07:57:17 INFO - 'PAGER': '/bin/cat', 07:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:17 INFO - 'PWD': '/builds/slave/test', 07:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:17 INFO - 'SHELL': '/bin/bash', 07:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:17 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:17 INFO - 'USER': 'cltbld', 07:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:17 INFO - 'XPC_FLAGS': '0x0', 07:57:17 INFO - 'XPC_SERVICE_NAME': '0', 07:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-TS9ZED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:17 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ug7TNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-XDbOnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-kBZKvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-bMhzOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-TRbGeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-x1Pou_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-wShBor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-UL8UcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-9uAtj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-hpAhDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:18 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-HyiEH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:19 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)) 07:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:19 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-B8YGEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:19 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)) 07:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:19 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-RAwt6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:19 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)) 07:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:19 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-6Eny1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:19 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)) 07:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:19 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-e3z06U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:19 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)) 07:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:19 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-hrMpU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:19 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)) 07:57:19 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)) 07:57:19 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)) 07:57:19 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)) 07:57:19 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)) 07:57:19 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)) 07:57:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:22 INFO - Downloading blessings-1.6.tar.gz 07:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:57:22 INFO - Installing collected packages: blessings 07:57:22 INFO - Running setup.py install for blessings 07:57:22 INFO - Successfully installed blessings 07:57:22 INFO - Cleaning up... 07:57:22 INFO - Return code: 0 07:57:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:57:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:22 INFO - Reading from file tmpfile_stdout 07:57:22 INFO - Current package versions: 07:57:22 INFO - blessings == 1.6 07:57:22 INFO - blobuploader == 1.2.4 07:57:22 INFO - docopt == 0.6.1 07:57:22 INFO - functools32 == 3.2.3-2 07:57:22 INFO - jsonschema == 2.5.1 07:57:22 INFO - manifestparser == 1.1 07:57:22 INFO - mozInstall == 1.12 07:57:22 INFO - mozcrash == 1.0 07:57:22 INFO - mozdebug == 0.1 07:57:22 INFO - mozdevice == 0.49 07:57:22 INFO - mozfile == 1.2 07:57:22 INFO - mozhttpd == 0.7 07:57:22 INFO - mozinfo == 0.9 07:57:22 INFO - mozleak == 0.1 07:57:22 INFO - mozlog == 3.4 07:57:22 INFO - moznetwork == 0.27 07:57:22 INFO - mozprocess == 0.25 07:57:22 INFO - mozprofile == 0.28 07:57:22 INFO - mozrunner == 6.13 07:57:22 INFO - mozscreenshot == 0.1 07:57:22 INFO - mozsystemmonitor == 0.3 07:57:22 INFO - moztest == 0.8 07:57:22 INFO - mozversion == 1.4 07:57:22 INFO - psutil == 3.1.1 07:57:22 INFO - requests == 1.2.3 07:57:22 INFO - wsgiref == 0.1.2 07:57:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:22 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 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:22 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 07:57:22 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 07:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:22 INFO - 'HOME': '/Users/cltbld', 07:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:22 INFO - 'LOGNAME': 'cltbld', 07:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:22 INFO - 'MOZ_NO_REMOTE': '1', 07:57:22 INFO - 'NO_EM_RESTART': '1', 07:57:22 INFO - 'PAGER': '/bin/cat', 07:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:22 INFO - 'PWD': '/builds/slave/test', 07:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:22 INFO - 'SHELL': '/bin/bash', 07:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:22 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:22 INFO - 'USER': 'cltbld', 07:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:22 INFO - 'XPC_FLAGS': '0x0', 07:57:22 INFO - 'XPC_SERVICE_NAME': '0', 07:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 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)) 07:57:23 INFO - Cleaning up... 07:57:23 INFO - Return code: 0 07:57:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a865b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a868768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c1473800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, '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.4tNOSSatOM/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.jZgxcOgazK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 07:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:23 INFO - 'HOME': '/Users/cltbld', 07:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:23 INFO - 'LOGNAME': 'cltbld', 07:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:23 INFO - 'MOZ_NO_REMOTE': '1', 07:57:23 INFO - 'NO_EM_RESTART': '1', 07:57:23 INFO - 'PAGER': '/bin/cat', 07:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:23 INFO - 'PWD': '/builds/slave/test', 07:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:23 INFO - 'SHELL': '/bin/bash', 07:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:23 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:23 INFO - 'USER': 'cltbld', 07:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:23 INFO - 'XPC_FLAGS': '0x0', 07:57:23 INFO - 'XPC_SERVICE_NAME': '0', 07:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:57:23 INFO - Cleaning up... 07:57:23 INFO - Return code: 0 07:57:23 INFO - Running post-action listener: _resource_record_post_action 07:57:23 INFO - Running post-action listener: _start_resource_monitoring 07:57:23 INFO - Starting resource monitoring. 07:57:23 INFO - [mozharness: 2017-03-29 14:57:23.517300Z] Finished create-virtualenv step (success) 07:57:23 INFO - [mozharness: 2017-03-29 14:57:23.518370Z] Running install step. 07:57:23 INFO - Running pre-action listener: _resource_record_pre_action 07:57:23 INFO - Running main action method: install 07:57:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:23 INFO - Reading from file tmpfile_stdout 07:57:23 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:57:23 INFO - Reading from file tmpfile_stdout 07:57:23 INFO - Output received: 07:57:23 INFO - Usage: mozinstall [options] installer 07:57:23 INFO - Options: 07:57:23 INFO - -h, --help show this help message and exit 07:57:23 INFO - -d DEST, --destination=DEST 07:57:23 INFO - Directory to install application into. [default: 07:57:23 INFO - "/builds/slave/test"] 07:57:23 INFO - --app=APP Application being installed. [default: firefox] 07:57:23 INFO - mkdir: /builds/slave/test/build/application 07:57:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:57:44 INFO - Reading from file tmpfile_stdout 07:57:44 INFO - Output received: 07:57:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:57:44 INFO - Running post-action listener: _resource_record_post_action 07:57:44 INFO - [mozharness: 2017-03-29 14:57:44.220287Z] Finished install step (success) 07:57:44 INFO - [mozharness: 2017-03-29 14:57:44.220463Z] Running run-tests step. 07:57:44 INFO - Running pre-action listener: _resource_record_pre_action 07:57:44 INFO - Running main action method: run_tests 07:57:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:57:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:57:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:57:44 INFO - 2017-03-29 07:57:44.235 screenresolution[1624:7372] starting screenresolution argv=screenresolution get 07:57:44 INFO - 2017-03-29 07:57:44.238 screenresolution[1624:7372] Display 0: 1600x1200x32@60 07:57:44 INFO - 2017-03-29 07:57:44.243 screenresolution[1625:7374] starting screenresolution argv=screenresolution list 07:57:44 INFO - Available Modes on Display 0 07:57:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:57:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:57:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:57:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:57:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:57:44 INFO - Intel Iris: 07:57:44 INFO - Chipset Model: Intel Iris 07:57:44 INFO - Type: GPU 07:57:44 INFO - Bus: Built-In 07:57:44 INFO - VRAM (Dynamic, Max): 1536 MB 07:57:44 INFO - Vendor: Intel (0x8086) 07:57:44 INFO - Device ID: 0x0a2e 07:57:44 INFO - Revision ID: 0x0009 07:57:44 INFO - Displays: 07:57:44 INFO - Display: 07:57:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:57:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:57:44 INFO - Display Serial Number: 1600x1200 60 07:57:44 INFO - Main Display: Yes 07:57:44 INFO - Mirror: Off 07:57:44 INFO - Online: Yes 07:57:44 INFO - Rotation: Supported 07:57:44 INFO - Return code: 0 07:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:57:44 INFO - Python 2.7.3 07:57:44 INFO - Return code: 0 07:57:44 INFO - grabbing minidump binary from tooltool 07:57:44 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')]} 07:57:44 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 0x10a643d30>, '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 0x10a56adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a638be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:57:44 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 07:57:44 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 07:57:44 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 07:57:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:57:44 INFO - Return code: 0 07:57:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:57:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:57:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:57:44 INFO - ENV: RUST_BACKTRACE is now 1 07:57:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:57:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:57:44 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/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 07:57:44 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/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --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 07:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4tNOSSatOM/Render', 07:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:44 INFO - 'HOME': '/Users/cltbld', 07:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:44 INFO - 'LOGNAME': 'cltbld', 07:57:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:57:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:44 INFO - 'MOZ_NO_REMOTE': '1', 07:57:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:57:44 INFO - 'NO_EM_RESTART': '1', 07:57:44 INFO - 'PAGER': '/bin/cat', 07:57:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:44 INFO - 'PWD': '/builds/slave/test', 07:57:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:44 INFO - 'RUST_BACKTRACE': '1', 07:57:44 INFO - 'SHELL': '/bin/bash', 07:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZgxcOgazK/Listeners', 07:57:44 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 07:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:44 INFO - 'USER': 'cltbld', 07:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:44 INFO - 'XPC_FLAGS': '0x0', 07:57:44 INFO - 'XPC_SERVICE_NAME': '0', 07:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:44 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/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 07:57:44 INFO - mozversion application_buildid: 20170329064223 07:57:44 INFO - mozversion application_changeset: 6ea713ccc9abea93126423fefb855d0e051c95e2 07:57:44 INFO - mozversion application_display_name: Nightly 07:57:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:57:44 INFO - mozversion application_name: Firefox 07:57:44 INFO - mozversion application_remotingname: firefox 07:57:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:57:44 INFO - mozversion application_vendor: Mozilla 07:57:44 INFO - mozversion application_version: 55.0a1 07:57:44 INFO - mozversion platform_buildid: 20170329064223 07:57:44 INFO - mozversion platform_changeset: 6ea713ccc9abea93126423fefb855d0e051c95e2 07:57:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:57:44 INFO - mozversion platform_version: 55.0a1 07:57:44 INFO - using testdate: 1490799464 07:57:44 INFO - actual date: 1490799464 07:57:44 INFO - starting webserver on 'localhost:49241' 07:57:44 INFO - SUITE-START | Running 2 tests 07:57:44 INFO - TEST-START | dromaeo_css 07:57:44 INFO - operating with platform_type : mac_ 07:57:44 INFO - Initialising browser for dromaeo_css test... 07:57:44 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpUTi1Xs/profile http://localhost:49241/getInfo.html) 07:57:47 INFO - PID 1635 | __metrics Screen width/height:1600/1200 07:57:47 INFO - PID 1635 | colorDepth:24 07:57:47 INFO - PID 1635 | Browser inner width/height: 1024/689 07:57:47 INFO - PID 1635 | __metrics 07:57:47 ERROR - PID 1635 | 1490799467446 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 07:57:47 INFO - PID 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:57:47 INFO - PID 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:57:48 INFO - TEST-INFO | 1635: exit 0 07:57:48 INFO - Browser initialized. 07:57:48 INFO - Running cycle 1/1 for dromaeo_css test... 07:57:48 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpUTi1Xs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:58:00 INFO - PID 1637 | RSS: Main: 234029056 07:58:00 INFO - PID 1637 | 08:01:05 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:01:07 INFO - PID 1637 | RSS: Main: 423071744 08:01:07 INFO - PID 1637 | 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 08:02:49 INFO - PID 1637 | 08:02:49 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:02:49 INFO - PID 1637 | Full stack: 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 08:02:49 INFO - PID 1637 | 08:02:49 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:02:49 INFO - PID 1637 | Full stack: 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 08:02:49 INFO - PID 1637 | 08:02:49 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:02:49 INFO - PID 1637 | Full stack: 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | ************************* 08:02:49 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 08:02:49 INFO - PID 1637 | 08:02:49 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:02:49 INFO - PID 1637 | Full stack: 08:02:49 INFO - PID 1637 | ************************* 08:04:10 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:04:11 INFO - PID 1637 | RSS: Main: 367742976 08:04:11 INFO - PID 1637 | 08:07:13 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:07:14 INFO - PID 1637 | RSS: Main: 333340672 08:07:14 INFO - PID 1637 | 08:10:16 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:10:17 INFO - PID 1637 | RSS: Main: 359559168 08:10:17 INFO - PID 1637 | 08:13:21 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:13:21 INFO - PID 1637 | RSS: Main: 335704064 08:13:21 INFO - PID 1637 | 08:16:24 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:16:25 INFO - PID 1637 | RSS: Main: 539541504 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | __start_tp_report 08:16:25 INFO - PID 1637 | _x_x_mozilla_page_load 08:16:25 INFO - PID 1637 | _x_x_mozilla_page_load_details 08:16:25 INFO - PID 1637 | |i|pagename|runs| 08:16:25 INFO - PID 1637 | |0;dojo.html;3512.9121771955715;3513.156842357834;3535.381308271052;3560.572731272246;3585.7848529088255;6071.51427885768;6095.922000763725;6310.49516038717;6317.526185536086;6340.682965851712;5135.666181698187;6203.627782333063;6318.652474113922;6326.841828954279;6329.082283068952;5481.150421684645;5568.220449137124;5635.971820140893;5641.802536911209;5767.9423205768035;5670.744816483248;5709.8572535686735;5748.5688573357165;5756.338021127582;5766.567507436938;5499.807506737285;5614.606977511535;5621.578381621394;5640.238567793336;5686.09022556389;5635.6055076144685;5659.292588426447;5685.403032681592;5726.169705392736;5767.538596912279;5612.522935550483;5621.662700237999;5634.605577609569;5656.095024796054;5663.8584035399035;6124.319907527348;6206.813795586139;6310;6318.083877837734;6324.652144132071;6456.192975878015;6546.9017964730565;6636.701348439332;6642.966785166043;6704.5306828522025;6677.732890684415;6681.465482761368;6746.426553742937;6782.287859774751;6790.253072162058;6317.368263173646;6376;6386;6391.680415979183;6409.871802563923;5522.2544956431175;6663.433608143265;6663.566868153554;6667.966660166765;6705.195376554844;6437.645929473831;6504.219493660791;6531.444827189742;6531.673416329259;6544.541882068304;6643.634600097041;6662.400383965466;6704.731810727614;6716.597004179765;6758.087658166183;6352.6823658816875;6623.668816559153;6629.105070815378;6640.136782218281;6640.435562977104;6007.798440311955;6410.262819775692;6529.216494020748;6601.966990165114;6634.237062737847;5325.121354976463;5350.598705097055;5350.785968561292;5352.973235133877;5376.892462150735;4704.2473204287835;4768.666193366431;4769.9046019080115;4776.164171270083;4810.037992401533;6213.068039793977;6249.312575616678;6260.780872669526;6359.682015899187;6382.32985536512;6662.86674266512;6727.058211850345;6753.527253092237;6774.119364482684;6774.864502709918;6309.968450157719;6324.9051264231075;6326.588771729823;6331.493480521547;6335.524835637346;5766.1062535306255;5826.563007774434;5828.854278642983;5868.589198756046;5880.529557635379;6748.325167483213;6767.661616919233;6885.139357580302;6979.7557085503795;6980.650967451708;6425.293217745951;6465.03024546303;6476.384743449365;6720.395164435225;6737.191537015589;6789.15135608049;6831.829204269933;6837.863242735217;6904.620245886525;6914.654267286515;6557.180352455943;6588.209414870054;6599.835004124935;6622.039804228263;6637;5455.890882182413;6228.501719862491;6525.445337146396;6597.175353080865;6711.094002309821;6404.519661025349;6412.967935160481;6424.743010279536;6430.710618022106;6439.549231553934;6402.03969404593;6417.101605775289;6418.807435776934;6422.9036564450635;6423.871522569429;5244.213367994831;6293.559450838397;6308.33762454954;6340.334264902119;6358.205224346957;6376.39424254695;6381.106645069601;6389.36106389376;6442.420182183626;6492.837679058061;5278.97360513195;6305.464035556845;6330.9050364245495;6338.366163383625;6346.9682651586445 08:16:25 INFO - PID 1637 | |1;ext.html;799.2447137455106;820.811213420913;1110.0675432636588;1137.1528211482446;1139.191174266271;37003.81498092509;37763.43354849676;37835.24329513408;37985;38016.4793408264;39582.01044973877;39783.40324895129;39852.20295594086;39982;39987.40018899719;6105.786297479589;8628.870566941501;8750.08124146965;8780.824383512327;8801.471911685287;6243.188385509878;8466.788330291762;8668.436551624156;8678.783030424227;8681.609327580274;8707.782305442352;8768.210861022506;8768.736937891874;8771;8801.823963520726;8668.739937801876;8710.912890871105;8712.912870871274;8735.563221838882;8744.868826967537;8506;8590.742277731679;8594.613242404106;8597.957010214972;8608.741737747878;38443.42334864979;38560;38578;38623.806880965414;38625.42061869074;2678.2233152385784;3073.34364078369;3095.7059079387423;3119.2357872321427;3165;27300.31749206286;28421.71578284232;28518.572221416696;28684;28757.849686012534;2727.7408646178583;2803.4673412051643;2858.8427636479987;2886.7546258568045;2900.7824413168887;322085;322236.3888180591;322438.9999999977;322697;324400;49337.50662493401;49439;49481.752591236815;49491.76270593264;49533.50466495361;42947.78526107349;53075.469245307824;53102;53122;53189;14220.85779142216;14274.571762847132;14308.856911430961;14309.926755493172;14326.641833954234;11625.18623696333;11626.941865290619;11647.76704465906;11649.242799218024;11650.41747912601;10848.294860834021;12626.936865315614;12642.55751048709;12695.555655552196;12758.872411275768;5369.677819330853;5416.7020813855615;5432.945670543323;5435.619506634576;5439.156930675752;970.7767213540784;990.9653162139291;993.7515621094726;1006.6476733143342;1008.0927165550917;24128.87935560311;24287.514249714906;24325.270241892773;24335.634965475532;24395.756042439705;1090.6237348114887;1091.9454027298602;1110.650145204258;1128.7573171768108;1142.605800998654;11606.88393116058;11679.299242045334;12359.876401236052;12365.948894343901;12523.123381363217;401677;402145.7210562667;402782;403047.9847600802;404889;54275.72862135664;54287.45712542903;54297.185542216896;54366.72816635892;54419.45580544223;1486.2345891865698;1488.5534339698263;1534.7851300817895;1542.5526597286662;1545.1501674079211;17092.658146836744;22262.109515619843;23503.52992940166;23646.52706945817;23660.763392366545;38275.80862095783;38319.6168038316;38324.6167538321;38419;38477;380303;380315;381292;382043;383288.08355958044;31556.211094722814;32523.837380812944;33449.82576132544;33486.33027339488;33565.66434335723;35336.11659708528;42202.36696449617;43454.91362715957;43563;43764.34353484764;27640.6722571841;29486.705132948395;30186.54720179243;30342.84828575931;30388.24029399283;29373.55939660864;29498.672559734434;30146.69853301439;30351.696483035772;30359.544606831358;35182.94451166469;35250.823745881105;35934.8203258982;36230.09424764296;36270.91187264386;28673.13980580586;30470.390592188476;30558.472076396534;30596.235094122825;30671.846640766653;22272.554548909255;23612.17357392483;23989.160379386638;24086;24199.395015124763 08:16:25 INFO - PID 1637 | |2;jquery.html;385.7608282864624;419.97690127043;428.50293659355157;429.9720518166319;446.5199910096647;15545.766813497807;20804.597701149425;22212.333629991124;22397.552048959013;22425.439364015907;22706.65940010897;23063.846807659633;23077.884610576966;23220.41948951273;23506.412339691517;5983.910241346383;6073.271207455089;6225.688715564226;6327.968360158193;6328.525360597951;3648.945265821015;6154.969225153868;6267.43593076623;6270.372962703739;6277.309495955441;3838.481804956337;5934.287885453751;6198.411150940653;6219.9689001554925;6245.344238854923;5956.195913551658;6173.074038894157;6231.750729970819;6247.718852651642;6256.655883926383;5527.806526771584;5529.3364796224305;5530.115181570929;5534.612577119603;5535.806246781383;22826;26324.73675263242;27102;27789.166325010283;28118.578221326698;2010.1657812008;2036.53159773253;2044.356027851237;2051.3435700575824;2085.291001059632;16108.261595528671;16488.17559122051;16566.751498727528;16798.496045118667;17277.049762263072;1624.5207663739225;1628.828972957846;1635.435774657753;1668.4994501649578;1775.387491315507;142853;153888.2305588476;155024.22487887487;156872.21563892107;159987;11488.942555287338;11504.597339093092;12126.151169418055;12132.5146994121;12160.878391215974;15121.731740239053;15924.566788988815;16204.432844850373;16205.027698338137;16226.837731622532;8928;8966.596503157374;9058.230050445654;9186;9189.55391728102;6385.872282554323;8137.389695772841;8235.052968908532;8590.570471476402;8737.694180703646;6943.79168624942;7798.805029874298;7838.490498117604;7847.607619618996;7865.882011769828;1786.56229223841;2135.1566131378017;2137.78622137785;2144.914203431845;2279.2478482101196;900.4777229207044;900.6847603338779;900.7342833864026;941.3175447800427;952.6189524190379;20372.185112595627;20913.163473461194;20941;21027.474313142295;21108.250643605064;892.4243862708516;945.9290553208536;962.167724917941;970.5729479029204;978.5058545434653;9977.600895964226;10938.015578597806;11046.834297485544;11054.944725276322;11055.11559075272;591552;595680;597005;610215;610939;131159;140797;141912.29043854715;148993;155107;1167.3962893475134;1182.810750279951;1197.3474456421272;1198.874118217592;1202.2425871700773;14058.437662493794;17197;17908.55228619295;18346.81653183451;18403.17185726619;56545.71727141338;56927.57681058007;57205.85588288294;57383.713081434325;57671;77292.61353693421;77754;78045;78454.2154578447;80464;8482;8506.78733031679;8652.480851148952;8667.91332086671;8699.13008699125;11881.643550693492;13206.537771178348;13210.867891320964;13589.252591107585;13613.38739756733;11030.558777649125;11703.824442633197;11905.761884762429;11922.701932451759;12007.639770806889;10713.464326783786;10773.407462589634;10908;11070.889291106985;11107.333559986426;11525;11712.180147389941;11865.644030679094;12053.57812476559;12082.39588020613;8535.487870727527;8854.955725221333;12482.563110291096;12527.37363131858;13022.544210952572;10324.690259292232;11828;11868.703282418008;12173.939130304292;12240.87759122433 08:16:25 INFO - PID 1637 | |3;mootools.html;1360.6802401435662;1419.0988722161424;1434.7776094705312;1445.0679311843855;1460.291758497129;10762.78474430512;11203.159763017766;11207.60773372932;11227.55089796407;11229.382383968878;10781.514831832566;10842.186835987293;11160.720981975455;11161.944190279037;11180.66458006259;2433.5862903306393;2478.021181633254;2478.615814548748;2484.1429706751233;2484.5155194737035;2402.1232250228713;2425.842320249187;2477.7770000699934;2483.962740558893;2484.093305943336;2364.8817559122062;2477.2692055843568;2481.6029435290357;2481.6649752283483;2487.614419764939;2441.1700021992606;2465.3097132802845;2470.4441500662388;2495.0394098272113;2505.66173566568;2195.913023053583;2360.5750964826325;2451.203358908362;2465.4822487277693;2469.4196863737006;10851.969062939008;11104.167187460973;11122.388268645222;11172.273802202832;11173.608923687632;6209;6462.4183823456115;6463.321351258144;6463.773767918147;6468.773592924225;3945.7237993340477;3977.5425826030087;3987.80060996949;4041.4948131483566;4084.7957602119777;1129.7232178116333;1143.4454289669504;1167.575557819459;1169.0355456748216;1209.5403746576333;13984.93007534966;14434.206118663575;14439.494617688331;15212.46756363522;15428.537143885702;5267.578593712494;5409.350877894678;5438.456154384609;5447.863803404867;5450.700211488327;11491.54033838637;11531.731509533945;11545.365004924643;11554.76890462186;11560.364179970182;5492.450754924555;5605.775768969277;5612.550995920316;6344.206974128234;6382.521310901701;5669.234653321749;5753.165790960287;5809.8547536311935;5822.359540450631;5832.270966129234;4068.145689405199;4089.959100408958;4175.64507016907;4196.349565817303;4196.349565817303;1165.7318816672214;1177.0995188680665;1183.7810005149074;1201.1831954271004;1204.4579939027296;756.5649751392971;767.1982777997007;784.9489783164076;788.7830846517254;802.2899733735686;5997.100434934795;6045.909311360333;6158.261008678987;6240.375962403814;6266.843328916813;555.9666420014812;561.9072852979294;571.4913726783167;584.8157830283448;586.8738221282359;3674.8346324415465;4355.911022244439;4378.343248512685;4515.706479078849;4521.683482156283;13543;13851.58445246624;14257.78613320801;14286.85713142876;14305.356258968372;14092.929535352258;14093.224872632103;14098;14103.71792564143;14112.788308175386;10094.141997930112;10367.274290799722;10378.740531486925;10380.948095259626;10384.948075259575;5929.584929054925;6114.938850611615;6568;6594.4724422045165;7015.473839461959;12476.43856026499;12783.296918669563;12844.743105138032;12858.614241572768;12863.292518911176;12745.362731863555;12849.629452546229;12871.549495767604;13090.541831035867;13137.605871823478;8987.281017518451;9313;9318.161365477139;9319.207867331354;9322.021187775457;10072.84890726625;10152.289339746147;10304.587816487556;10305.74235644115;10309.123724483503;7437.405007599271;8646.351523635627;8753.606087726195;8823.250023747796;8880.911190888008;7010;8420;8442.704505342284;8577.142285771622;8675.132486751274;7777.92222077772;7811.531308121531;7947.960260198662;7964.482308649919;7967.322777563972;5811.622244554091;5906.96628090074;5920.881582368243;6081.6351018939;6099.176611157437;4953.72754498506;4990.1516742154645;5802;6165.6608886509875;6298.149749783721 08:16:25 INFO - PID 1637 | |4;prototype.html;365.35697172975574;385.8109526332098;388.5415210052139;400.2055919000783;401.8754186202278;5940.405959404063;6041.4562689357945;6045.697715114248;6076.21009268795;6076.878462430755;5926.674032928187;5931.317898441681;5954.315253745821;5958.838026584817;5961.225040744708;3806.6764325032404;4238.936415953748;4251.447311849455;4691.88270293242;4708.387909571768;4133.545310015896;4216.746995180284;4654.720716756989;4665.83669571565;4673.556012178838;3799.9620003799882;4285.8071386787515;4308.3968244445805;4687.906241875177;4688.460827004904;3673.989652845462;3828.9808550957346;4455.621272191867;4477.977610111929;4489.663275254369;3612.0628514918603;3815.313243616148;3820.465134881119;3832.5400951885954;3840.732558255797;5824.970875145639;5865.35481097079;5870.559708021916;5874.73563689635;5880.382559831199;4331.610155086058;4955.628327875388;5173.948260517384;5232.555232805216;5236.450172731847;6323.083152942798;6326.620402775849;6342.241125110591;6342.873142537123;6347.682615869188;4254.595813397722;4261.97869010653;4262.488501379823;4262.957370426318;4294.828206871722;6322.841928951813;6376.489880809524;6604.240512341143;6604.900926486059;6779.525433219676;3822.235552889433;3901.5513215980527;4303.160883627663;4313.4823821141035;4331.718438301564;3864.9806750966063;4385.956140438619;4389.517153113155;4420.469543654782;4427.158839820424;4751.928721069186;4790.305405716152;5151.819686311036;5165.354330708661;5181.663191892515;6315.368463153648;6338.334474880164;6366.649834259068;6367.363263673596;6388.196570652333;1481.5111013365563;1488.9303867624012;1495.3794175417297;1495.9177245251615;1500.482333594908;945.6548359848606;982.4547376206132;987.9407235565889;997.1623835977814;999.8551658351147;5246.265522826808;5784.479396854304;5968.641881487125;6015.3383127855905;6022.939770602326;6585.9012114818315;6587.4071333580205;6588.341165883373;6602.207735071822;6619.966900165565;5686.744096515667;6014.669193194329;6068.33248342682;6100.908486372708;6109.877802443926;2682.77196438301;3096.8541639593495;3174.8095114293214;3438.3123375325035;3466.3413951349667;5467.863303417446;6621.702023408958;6776.2546119926765;6788.93211067883;6854.725810967605;6775.102971168081;6812.727490900407;6830.863382732317;6835.2228420611;6849.835527960258;5646.802361917313;6566.868662626721;6823.1812182538415;6843.8973415398805;6937.618430986344;5091.650712561248;6024.638521688713;6527.804165875032;6592.450774068186;6601;5364.302640656846;5694.943050569441;6013.458788709037;6188.133661287513;6475.413523686539;5646.407127251579;5707.915496055736;6535.908509276986;6814.8637027260165;6829.726810927706;4306.074194048295;4321.178975994551;4322.357504148404;4335.395903515593;4345.478542574911;5240.030594340059;6248.500119990481;6508.414242718178;6585.96707016481;6608.8347791305605;5364.612747327402;6031.276246850406;6275.525251565787;6342.619442833445;6350.682465876595;6012.3386427493815;6337.904931426125;6379.170707807955;6385.93614063872;6450.677900880956;6151.231096112986;6169.907451388143;6177.135201071944;6210.875782484415;6223.728339156266;5316.308879845596;5362.570994320367;6248.562600617913;6340.7463701451425;6387.808365749035;5426.550228812336;5667.801626943037;5930.318013428511;6124.295705993868;6154.846128846635 08:16:25 INFO - PID 1637 | |5;yui.html;720.8342081321296;723.6598798564675;725.2131437390431;728.4281838756574;734.8603765284594;15676.608084797886;16203.837961620395;16228.350865965362;16228.675426491463;17680.38118665847;17020;17085.572860678458;17104.48686539403;17168.141592920336;17168.484945451655;965.8406362950104;994.4331730913382;995.2436148527136;999.4103478947419;1003.6286573967606;975.6097560975631;1002.5388321372141;1015.3501758874327;1017.2726500552104;1017.6031347774374;1066.189695831166;1067.5622994572227;1069.1393428290223;1072.9785404291908;1077.6713102503734;1053.3574519543072;1057.888921663222;1059.2691043180257;1061.1669839176286;1065.9680209593687;782.6830133795822;788.7239466186846;790.2453157234827;791.0072858562525;792.0416296281506;15802.051876887424;16003.599910002227;16167.272472738638;16447.671046579002;16652.250648720837;14403.639909002253;14546.179932176363;14674.853251467455;14710.926445367812;14724.705505889822;11722.413879306001;11733.53065877364;11823.763524729457;11952.940235298855;12237.143399962006;2729.1266794625726;2986.8566368511656;3019.3055597212547;3092.2351092970803;3105.285784269608;104155;108677.91322086836;109040;110970.33544496758;112386.43806780914;10988.28576142564;11226.775464490827;11327.660170194908;11429.257098288585;11750.214438172045;24580.877095614407;25130;25170.24489265325;25192.35480274545;25255.87372063128;7329.900514922804;7383.335499805044;7492.512986655959;7539;7640.732574359982;4021.6581590564547;4118.629323360912;4150.584941505826;4158.043649960526;4170.270202714573;4046.1300820323772;4065.166640838621;4106.342985122366;4136.855210067633;4164.770937598461;772.8608850406869;773.7523992322402;778.1906816910463;795.2762985683;800.3517151107621;498.2924247568462;542.5795008868158;547.5960533258997;565.6537440890654;576.7001159397174;8471.49171049739;9029.864552031593;9895.703128906145;9953.601855925683;10192.745181370377;467.90407966366814;501.38830626635445;509.8266589359561;516.8811173430057;526.9245598252159;4408;4527.411436513232;4556.3621093047;4560.540626999328;4640.303954406799;92568;99160;100003;101448;103612;72750.90873636899;76428;79879.81081702675;87584.56207719048;91007;49207.76623493218;52734.736326318125;58948.82102357929;71174.93237601544;71379.64310178415;10595.152387808803;10664.89335106639;10855.945720271347;10897.400642964712;11052.834207487054;29480.852595736884;34208.657913420546;36852.81573592115;37015.629843701216;37421.81289093537;47454.76272618615;48298;48632.756836215594;48680.5131948676;49396.75301623469;13134.60596182116;13352.933235333761;13466.989975752054;13504.054716170167;13596.717281846153;14169;14292.28538573088;14370.85629143737;14455.349509271897;14626.268686565842;13481.865181348061;13552.59342219735;13559.389827457591;13623.591292261248;13701.383437745524;13083.93458032742;13503.783558627834;13527;13872.653183670489;13985.720285594434;12871.613851584467;13171.07802453819;13223.970872047894;13229.07396482237;13706.794398084236;9071.138241867013;9079.273658107204;9142.634294628406;9219.76950576241;9791.510424478605;7766.5728384939375;9286.814263714823;9874.605015799287;11120.833187502356;11124.888751112709 08:16:25 INFO - PID 1637 | __end_tp_report 08:16:25 INFO - PID 1637 | __start_cc_report 08:16:25 INFO - PID 1637 | _x_x_mozilla_cycle_collect,2199 08:16:25 INFO - PID 1637 | __end_cc_report 08:16:25 INFO - PID 1637 | __startTimestamp1490800585105__endTimestamp 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | ------- Summary: start ------- 08:16:25 INFO - PID 1637 | Number of tests: 6 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6143.88 Median:6352.68 stddev:682.23 (10.7%) stddev-sans-first:652.35 08:16:25 INFO - PID 1637 | Values: 3512.9 3513.2 3535.4 3560.6 3585.8 6071.5 6095.9 6310.5 6317.5 6340.7 5135.7 6203.6 6318.7 6326.8 6329.1 5481.2 5568.2 5636.0 5641.8 5767.9 5670.7 5709.9 5748.6 5756.3 5766.6 5499.8 5614.6 5621.6 5640.2 5686.1 5635.6 5659.3 5685.4 5726.2 5767.5 5612.5 5621.7 5634.6 5656.1 5663.9 6124.3 6206.8 6310.0 6318.1 6324.7 6456.2 6546.9 6636.7 6643.0 6704.5 6677.7 6681.5 6746.4 6782.3 6790.3 6317.4 6376.0 6386.0 6391.7 6409.9 5522.3 6663.4 6663.6 6668.0 6705.2 6437.6 6504.2 6531.4 6531.7 6544.5 6643.6 6662.4 6704.7 6716.6 6758.1 6352.7 6623.7 6629.1 6640.1 6640.4 6007.8 6410.3 6529.2 6602.0 6634.2 5325.1 5350.6 5350.8 5353.0 5376.9 4704.2 4768.7 4769.9 4776.2 4810.0 6213.1 6249.3 6260.8 6359.7 6382.3 6662.9 6727.1 6753.5 6774.1 6774.9 6310.0 6324.9 6326.6 6331.5 6335.5 5766.1 5826.6 5828.9 5868.6 5880.5 6748.3 6767.7 6885.1 6979.8 6980.7 6425.3 6465.0 6476.4 6720.4 6737.2 6789.2 6831.8 6837.9 6904.6 6914.7 6557.2 6588.2 6599.8 6622.0 6637.0 5455.9 6228.5 6525.4 6597.2 6711.1 6404.5 6413.0 6424.7 6430.7 6439.5 6402.0 6417.1 6418.8 6422.9 6423.9 5244.2 6293.6 6308.3 6340.3 6358.2 6376.4 6381.1 6389.4 6442.4 6492.8 5279.0 6305.5 6330.9 6338.4 6347.0 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:50654.59 Median:35216.88 stddev:98016.40 (278.3%) stddev-sans-first:98219.89 08:16:25 INFO - PID 1637 | Values: 799.2 820.8 1110.1 1137.2 1139.2 37003.8 37763.4 37835.2 37985.0 38016.5 39582.0 39783.4 39852.2 39982.0 39987.4 6105.8 8628.9 8750.1 8780.8 8801.5 6243.2 8466.8 8668.4 8678.8 8681.6 8707.8 8768.2 8768.7 8771.0 8801.8 8668.7 8710.9 8712.9 8735.6 8744.9 8506.0 8590.7 8594.6 8598.0 8608.7 38443.4 38560.0 38578.0 38623.8 38625.4 2678.2 3073.3 3095.7 3119.2 3165.0 27300.3 28421.7 28518.6 28684.0 28757.8 2727.7 2803.5 2858.8 2886.8 2900.8 322085.0 322236.4 322439.0 322697.0 324400.0 49337.5 49439.0 49481.8 49491.8 49533.5 42947.8 53075.5 53102.0 53122.0 53189.0 14220.9 14274.6 14308.9 14309.9 14326.6 11625.2 11626.9 11647.8 11649.2 11650.4 10848.3 12626.9 12642.6 12695.6 12758.9 5369.7 5416.7 5432.9 5435.6 5439.2 970.8 991.0 993.8 1006.6 1008.1 24128.9 24287.5 24325.3 24335.6 24395.8 1090.6 1091.9 1110.7 1128.8 1142.6 11606.9 11679.3 12359.9 12365.9 12523.1 401677.0 402145.7 402782.0 403048.0 404889.0 54275.7 54287.5 54297.2 54366.7 54419.5 1486.2 1488.6 1534.8 1542.6 1545.2 17092.7 22262.1 23503.5 23646.5 23660.8 38275.8 38319.6 38324.6 38419.0 38477.0 380303.0 380315.0 381292.0 382043.0 383288.1 31556.2 32523.8 33449.8 33486.3 33565.7 35336.1 42202.4 43454.9 43563.0 43764.3 27640.7 29486.7 30186.5 30342.8 30388.2 29373.6 29498.7 30146.7 30351.7 30359.5 35182.9 35250.8 35934.8 36230.1 36270.9 28673.1 30470.4 30558.5 30596.2 30671.8 22272.6 23612.2 23989.2 24086.0 24199.4 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:37216.44 Median:22566.05 stddev:101628.63 (450.4%) stddev-sans-first:101876.10 08:16:25 INFO - PID 1637 | Values: 385.8 420.0 428.5 430.0 446.5 15545.8 20804.6 22212.3 22397.6 22425.4 22706.7 23063.8 23077.9 23220.4 23506.4 5983.9 6073.3 6225.7 6328.0 6328.5 3648.9 6155.0 6267.4 6270.4 6277.3 3838.5 5934.3 6198.4 6220.0 6245.3 5956.2 6173.1 6231.8 6247.7 6256.7 5527.8 5529.3 5530.1 5534.6 5535.8 22826.0 26324.7 27102.0 27789.2 28118.6 2010.2 2036.5 2044.4 2051.3 2085.3 16108.3 16488.2 16566.8 16798.5 17277.0 1624.5 1628.8 1635.4 1668.5 1775.4 142853.0 153888.2 155024.2 156872.2 159987.0 11488.9 11504.6 12126.2 12132.5 12160.9 15121.7 15924.6 16204.4 16205.0 16226.8 8928.0 8966.6 9058.2 9186.0 9189.6 6385.9 8137.4 8235.1 8590.6 8737.7 6943.8 7798.8 7838.5 7847.6 7865.9 1786.6 2135.2 2137.8 2144.9 2279.2 900.5 900.7 900.7 941.3 952.6 20372.2 20913.2 20941.0 21027.5 21108.3 892.4 945.9 962.2 970.6 978.5 9977.6 10938.0 11046.8 11054.9 11055.1 591552.0 595680.0 597005.0 610215.0 610939.0 131159.0 140797.0 141912.3 148993.0 155107.0 1167.4 1182.8 1197.3 1198.9 1202.2 14058.4 17197.0 17908.6 18346.8 18403.2 56545.7 56927.6 57205.9 57383.7 57671.0 77292.6 77754.0 78045.0 78454.2 80464.0 8482.0 8506.8 8652.5 8667.9 8699.1 11881.6 13206.5 13210.9 13589.3 13613.4 11030.6 11703.8 11905.8 11922.7 12007.6 10713.5 10773.4 10908.0 11070.9 11107.3 11525.0 11712.2 11865.6 12053.6 12082.4 8535.5 8855.0 12482.6 12527.4 13022.5 10324.7 11828.0 11868.7 12173.9 12240.9 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6812.40 Median:2484.33 stddev:4250.42 (171.1%) stddev-sans-first:4242.60 08:16:25 INFO - PID 1637 | Values: 1360.7 1419.1 1434.8 1445.1 1460.3 10762.8 11203.2 11207.6 11227.6 11229.4 10781.5 10842.2 11160.7 11161.9 11180.7 2433.6 2478.0 2478.6 2484.1 2484.5 2402.1 2425.8 2477.8 2484.0 2484.1 2364.9 2477.3 2481.6 2481.7 2487.6 2441.2 2465.3 2470.4 2495.0 2505.7 2195.9 2360.6 2451.2 2465.5 2469.4 10852.0 11104.2 11122.4 11172.3 11173.6 6209.0 6462.4 6463.3 6463.8 6468.8 3945.7 3977.5 3987.8 4041.5 4084.8 1129.7 1143.4 1167.6 1169.0 1209.5 13984.9 14434.2 14439.5 15212.5 15428.5 5267.6 5409.4 5438.5 5447.9 5450.7 11491.5 11531.7 11545.4 11554.8 11560.4 5492.5 5605.8 5612.6 6344.2 6382.5 5669.2 5753.2 5809.9 5822.4 5832.3 4068.1 4090.0 4175.6 4196.3 4196.3 1165.7 1177.1 1183.8 1201.2 1204.5 756.6 767.2 784.9 788.8 802.3 5997.1 6045.9 6158.3 6240.4 6266.8 556.0 561.9 571.5 584.8 586.9 3674.8 4355.9 4378.3 4515.7 4521.7 13543.0 13851.6 14257.8 14286.9 14305.4 14092.9 14093.2 14098.0 14103.7 14112.8 10094.1 10367.3 10378.7 10380.9 10384.9 5929.6 6114.9 6568.0 6594.5 7015.5 12476.4 12783.3 12844.7 12858.6 12863.3 12745.4 12849.6 12871.5 13090.5 13137.6 8987.3 9313.0 9318.2 9319.2 9322.0 10072.8 10152.3 10304.6 10305.7 10309.1 7437.4 8646.4 8753.6 8823.3 8880.9 7010.0 8420.0 8442.7 8577.1 8675.1 7777.9 7811.5 7948.0 7964.5 7967.3 5811.6 5907.0 5920.9 6081.6 6099.2 4953.7 4990.2 5802.0 6165.7 6298.1 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5127.04 Median:5903.53 stddev:1613.31 (27.3%) stddev-sans-first:1577.75 08:16:25 INFO - PID 1637 | Values: 365.4 385.8 388.5 400.2 401.9 5940.4 6041.5 6045.7 6076.2 6076.9 5926.7 5931.3 5954.3 5958.8 5961.2 3806.7 4238.9 4251.4 4691.9 4708.4 4133.5 4216.7 4654.7 4665.8 4673.6 3800.0 4285.8 4308.4 4687.9 4688.5 3674.0 3829.0 4455.6 4478.0 4489.7 3612.1 3815.3 3820.5 3832.5 3840.7 5825.0 5865.4 5870.6 5874.7 5880.4 4331.6 4955.6 5173.9 5232.6 5236.5 6323.1 6326.6 6342.2 6342.9 6347.7 4254.6 4262.0 4262.5 4263.0 4294.8 6322.8 6376.5 6604.2 6604.9 6779.5 3822.2 3901.6 4303.2 4313.5 4331.7 3865.0 4386.0 4389.5 4420.5 4427.2 4751.9 4790.3 5151.8 5165.4 5181.7 6315.4 6338.3 6366.6 6367.4 6388.2 1481.5 1488.9 1495.4 1495.9 1500.5 945.7 982.5 987.9 997.2 999.9 5246.3 5784.5 5968.6 6015.3 6022.9 6585.9 6587.4 6588.3 6602.2 6620.0 5686.7 6014.7 6068.3 6100.9 6109.9 2682.8 3096.9 3174.8 3438.3 3466.3 5467.9 6621.7 6776.3 6788.9 6854.7 6775.1 6812.7 6830.9 6835.2 6849.8 5646.8 6566.9 6823.2 6843.9 6937.6 5091.7 6024.6 6527.8 6592.5 6601.0 5364.3 5694.9 6013.5 6188.1 6475.4 5646.4 5707.9 6535.9 6814.9 6829.7 4306.1 4321.2 4322.4 4335.4 4345.5 5240.0 6248.5 6508.4 6586.0 6608.8 5364.6 6031.3 6275.5 6342.6 6350.7 6012.3 6337.9 6379.2 6385.9 6450.7 6151.2 6169.9 6177.1 6210.9 6223.7 5316.3 5362.6 6248.6 6340.7 6387.8 5426.6 5667.8 5930.3 6124.3 6154.8 08:16:25 INFO - PID 1637 | 08:16:25 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19030.38 Median:13992.50 stddev:27042.63 (193.3%) stddev-sans-first:27083.55 08:16:25 INFO - PID 1637 | Values: 720.8 723.7 725.2 728.4 734.9 15676.6 16203.8 16228.4 16228.7 17680.4 17020.0 17085.6 17104.5 17168.1 17168.5 965.8 994.4 995.2 999.4 1003.6 975.6 1002.5 1015.4 1017.3 1017.6 1066.2 1067.6 1069.1 1073.0 1077.7 1053.4 1057.9 1059.3 1061.2 1066.0 782.7 788.7 790.2 791.0 792.0 15802.1 16003.6 16167.3 16447.7 16652.3 14403.6 14546.2 14674.9 14710.9 14724.7 11722.4 11733.5 11823.8 11952.9 12237.1 2729.1 2986.9 3019.3 3092.2 3105.3 104155.0 108677.9 109040.0 110970.3 112386.4 10988.3 11226.8 11327.7 11429.3 11750.2 24580.9 25130.0 25170.2 25192.4 25255.9 7329.9 7383.3 7492.5 7539.0 7640.7 4021.7 4118.6 4150.6 4158.0 4170.3 4046.1 4065.2 4106.3 4136.9 4164.8 772.9 773.8 778.2 795.3 800.4 498.3 542.6 547.6 565.7 576.7 8471.5 9029.9 9895.7 9953.6 10192.7 467.9 501.4 509.8 516.9 526.9 4408.0 4527.4 4556.4 4560.5 4640.3 92568.0 99160.0 100003.0 101448.0 103612.0 72750.9 76428.0 79879.8 87584.6 91007.0 49207.8 52734.7 58948.8 71174.9 71379.6 10595.2 10664.9 10855.9 10897.4 11052.8 29480.9 34208.7 36852.8 37015.6 37421.8 47454.8 48298.0 48632.8 48680.5 49396.8 13134.6 13352.9 13467.0 13504.1 13596.7 14169.0 14292.3 14370.9 14455.3 14626.3 13481.9 13552.6 13559.4 13623.6 13701.4 13083.9 13503.8 13527.0 13872.7 13985.7 12871.6 13171.1 13224.0 13229.1 13706.8 9071.1 9079.3 9142.6 9219.8 9791.5 7766.6 9286.8 9874.6 11120.8 11124.9 08:16:25 INFO - PID 1637 | -------- Summary: end -------- 08:16:25 INFO - PID 1637 | 08:16:25 INFO - TEST-INFO | 1637: exit 0 08:16:25 INFO - TEST-OK | dromaeo_css | took 1121113ms 08:16:25 INFO - TEST-START | kraken 08:16:25 INFO - operating with platform_type : mac_ 08:16:25 INFO - Initialising browser for kraken test... 08:16:25 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpLnjpeS/profile http://localhost:49241/getInfo.html) 08:16:28 INFO - PID 1647 | __metrics Screen width/height:1600/1200 08:16:28 INFO - PID 1647 | colorDepth:24 08:16:28 INFO - PID 1647 | Browser inner width/height: 1024/689 08:16:28 INFO - PID 1647 | __metrics 08:16:28 ERROR - PID 1647 | 1490800588658 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:16:28 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:16:28 INFO - PID 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:16:29 INFO - TEST-INFO | 1647: exit 0 08:16:29 INFO - Browser initialized. 08:16:29 INFO - Running cycle 1/1 for kraken test... 08:16:29 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpLnjpeS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:41 INFO - PID 1648 | RSS: Main: 234356736 08:16:41 INFO - PID 1648 | 08:16:44 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:16:45 INFO - PID 1648 | RSS: Main: 287014912 08:16:45 INFO - PID 1648 | 08:16:48 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:16:49 INFO - PID 1648 | RSS: Main: 287670272 08:16:49 INFO - PID 1648 | 08:16:52 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:16:53 INFO - PID 1648 | RSS: Main: 382308352 08:16:53 INFO - PID 1648 | 08:16:55 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:16:56 INFO - PID 1648 | RSS: Main: 283357184 08:16:56 INFO - PID 1648 | 08:16:58 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:16:59 INFO - PID 1648 | RSS: Main: 285851648 08:16:59 INFO - PID 1648 | 08:17:02 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:17:02 INFO - PID 1648 | RSS: Main: 327151616 08:17:02 INFO - PID 1648 | 08:17:06 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:17:06 INFO - PID 1648 | RSS: Main: 336842752 08:17:06 INFO - PID 1648 | 08:17:09 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:17:10 INFO - PID 1648 | RSS: Main: 340402176 08:17:10 INFO - PID 1648 | 08:17:12 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:17:12 INFO - PID 1648 | RSS: Main: 306278400 08:17:12 INFO - PID 1648 | 08:17:14 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:17:15 INFO - PID 1648 | RSS: Main: 307998720 08:17:15 INFO - PID 1648 | 08:17:17 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:17:18 INFO - PID 1648 | RSS: Main: 310153216 08:17:18 INFO - PID 1648 | 08:17:21 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:17:22 INFO - PID 1648 | RSS: Main: 329682944 08:17:22 INFO - PID 1648 | 08:17:25 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:17:26 INFO - PID 1648 | RSS: Main: 312123392 08:17:26 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:17:28 INFO - PID 1648 | RSS: Main: 378003456 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | __start_tp_report 08:17:28 INFO - PID 1648 | _x_x_mozilla_page_load 08:17:28 INFO - PID 1648 | _x_x_mozilla_page_load_details 08:17:28 INFO - PID 1648 | |i|pagename|runs| 08:17:28 INFO - PID 1648 | |0;ai-astar;112;124;123;121;139;183;161;123;121;122 08:17:28 INFO - PID 1648 | |1;audio-beat-detection;161;122;123;164;120;128;151;159;166;122 08:17:28 INFO - PID 1648 | |2;audio-dft;211;223;220;212;216;214;213;212;213;216 08:17:28 INFO - PID 1648 | |3;audio-fft;85;62;63;96;67;67;64;64;79;64 08:17:28 INFO - PID 1648 | |4;audio-oscillator;99;76;93;113;115;114;116;74;75;93 08:17:28 INFO - PID 1648 | |5;imaging-gaussian-blur;95;96;97;95;95;96;96;96;98;92 08:17:28 INFO - PID 1648 | |6;imaging-darkroom;101;101;99;99;99;99;101;102;101;99 08:17:28 INFO - PID 1648 | |7;imaging-desaturate;101;102;101;102;100;102;102;102;101;102 08:17:28 INFO - PID 1648 | |8;json-parse-financial;67;63;63;64;64;65;65;66;65;65 08:17:28 INFO - PID 1648 | |9;json-stringify-tinderbox;59;74;63;74;69;91;101;101;71;69 08:17:28 INFO - PID 1648 | |10;stanford-crypto-aes;90;91;92;91;93;90;92;90;91;91 08:17:28 INFO - PID 1648 | |11;stanford-crypto-ccm;162;148;160;148;150;154;152;156;206;154 08:17:28 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;221;205;217;207;196;262;262;197;198;203 08:17:28 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;71;77;73;72;72;73;73;74;74;73 08:17:28 INFO - PID 1648 | __end_tp_report 08:17:28 INFO - PID 1648 | __start_cc_report 08:17:28 INFO - PID 1648 | _x_x_mozilla_cycle_collect,2161 08:17:28 INFO - PID 1648 | __end_cc_report 08:17:28 INFO - PID 1648 | __startTimestamp1490800648668__endTimestamp 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | ------- Summary: start ------- 08:17:28 INFO - PID 1648 | Number of tests: 14 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:132.90 Median:123.50 stddev:22.24 (18.0%) stddev-sans-first:22.26 08:17:28 INFO - PID 1648 | Values: 112.0 124.0 123.0 121.0 139.0 183.0 161.0 123.0 121.0 122.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:155.00 stddev:20.08 (13.0%) stddev-sans-first:20.04 08:17:28 INFO - PID 1648 | Values: 161.0 122.0 123.0 164.0 120.0 128.0 151.0 159.0 166.0 122.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:215.00 Median:215.00 stddev:3.86 (1.8%) stddev-sans-first:3.81 08:17:28 INFO - PID 1648 | Values: 211.0 223.0 220.0 212.0 216.0 214.0 213.0 212.0 213.0 216.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:71.10 Median:67.00 stddev:11.59 (17.3%) stddev-sans-first:11.15 08:17:28 INFO - PID 1648 | Values: 85.0 62.0 63.0 96.0 67.0 67.0 64.0 64.0 79.0 64.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:75.50 stddev:17.38 (23.0%) stddev-sans-first:18.42 08:17:28 INFO - PID 1648 | Values: 99.0 76.0 93.0 113.0 115.0 114.0 116.0 74.0 75.0 93.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:96.00 stddev:1.58 (1.6%) stddev-sans-first:1.66 08:17:28 INFO - PID 1648 | Values: 95.0 96.0 97.0 95.0 95.0 96.0 96.0 96.0 98.0 92.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:99.00 stddev:1.20 (1.2%) stddev-sans-first:1.22 08:17:28 INFO - PID 1648 | Values: 101.0 101.0 99.0 99.0 99.0 99.0 101.0 102.0 101.0 99.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:101.50 Median:102.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 08:17:28 INFO - PID 1648 | Values: 101.0 102.0 101.0 102.0 100.0 102.0 102.0 102.0 101.0 102.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:1.25 (1.9%) stddev-sans-first:1.01 08:17:28 INFO - PID 1648 | Values: 67.0 63.0 63.0 64.0 64.0 65.0 65.0 66.0 65.0 65.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:77.20 Median:70.00 stddev:15.09 (21.6%) stddev-sans-first:14.50 08:17:28 INFO - PID 1648 | Values: 59.0 74.0 63.0 74.0 69.0 91.0 101.0 101.0 71.0 69.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:91.00 stddev:0.99 (1.1%) stddev-sans-first:0.97 08:17:28 INFO - PID 1648 | Values: 90.0 91.0 92.0 91.0 93.0 90.0 92.0 90.0 91.0 91.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:159.00 Median:155.00 stddev:17.16 (11.1%) stddev-sans-first:18.17 08:17:28 INFO - PID 1648 | Values: 162.0 148.0 160.0 148.0 150.0 154.0 152.0 156.0 206.0 154.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:216.80 Median:212.00 stddev:25.18 (11.9%) stddev-sans-first:26.66 08:17:28 INFO - PID 1648 | Values: 221.0 205.0 217.0 207.0 196.0 262.0 262.0 197.0 198.0 203.0 08:17:28 INFO - PID 1648 | 08:17:28 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:73.00 stddev:1.62 (2.2%) stddev-sans-first:1.51 08:17:28 INFO - PID 1648 | Values: 71.0 77.0 73.0 72.0 72.0 73.0 73.0 74.0 74.0 73.0 08:17:28 INFO - PID 1648 | -------- Summary: end -------- 08:17:28 INFO - PID 1648 | 08:17:29 INFO - TEST-INFO | 1648: exit 0 08:17:29 INFO - TEST-OK | kraken | took 63640ms 08:17:29 INFO - SUITE-END | took 1184s 08:17:29 INFO - Completed test suite (00:19:44) 08:17:29 INFO - javascript benchmark 08:17:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7602.169281145024, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3512.9121771955715, 3513.156842357834, 3535.381308271052, 3560.572731272246, 3585.7848529088255, 6071.51427885768, 6095.922000763725, 6310.49516038717, 6317.526185536086, 6340.682965851712, 5135.666181698187, 6203.627782333063, 6318.652474113922, 6326.841828954279, 6329.082283068952, 5481.150421684645, 5568.220449137124, 5635.971820140893, 5641.802536911209, 5767.9423205768035, 5670.744816483248, 5709.8572535686735, 5748.5688573357165, 5756.338021127582, 5766.567507436938, 5499.807506737285, 5614.606977511535, 5621.578381621394, 5640.238567793336, 5686.09022556389, 5635.6055076144685, 5659.292588426447, 5685.403032681592, 5726.169705392736, 5767.538596912279, 5612.522935550483, 5621.662700237999, 5634.605577609569, 5656.095024796054, 5663.8584035399035, 6124.319907527348, 6206.813795586139, 6310.0, 6318.083877837734, 6324.652144132071, 6456.192975878015, 6546.9017964730565, 6636.701348439332, 6642.966785166043, 6704.5306828522025, 6677.732890684415, 6681.465482761368, 6746.426553742937, 6782.287859774751, 6790.253072162058, 6317.368263173646, 6376.0, 6386.0, 6391.680415979183, 6409.871802563923, 5522.2544956431175, 6663.433608143265, 6663.566868153554, 6667.966660166765, 6705.195376554844, 6437.645929473831, 6504.219493660791, 6531.444827189742, 6531.673416329259, 6544.541882068304, 6643.634600097041, 6662.400383965466, 6704.731810727614, 6716.597004179765, 6758.087658166183, 6352.6823658816875, 6623.668816559153, 6629.105070815378, 6640.136782218281, 6640.435562977104, 6007.798440311955, 6410.262819775692, 6529.216494020748, 6601.966990165114, 6634.237062737847, 5325.121354976463, 5350.598705097055, 5350.785968561292, 5352.973235133877, 5376.892462150735, 4704.2473204287835, 4768.666193366431, 4769.9046019080115, 4776.164171270083, 4810.037992401533, 6213.068039793977, 6249.312575616678, 6260.780872669526, 6359.682015899187, 6382.32985536512, 6662.86674266512, 6727.058211850345, 6753.527253092237, 6774.119364482684, 6774.864502709918, 6309.968450157719, 6324.9051264231075, 6326.588771729823, 6331.493480521547, 6335.524835637346, 5766.1062535306255, 5826.563007774434, 5828.854278642983, 5868.589198756046, 5880.529557635379, 6748.325167483213, 6767.661616919233, 6885.139357580302, 6979.7557085503795, 6980.650967451708, 6425.293217745951, 6465.03024546303, 6476.384743449365, 6720.395164435225, 6737.191537015589, 6789.15135608049, 6831.829204269933, 6837.863242735217, 6904.620245886525, 6914.654267286515, 6557.180352455943, 6588.209414870054, 6599.835004124935, 6622.039804228263, 6637.0, 5455.890882182413, 6228.501719862491, 6525.445337146396, 6597.175353080865, 6711.094002309821, 6404.519661025349, 6412.967935160481, 6424.743010279536, 6430.710618022106, 6439.549231553934, 6402.03969404593, 6417.101605775289, 6418.807435776934, 6422.9036564450635, 6423.871522569429, 5244.213367994831, 6293.559450838397, 6308.33762454954, 6340.334264902119, 6358.205224346957, 6376.39424254695, 6381.106645069601, 6389.36106389376, 6442.420182183626, 6492.837679058061, 5278.97360513195, 6305.464035556845, 6330.9050364245495, 6338.366163383625, 6346.9682651586445], "value": 6101.540075354287, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.2447137455106, 820.811213420913, 1110.0675432636588, 1137.1528211482446, 1139.191174266271, 37003.81498092509, 37763.43354849676, 37835.24329513408, 37985.0, 38016.4793408264, 39582.01044973877, 39783.40324895129, 39852.20295594086, 39982.0, 39987.40018899719, 6105.786297479589, 8628.870566941501, 8750.08124146965, 8780.824383512327, 8801.471911685287, 6243.188385509878, 8466.788330291762, 8668.436551624156, 8678.783030424227, 8681.609327580274, 8707.782305442352, 8768.210861022506, 8768.736937891874, 8771.0, 8801.823963520726, 8668.739937801876, 8710.912890871105, 8712.912870871274, 8735.563221838882, 8744.868826967537, 8506.0, 8590.742277731679, 8594.613242404106, 8597.957010214972, 8608.741737747878, 38443.42334864979, 38560.0, 38578.0, 38623.806880965414, 38625.42061869074, 2678.2233152385784, 3073.34364078369, 3095.7059079387423, 3119.2357872321427, 3165.0, 27300.31749206286, 28421.71578284232, 28518.572221416696, 28684.0, 28757.849686012534, 2727.7408646178583, 2803.4673412051643, 2858.8427636479987, 2886.7546258568045, 2900.7824413168887, 322085.0, 322236.3888180591, 322438.9999999977, 322697.0, 324400.0, 49337.50662493401, 49439.0, 49481.752591236815, 49491.76270593264, 49533.50466495361, 42947.78526107349, 53075.469245307824, 53102.0, 53122.0, 53189.0, 14220.85779142216, 14274.571762847132, 14308.856911430961, 14309.926755493172, 14326.641833954234, 11625.18623696333, 11626.941865290619, 11647.76704465906, 11649.242799218024, 11650.41747912601, 10848.294860834021, 12626.936865315614, 12642.55751048709, 12695.555655552196, 12758.872411275768, 5369.677819330853, 5416.7020813855615, 5432.945670543323, 5435.619506634576, 5439.156930675752, 970.7767213540784, 990.9653162139291, 993.7515621094726, 1006.6476733143342, 1008.0927165550917, 24128.87935560311, 24287.514249714906, 24325.270241892773, 24335.634965475532, 24395.756042439705, 1090.6237348114887, 1091.9454027298602, 1110.650145204258, 1128.7573171768108, 1142.605800998654, 11606.88393116058, 11679.299242045334, 12359.876401236052, 12365.948894343901, 12523.123381363217, 401677.0, 402145.7210562667, 402782.0, 403047.9847600802, 404889.0, 54275.72862135664, 54287.45712542903, 54297.185542216896, 54366.72816635892, 54419.45580544223, 1486.2345891865698, 1488.5534339698263, 1534.7851300817895, 1542.5526597286662, 1545.1501674079211, 17092.658146836744, 22262.109515619843, 23503.52992940166, 23646.52706945817, 23660.763392366545, 38275.80862095783, 38319.6168038316, 38324.6167538321, 38419.0, 38477.0, 380303.0, 380315.0, 381292.0, 382043.0, 383288.08355958044, 31556.211094722814, 32523.837380812944, 33449.82576132544, 33486.33027339488, 33565.66434335723, 35336.11659708528, 42202.36696449617, 43454.91362715957, 43563.0, 43764.34353484764, 27640.6722571841, 29486.705132948395, 30186.54720179243, 30342.84828575931, 30388.24029399283, 29373.55939660864, 29498.672559734434, 30146.69853301439, 30351.696483035772, 30359.544606831358, 35182.94451166469, 35250.823745881105, 35934.8203258982, 36230.09424764296, 36270.91187264386, 28673.13980580586, 30470.390592188476, 30558.472076396534, 30596.235094122825, 30671.846640766653, 22272.554548909255, 23612.17357392483, 23989.160379386638, 24086.0, 24199.395015124763], "value": 17539.375745717713, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.7608282864624, 419.97690127043, 428.50293659355157, 429.9720518166319, 446.5199910096647, 15545.766813497807, 20804.597701149425, 22212.333629991124, 22397.552048959013, 22425.439364015907, 22706.65940010897, 23063.846807659633, 23077.884610576966, 23220.41948951273, 23506.412339691517, 5983.910241346383, 6073.271207455089, 6225.688715564226, 6327.968360158193, 6328.525360597951, 3648.945265821015, 6154.969225153868, 6267.43593076623, 6270.372962703739, 6277.309495955441, 3838.481804956337, 5934.287885453751, 6198.411150940653, 6219.9689001554925, 6245.344238854923, 5956.195913551658, 6173.074038894157, 6231.750729970819, 6247.718852651642, 6256.655883926383, 5527.806526771584, 5529.3364796224305, 5530.115181570929, 5534.612577119603, 5535.806246781383, 22826.0, 26324.73675263242, 27102.0, 27789.166325010283, 28118.578221326698, 2010.1657812008, 2036.53159773253, 2044.356027851237, 2051.3435700575824, 2085.291001059632, 16108.261595528671, 16488.17559122051, 16566.751498727528, 16798.496045118667, 17277.049762263072, 1624.5207663739225, 1628.828972957846, 1635.435774657753, 1668.4994501649578, 1775.387491315507, 142853.0, 153888.2305588476, 155024.22487887487, 156872.21563892107, 159987.0, 11488.942555287338, 11504.597339093092, 12126.151169418055, 12132.5146994121, 12160.878391215974, 15121.731740239053, 15924.566788988815, 16204.432844850373, 16205.027698338137, 16226.837731622532, 8928.0, 8966.596503157374, 9058.230050445654, 9186.0, 9189.55391728102, 6385.872282554323, 8137.389695772841, 8235.052968908532, 8590.570471476402, 8737.694180703646, 6943.79168624942, 7798.805029874298, 7838.490498117604, 7847.607619618996, 7865.882011769828, 1786.56229223841, 2135.1566131378017, 2137.78622137785, 2144.914203431845, 2279.2478482101196, 900.4777229207044, 900.6847603338779, 900.7342833864026, 941.3175447800427, 952.6189524190379, 20372.185112595627, 20913.163473461194, 20941.0, 21027.474313142295, 21108.250643605064, 892.4243862708516, 945.9290553208536, 962.167724917941, 970.5729479029204, 978.5058545434653, 9977.600895964226, 10938.015578597806, 11046.834297485544, 11054.944725276322, 11055.11559075272, 591552.0, 595680.0, 597005.0, 610215.0, 610939.0, 131159.0, 140797.0, 141912.29043854715, 148993.0, 155107.0, 1167.3962893475134, 1182.810750279951, 1197.3474456421272, 1198.874118217592, 1202.2425871700773, 14058.437662493794, 17197.0, 17908.55228619295, 18346.81653183451, 18403.17185726619, 56545.71727141338, 56927.57681058007, 57205.85588288294, 57383.713081434325, 57671.0, 77292.61353693421, 77754.0, 78045.0, 78454.2154578447, 80464.0, 8482.0, 8506.78733031679, 8652.480851148952, 8667.91332086671, 8699.13008699125, 11881.643550693492, 13206.537771178348, 13210.867891320964, 13589.252591107585, 13613.38739756733, 11030.558777649125, 11703.824442633197, 11905.761884762429, 11922.701932451759, 12007.639770806889, 10713.464326783786, 10773.407462589634, 10908.0, 11070.889291106985, 11107.333559986426, 11525.0, 11712.180147389941, 11865.644030679094, 12053.57812476559, 12082.39588020613, 8535.487870727527, 8854.955725221333, 12482.563110291096, 12527.37363131858, 13022.544210952572, 10324.690259292232, 11828.0, 11868.703282418008, 12173.939130304292, 12240.87759122433], "value": 10252.189198313057, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.6802401435662, 1419.0988722161424, 1434.7776094705312, 1445.0679311843855, 1460.291758497129, 10762.78474430512, 11203.159763017766, 11207.60773372932, 11227.55089796407, 11229.382383968878, 10781.514831832566, 10842.186835987293, 11160.720981975455, 11161.944190279037, 11180.66458006259, 2433.5862903306393, 2478.021181633254, 2478.615814548748, 2484.1429706751233, 2484.5155194737035, 2402.1232250228713, 2425.842320249187, 2477.7770000699934, 2483.962740558893, 2484.093305943336, 2364.8817559122062, 2477.2692055843568, 2481.6029435290357, 2481.6649752283483, 2487.614419764939, 2441.1700021992606, 2465.3097132802845, 2470.4441500662388, 2495.0394098272113, 2505.66173566568, 2195.913023053583, 2360.5750964826325, 2451.203358908362, 2465.4822487277693, 2469.4196863737006, 10851.969062939008, 11104.167187460973, 11122.388268645222, 11172.273802202832, 11173.608923687632, 6209.0, 6462.4183823456115, 6463.321351258144, 6463.773767918147, 6468.773592924225, 3945.7237993340477, 3977.5425826030087, 3987.80060996949, 4041.4948131483566, 4084.7957602119777, 1129.7232178116333, 1143.4454289669504, 1167.575557819459, 1169.0355456748216, 1209.5403746576333, 13984.93007534966, 14434.206118663575, 14439.494617688331, 15212.46756363522, 15428.537143885702, 5267.578593712494, 5409.350877894678, 5438.456154384609, 5447.863803404867, 5450.700211488327, 11491.54033838637, 11531.731509533945, 11545.365004924643, 11554.76890462186, 11560.364179970182, 5492.450754924555, 5605.775768969277, 5612.550995920316, 6344.206974128234, 6382.521310901701, 5669.234653321749, 5753.165790960287, 5809.8547536311935, 5822.359540450631, 5832.270966129234, 4068.145689405199, 4089.959100408958, 4175.64507016907, 4196.349565817303, 4196.349565817303, 1165.7318816672214, 1177.0995188680665, 1183.7810005149074, 1201.1831954271004, 1204.4579939027296, 756.5649751392971, 767.1982777997007, 784.9489783164076, 788.7830846517254, 802.2899733735686, 5997.100434934795, 6045.909311360333, 6158.261008678987, 6240.375962403814, 6266.843328916813, 555.9666420014812, 561.9072852979294, 571.4913726783167, 584.8157830283448, 586.8738221282359, 3674.8346324415465, 4355.911022244439, 4378.343248512685, 4515.706479078849, 4521.683482156283, 13543.0, 13851.58445246624, 14257.78613320801, 14286.85713142876, 14305.356258968372, 14092.929535352258, 14093.224872632103, 14098.0, 14103.71792564143, 14112.788308175386, 10094.141997930112, 10367.274290799722, 10378.740531486925, 10380.948095259626, 10384.948075259575, 5929.584929054925, 6114.938850611615, 6568.0, 6594.4724422045165, 7015.473839461959, 12476.43856026499, 12783.296918669563, 12844.743105138032, 12858.614241572768, 12863.292518911176, 12745.362731863555, 12849.629452546229, 12871.549495767604, 13090.541831035867, 13137.605871823478, 8987.281017518451, 9313.0, 9318.161365477139, 9319.207867331354, 9322.021187775457, 10072.84890726625, 10152.289339746147, 10304.587816487556, 10305.74235644115, 10309.123724483503, 7437.405007599271, 8646.351523635627, 8753.606087726195, 8823.250023747796, 8880.911190888008, 7010.0, 8420.0, 8442.704505342284, 8577.142285771622, 8675.132486751274, 7777.92222077772, 7811.531308121531, 7947.960260198662, 7964.482308649919, 7967.322777563972, 5811.622244554091, 5906.96628090074, 5920.881582368243, 6081.6351018939, 6099.176611157437, 4953.72754498506, 4990.1516742154645, 5802.0, 6165.6608886509875, 6298.149749783721], "value": 5124.953701373889, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.35697172975574, 385.8109526332098, 388.5415210052139, 400.2055919000783, 401.8754186202278, 5940.405959404063, 6041.4562689357945, 6045.697715114248, 6076.21009268795, 6076.878462430755, 5926.674032928187, 5931.317898441681, 5954.315253745821, 5958.838026584817, 5961.225040744708, 3806.6764325032404, 4238.936415953748, 4251.447311849455, 4691.88270293242, 4708.387909571768, 4133.545310015896, 4216.746995180284, 4654.720716756989, 4665.83669571565, 4673.556012178838, 3799.9620003799882, 4285.8071386787515, 4308.3968244445805, 4687.906241875177, 4688.460827004904, 3673.989652845462, 3828.9808550957346, 4455.621272191867, 4477.977610111929, 4489.663275254369, 3612.0628514918603, 3815.313243616148, 3820.465134881119, 3832.5400951885954, 3840.732558255797, 5824.970875145639, 5865.35481097079, 5870.559708021916, 5874.73563689635, 5880.382559831199, 4331.610155086058, 4955.628327875388, 5173.948260517384, 5232.555232805216, 5236.450172731847, 6323.083152942798, 6326.620402775849, 6342.241125110591, 6342.873142537123, 6347.682615869188, 4254.595813397722, 4261.97869010653, 4262.488501379823, 4262.957370426318, 4294.828206871722, 6322.841928951813, 6376.489880809524, 6604.240512341143, 6604.900926486059, 6779.525433219676, 3822.235552889433, 3901.5513215980527, 4303.160883627663, 4313.4823821141035, 4331.718438301564, 3864.9806750966063, 4385.956140438619, 4389.517153113155, 4420.469543654782, 4427.158839820424, 4751.928721069186, 4790.305405716152, 5151.819686311036, 5165.354330708661, 5181.663191892515, 6315.368463153648, 6338.334474880164, 6366.649834259068, 6367.363263673596, 6388.196570652333, 1481.5111013365563, 1488.9303867624012, 1495.3794175417297, 1495.9177245251615, 1500.482333594908, 945.6548359848606, 982.4547376206132, 987.9407235565889, 997.1623835977814, 999.8551658351147, 5246.265522826808, 5784.479396854304, 5968.641881487125, 6015.3383127855905, 6022.939770602326, 6585.9012114818315, 6587.4071333580205, 6588.341165883373, 6602.207735071822, 6619.966900165565, 5686.744096515667, 6014.669193194329, 6068.33248342682, 6100.908486372708, 6109.877802443926, 2682.77196438301, 3096.8541639593495, 3174.8095114293214, 3438.3123375325035, 3466.3413951349667, 5467.863303417446, 6621.702023408958, 6776.2546119926765, 6788.93211067883, 6854.725810967605, 6775.102971168081, 6812.727490900407, 6830.863382732317, 6835.2228420611, 6849.835527960258, 5646.802361917313, 6566.868662626721, 6823.1812182538415, 6843.8973415398805, 6937.618430986344, 5091.650712561248, 6024.638521688713, 6527.804165875032, 6592.450774068186, 6601.0, 5364.302640656846, 5694.943050569441, 6013.458788709037, 6188.133661287513, 6475.413523686539, 5646.407127251579, 5707.915496055736, 6535.908509276986, 6814.8637027260165, 6829.726810927706, 4306.074194048295, 4321.178975994551, 4322.357504148404, 4335.395903515593, 4345.478542574911, 5240.030594340059, 6248.500119990481, 6508.414242718178, 6585.96707016481, 6608.8347791305605, 5364.612747327402, 6031.276246850406, 6275.525251565787, 6342.619442833445, 6350.682465876595, 6012.3386427493815, 6337.904931426125, 6379.170707807955, 6385.93614063872, 6450.677900880956, 6151.231096112986, 6169.907451388143, 6177.135201071944, 6210.875782484415, 6223.728339156266, 5316.308879845596, 5362.570994320367, 6248.562600617913, 6340.7463701451425, 6387.808365749035, 5426.550228812336, 5667.801626943037, 5930.318013428511, 6124.295705993868, 6154.846128846635], "value": 4624.354015741918, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.8342081321296, 723.6598798564675, 725.2131437390431, 728.4281838756574, 734.8603765284594, 15676.608084797886, 16203.837961620395, 16228.350865965362, 16228.675426491463, 17680.38118665847, 17020.0, 17085.572860678458, 17104.48686539403, 17168.141592920336, 17168.484945451655, 965.8406362950104, 994.4331730913382, 995.2436148527136, 999.4103478947419, 1003.6286573967606, 975.6097560975631, 1002.5388321372141, 1015.3501758874327, 1017.2726500552104, 1017.6031347774374, 1066.189695831166, 1067.5622994572227, 1069.1393428290223, 1072.9785404291908, 1077.6713102503734, 1053.3574519543072, 1057.888921663222, 1059.2691043180257, 1061.1669839176286, 1065.9680209593687, 782.6830133795822, 788.7239466186846, 790.2453157234827, 791.0072858562525, 792.0416296281506, 15802.051876887424, 16003.599910002227, 16167.272472738638, 16447.671046579002, 16652.250648720837, 14403.639909002253, 14546.179932176363, 14674.853251467455, 14710.926445367812, 14724.705505889822, 11722.413879306001, 11733.53065877364, 11823.763524729457, 11952.940235298855, 12237.143399962006, 2729.1266794625726, 2986.8566368511656, 3019.3055597212547, 3092.2351092970803, 3105.285784269608, 104155.0, 108677.91322086836, 109040.0, 110970.33544496758, 112386.43806780914, 10988.28576142564, 11226.775464490827, 11327.660170194908, 11429.257098288585, 11750.214438172045, 24580.877095614407, 25130.0, 25170.24489265325, 25192.35480274545, 25255.87372063128, 7329.900514922804, 7383.335499805044, 7492.512986655959, 7539.0, 7640.732574359982, 4021.6581590564547, 4118.629323360912, 4150.584941505826, 4158.043649960526, 4170.270202714573, 4046.1300820323772, 4065.166640838621, 4106.342985122366, 4136.855210067633, 4164.770937598461, 772.8608850406869, 773.7523992322402, 778.1906816910463, 795.2762985683, 800.3517151107621, 498.2924247568462, 542.5795008868158, 547.5960533258997, 565.6537440890654, 576.7001159397174, 8471.49171049739, 9029.864552031593, 9895.703128906145, 9953.601855925683, 10192.745181370377, 467.90407966366814, 501.38830626635445, 509.8266589359561, 516.8811173430057, 526.9245598252159, 4408.0, 4527.411436513232, 4556.3621093047, 4560.540626999328, 4640.303954406799, 92568.0, 99160.0, 100003.0, 101448.0, 103612.0, 72750.90873636899, 76428.0, 79879.81081702675, 87584.56207719048, 91007.0, 49207.76623493218, 52734.736326318125, 58948.82102357929, 71174.93237601544, 71379.64310178415, 10595.152387808803, 10664.89335106639, 10855.945720271347, 10897.400642964712, 11052.834207487054, 29480.852595736884, 34208.657913420546, 36852.81573592115, 37015.629843701216, 37421.81289093537, 47454.76272618615, 48298.0, 48632.756836215594, 48680.5131948676, 49396.75301623469, 13134.60596182116, 13352.933235333761, 13466.989975752054, 13504.054716170167, 13596.717281846153, 14169.0, 14292.28538573088, 14370.85629143737, 14455.349509271897, 14626.268686565842, 13481.865181348061, 13552.59342219735, 13559.389827457591, 13623.591292261248, 13701.383437745524, 13083.93458032742, 13503.783558627834, 13527.0, 13872.653183670489, 13985.720285594434, 12871.613851584467, 13171.07802453819, 13223.970872047894, 13229.07396482237, 13706.794398084236, 9071.138241867013, 9079.273658107204, 9142.634294628406, 9219.76950576241, 9791.510424478605, 7766.5728384939375, 9286.814263714823, 9874.605015799287, 11120.833187502356, 11124.888751112709], "value": 7423.03136864951, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1636.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 124.0, 123.0, 121.0, 139.0, 183.0, 161.0, 123.0, 121.0, 122.0], "value": 132.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 122.0, 123.0, 164.0, 120.0, 128.0, 151.0, 159.0, 166.0, 122.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 223.0, 220.0, 212.0, 216.0, 214.0, 213.0, 212.0, 213.0, 216.0], "value": 215.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 63.0, 96.0, 67.0, 67.0, 64.0, 64.0, 79.0, 64.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 76.0, 93.0, 113.0, 115.0, 114.0, 116.0, 74.0, 75.0, 93.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 98.0, 92.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 99.0, 99.0, 99.0, 99.0, 101.0, 102.0, 101.0, 99.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 101.0, 102.0, 100.0, 102.0, 102.0, 102.0, 101.0, 102.0], "value": 101.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 64.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 74.0, 63.0, 74.0, 69.0, 91.0, 101.0, 101.0, 71.0, 69.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 92.0, 91.0, 93.0, 90.0, 92.0, 90.0, 91.0, 91.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 148.0, 160.0, 148.0, 150.0, 154.0, 152.0, 156.0, 206.0, 154.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 205.0, 217.0, 207.0, 196.0, 262.0, 262.0, 197.0, 198.0, 203.0], "value": 216.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 73.0, 72.0, 72.0, 73.0, 73.0, 74.0, 74.0, 73.0], "value": 73.2, "unit": "score"}]}]} 08:17:29 INFO - Return code: 0 08:17:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:17:29 ERROR - # TBPL SUCCESS # 08:17:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:30 INFO - 2017-03-29 08:17:29.999 screenresolution[1652:9766] starting screenresolution argv=screenresolution get 08:17:30 INFO - 2017-03-29 08:17:30.001 screenresolution[1652:9766] Display 0: 1600x1200x32@60 08:17:30 INFO - 2017-03-29 08:17:30.008 screenresolution[1653:9769] starting screenresolution argv=screenresolution list 08:17:30 INFO - Available Modes on Display 0 08:17:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:30 INFO - Intel Iris: 08:17:30 INFO - Chipset Model: Intel Iris 08:17:30 INFO - Type: GPU 08:17:30 INFO - Bus: Built-In 08:17:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:30 INFO - Vendor: Intel (0x8086) 08:17:30 INFO - Device ID: 0x0a2e 08:17:30 INFO - Revision ID: 0x0009 08:17:30 INFO - Displays: 08:17:30 INFO - Display: 08:17:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:30 INFO - Display Serial Number: 1600x1200 60 08:17:30 INFO - Main Display: Yes 08:17:30 INFO - Mirror: Off 08:17:30 INFO - Online: Yes 08:17:30 INFO - Rotation: Supported 08:17:30 INFO - Return code: 0 08:17:30 INFO - Running post-action listener: _resource_record_post_action 08:17:30 INFO - [mozharness: 2017-03-29 15:17:30.242885Z] Finished run-tests step (success) 08:17:30 INFO - Running post-run listener: _resource_record_post_run 08:17:30 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 38204416; Write bytes: 519046144; Read time: 241; Write time: 1597 08:17:30 INFO - TinderboxPrint: CPU usage
53.6% 08:17:30 INFO - TinderboxPrint: I/O read bytes / time
38,204,416 / 241 08:17:30 INFO - TinderboxPrint: I/O write bytes / time
519,046,144 / 1,597 08:17:30 INFO - TinderboxPrint: CPU idle
2,240.7 (46.4%) 08:17:30 INFO - TinderboxPrint: CPU system
966.5 (20.0%) 08:17:30 INFO - TinderboxPrint: CPU user
1,618.5 (33.5%) 08:17:30 INFO - TinderboxPrint: Swap in / out
249,741,312 / 0 08:17:30 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205068800; Write bytes: 206788608; Read time: 15633; Write time: 448 08:17:30 INFO - run-tests - Wall time: 1186s; CPU: 54.0%; Read bytes: 37233664; Write bytes: 302242816; Read time: 227; Write time: 1119 08:17:30 INFO - Running post-run listener: _upload_blobber_files 08:17:30 INFO - Blob upload gear active. 08:17:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:17:30 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:17:30 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:17:30 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:17:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:17:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:17:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:17:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:32 INFO - (blobuploader) - INFO - Done attempting. 08:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:17:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:33 INFO - (blobuploader) - INFO - Done attempting. 08:17:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:17:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:17:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:34 INFO - (blobuploader) - INFO - Done attempting. 08:17:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:17:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:17:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:36 INFO - (blobuploader) - INFO - Done attempting. 08:17:36 INFO - (blobuploader) - INFO - Iteration through files over. 08:17:36 INFO - Return code: 0 08:17:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:17:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:17:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aea9ffbf65b4eb2df0018ba2862c9c273c2e1660e4f96abe4df94ba2b56d08e8045583782531dc916a1ae8a7fc13aff66a6d5f2c7b241d1fb46bbb3d4f0a3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/860e330f123dac97f4da37ee294fd0bbef991a5e2a0e6b3769b6a1e3e75ead49f5050d80d10a8d9a785bf32442f010ecbd3fa4d3268d6c21ff5cdabf802b1a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/587fee507b08f69b092b3616ec6520c6017e110f926b470d5038df60357a2a2e68ce6468ed153dbb12d177497d28a7ecc1c995200605213bf699037f66189293", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b67d34804e7a87da2c2863832b7f1222f0f10c161e873fb87efcd398af63572c3dc8f5e206ed1048441d124a86e80b8959e2e681bbd1562668f57553ef08abf9"} 08:17:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:17:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:17:36 INFO - Contents: 08:17:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aea9ffbf65b4eb2df0018ba2862c9c273c2e1660e4f96abe4df94ba2b56d08e8045583782531dc916a1ae8a7fc13aff66a6d5f2c7b241d1fb46bbb3d4f0a3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/860e330f123dac97f4da37ee294fd0bbef991a5e2a0e6b3769b6a1e3e75ead49f5050d80d10a8d9a785bf32442f010ecbd3fa4d3268d6c21ff5cdabf802b1a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/587fee507b08f69b092b3616ec6520c6017e110f926b470d5038df60357a2a2e68ce6468ed153dbb12d177497d28a7ecc1c995200605213bf699037f66189293", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b67d34804e7a87da2c2863832b7f1222f0f10c161e873fb87efcd398af63572c3dc8f5e206ed1048441d124a86e80b8959e2e681bbd1562668f57553ef08abf9"} 08:17:36 INFO - Running post-run listener: copy_logs_to_upload_dir 08:17:36 INFO - Copying logs to upload dir... 08:17:36 INFO - mkdir: /builds/slave/test/build/upload/logs 08:17:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.368813 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-29 08:17:36.599252) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 08:17:36.603359) ========= 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.4tNOSSatOM/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.jZgxcOgazK/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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/2aea9ffbf65b4eb2df0018ba2862c9c273c2e1660e4f96abe4df94ba2b56d08e8045583782531dc916a1ae8a7fc13aff66a6d5f2c7b241d1fb46bbb3d4f0a3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/860e330f123dac97f4da37ee294fd0bbef991a5e2a0e6b3769b6a1e3e75ead49f5050d80d10a8d9a785bf32442f010ecbd3fa4d3268d6c21ff5cdabf802b1a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/587fee507b08f69b092b3616ec6520c6017e110f926b470d5038df60357a2a2e68ce6468ed153dbb12d177497d28a7ecc1c995200605213bf699037f66189293", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b67d34804e7a87da2c2863832b7f1222f0f10c161e873fb87efcd398af63572c3dc8f5e206ed1048441d124a86e80b8959e2e681bbd1562668f57553ef08abf9"} build_url:https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011028 build_url: 'https://queue.taskcluster.net/v1/task/MzgBpvSgTw2wQl-jlLbq7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2aea9ffbf65b4eb2df0018ba2862c9c273c2e1660e4f96abe4df94ba2b56d08e8045583782531dc916a1ae8a7fc13aff66a6d5f2c7b241d1fb46bbb3d4f0a3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/860e330f123dac97f4da37ee294fd0bbef991a5e2a0e6b3769b6a1e3e75ead49f5050d80d10a8d9a785bf32442f010ecbd3fa4d3268d6c21ff5cdabf802b1a46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/587fee507b08f69b092b3616ec6520c6017e110f926b470d5038df60357a2a2e68ce6468ed153dbb12d177497d28a7ecc1c995200605213bf699037f66189293", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b67d34804e7a87da2c2863832b7f1222f0f10c161e873fb87efcd398af63572c3dc8f5e206ed1048441d124a86e80b8959e2e681bbd1562668f57553ef08abf9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 08:17:36.639810) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:17:36.640586) ========= 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.4tNOSSatOM/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.jZgxcOgazK/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.005240 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:17:36.690172) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 08:17:36.690590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 08:17:36.691071) ========= ========= Total master_lag: 1.07 =========