builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0022 starttime: 1489510409.35 results: success (0) buildid: 20170314081436 builduid: bbf87303c1b446fc8f5175dcad47978e revision: 2baef2ffbaedb7354286726660ebd36e84b432f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.351689) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.352372) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.352869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.378975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.379362) ========= 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.3qH40Yjaxz/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.VryxjCoTYy/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-14 09:53:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-03-14 09:53:29 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131444 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.946055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.946364) ========= 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.3qH40Yjaxz/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.VryxjCoTYy/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.019940 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:29.988738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-14 09:53:29.989112) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2baef2ffbaedb7354286726660ebd36e84b432f0 --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 2baef2ffbaedb7354286726660ebd36e84b432f0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3qH40Yjaxz/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.VryxjCoTYy/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-14 09:53:30,082 truncating revision to first 12 chars 2017-03-14 09:53:30,082 Setting DEBUG logging. 2017-03-14 09:53:30,083 attempt 1/10 2017-03-14 09:53:30,083 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2baef2ffbaed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 09:53:31,068 unpacking tar archive at: mozilla-central-2baef2ffbaed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.252103 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-14 09:53:31.268254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:31.268686) ========= script_repo_revision: 2baef2ffbaedb7354286726660ebd36e84b432f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:31.269127) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:31.269413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 09:53:31.289690) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-14 09:53:31.290089) ========= /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.3qH40Yjaxz/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.VryxjCoTYy/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:53:31 INFO - MultiFileLogger online at 20170314 09:53:31 in /builds/slave/test 09:53:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:53:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:53:31 INFO - {'append_to_log': False, 09:53:31 INFO - 'base_work_dir': '/builds/slave/test', 09:53:31 INFO - 'blob_upload_branch': 'Firefox', 09:53:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:53:31 INFO - 'branch': 'Firefox', 09:53:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:53:31 INFO - 'config_files': ('talos/mac_config.py',), 09:53:31 INFO - 'default_actions': ('clobber', 09:53:31 INFO - 'read-buildbot-config', 09:53:31 INFO - 'download-and-extract', 09:53:31 INFO - 'populate-webroot', 09:53:31 INFO - 'create-virtualenv', 09:53:31 INFO - 'install', 09:53:31 INFO - 'run-tests'), 09:53:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:53:31 INFO - 'download_minidump_stackwalk': True, 09:53:31 INFO - 'download_symbols': 'ondemand', 09:53:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:53:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:53:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:53:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:53:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:53:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:53:31 INFO - 'gecko_profile': False, 09:53:31 INFO - 'gecko_profile_interval': 0, 09:53:31 INFO - 'installer_path': 'installer.exe', 09:53:31 INFO - 'log_level': 'info', 09:53:31 INFO - 'log_name': 'talos', 09:53:31 INFO - 'log_to_console': True, 09:53:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:53:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:53:31 INFO - 'opt_config_files': (), 09:53:31 INFO - 'pip_index': False, 09:53:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:53:31 INFO - 'cmd': ('bash', 09:53:31 INFO - '-c', 09:53:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:53:31 INFO - 'enabled': True, 09:53:31 INFO - 'halt_on_failure': False, 09:53:31 INFO - 'name': 'check_screen_resolution'},), 09:53:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:53:31 INFO - 'cmd': ('bash', 09:53:31 INFO - '-c', 09:53:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:53:31 INFO - 'enabled': True, 09:53:31 INFO - 'halt_on_failure': False, 09:53:31 INFO - 'name': 'check_screen_resolution'},), 09:53:31 INFO - 'run_cmd_checks_enabled': True, 09:53:31 INFO - 'suite': 'dromaeojs', 09:53:31 INFO - 'system_bits': '32', 09:53:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:53:31 INFO - 'title': 't-yosemite-r7-0022', 09:53:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:53:31 INFO - 'use_talos_json': True, 09:53:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:53:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:53:31 INFO - 'work_dir': 'build'} 09:53:31 INFO - [mozharness: 2017-03-14 16:53:31.509064Z] Running clobber step. 09:53:31 INFO - Running pre-action listener: _resource_record_pre_action 09:53:31 INFO - Running main action method: clobber 09:53:31 INFO - rmtree: /builds/slave/test/build 09:53:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:53:33 INFO - Running post-action listener: _resource_record_post_action 09:53:33 INFO - [mozharness: 2017-03-14 16:53:33.326814Z] Finished clobber step (success) 09:53:33 INFO - [mozharness: 2017-03-14 16:53:33.326953Z] Running read-buildbot-config step. 09:53:33 INFO - Running pre-action listener: _resource_record_pre_action 09:53:33 INFO - Running main action method: read_buildbot_config 09:53:33 INFO - Using buildbot properties: 09:53:33 INFO - { 09:53:33 INFO - "project": "", 09:53:33 INFO - "product": "firefox", 09:53:33 INFO - "script_repo_revision": "production", 09:53:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:53:33 INFO - "repository": "", 09:53:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:53:33 INFO - "buildid": "20170314081436", 09:53:33 INFO - "pgo_build": "False", 09:53:33 INFO - "basedir": "/builds/slave/test", 09:53:33 INFO - "buildnumber": 40, 09:53:33 INFO - "slavename": "t-yosemite-r7-0022", 09:53:33 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:53:33 INFO - "platform": "yosemite_r7", 09:53:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:53:33 INFO - "branch": "mozilla-central", 09:53:33 INFO - "repo_path": "mozilla-central", 09:53:33 INFO - "revision": "2baef2ffbaedb7354286726660ebd36e84b432f0", 09:53:33 INFO - "stage_platform": "macosx64", 09:53:33 INFO - "builduid": "bbf87303c1b446fc8f5175dcad47978e", 09:53:33 INFO - "slavebuilddir": "test" 09:53:33 INFO - } 09:53:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:53:33 INFO - Running post-action listener: _resource_record_post_action 09:53:33 INFO - [mozharness: 2017-03-14 16:53:33.330435Z] Finished read-buildbot-config step (success) 09:53:33 INFO - [mozharness: 2017-03-14 16:53:33.330569Z] Running download-and-extract step. 09:53:33 INFO - Running pre-action listener: _resource_record_pre_action 09:53:33 INFO - Running main action method: download_and_extract 09:53:33 INFO - mkdir: /builds/slave/test/build/tests 09:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:33 INFO - trying https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:53:33 INFO - Downloading https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/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 09:53:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/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 09:53:34 INFO - Downloaded 1199 bytes. 09:53:34 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:53:34 INFO - Using the following test package requirements: 09:53:34 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:53:34 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:53:34 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:53:34 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'jsshell-mac.zip'], 09:53:34 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:53:34 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:53:34 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:53:34 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:53:34 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:53:34 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:53:34 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:53:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:53:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:53:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:53:34 INFO - Fetch https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:53:36 INFO - Expected file size: 52468960 09:53:36 INFO - Obtained file size: 52468960 09:53:52 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 09:53:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:53:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:53:52 INFO - Fetch https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:53:54 INFO - Expected file size: 52468960 09:53:54 INFO - Obtained file size: 52468960 09:54:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:54:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:54:09 INFO - Fetch https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:54:10 INFO - Expected file size: 14404452 09:54:10 INFO - Obtained file size: 14404452 09:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:11 INFO - trying https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:54:11 INFO - Downloading https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:54:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/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 09:54:13 INFO - Downloaded 74990414 bytes. 09:54:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:54:13 INFO - mkdir: /builds/slave/test/properties 09:54:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:54:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:54:13 INFO - Contents: 09:54:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:54:14 INFO - Running post-action listener: _resource_record_post_action 09:54:14 INFO - Running post-action listener: set_extra_try_arguments 09:54:14 INFO - [mozharness: 2017-03-14 16:54:14.123952Z] Finished download-and-extract step (success) 09:54:14 INFO - [mozharness: 2017-03-14 16:54:14.124124Z] Running populate-webroot step. 09:54:14 INFO - Running pre-action listener: _resource_record_pre_action 09:54:14 INFO - Running main action method: populate_webroot 09:54:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:54:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:54:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:54:14 INFO - '--tppagecycles', 09:54:14 INFO - '7'], 09:54:14 INFO - 'tests': ['tsvgm']}}, 09:54:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:54:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:54:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:54:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:54:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['damp', 'tps']}, 09:54:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'tests': ['damp', 'tps']}, 09:54:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['dromaeo_dom']}, 09:54:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:54:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['basic_compositor_video']}, 09:54:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:54:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['a11yr', 09:54:14 INFO - 'ts_paint', 09:54:14 INFO - 'tpaint', 09:54:14 INFO - 'sessionrestore', 09:54:14 INFO - 'sessionrestore_no_auto_restore', 09:54:14 INFO - 'tabpaint']}, 09:54:14 INFO - 'other-e10s': {'tests': ['a11yr', 09:54:14 INFO - 'ts_paint', 09:54:14 INFO - 'tpaint', 09:54:14 INFO - 'sessionrestore', 09:54:14 INFO - 'sessionrestore_no_auto_restore', 09:54:14 INFO - 'tabpaint']}, 09:54:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:54:14 INFO - 'ts_paint', 09:54:14 INFO - 'tpaint', 09:54:14 INFO - 'sessionrestore', 09:54:14 INFO - 'sessionrestore_no_auto_restore', 09:54:14 INFO - 'tabpaint']}, 09:54:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:54:14 INFO - 'ts_paint', 09:54:14 INFO - 'tpaint', 09:54:14 INFO - 'sessionrestore', 09:54:14 INFO - 'sessionrestore_no_auto_restore', 09:54:14 INFO - 'tabpaint']}, 09:54:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['a11yr', 09:54:14 INFO - 'ts_paint', 09:54:14 INFO - 'tpaint', 09:54:14 INFO - 'sessionrestore', 09:54:14 INFO - 'sessionrestore_no_auto_restore', 09:54:14 INFO - 'tabpaint']}, 09:54:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['a11yr', 09:54:14 INFO - 'ts_paint', 09:54:14 INFO - 'tpaint', 09:54:14 INFO - 'sessionrestore', 09:54:14 INFO - 'sessionrestore_no_auto_restore', 09:54:14 INFO - 'tabpaint']}, 09:54:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['tsvgx', 09:54:14 INFO - 'tsvgr_opacity', 09:54:14 INFO - 'tart', 09:54:14 INFO - 'tscrollx', 09:54:14 INFO - 'cart', 09:54:14 INFO - 'tsvg_static']}, 09:54:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:54:14 INFO - 'tsvgr_opacity', 09:54:14 INFO - 'tart', 09:54:14 INFO - 'tscrollx', 09:54:14 INFO - 'cart', 09:54:14 INFO - 'tsvg_static']}, 09:54:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'talos_options': ['--disable-e10s'], 09:54:14 INFO - 'tests': ['tp5o']}, 09:54:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:54:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'talos_options': ['--disable-e10s', 09:54:14 INFO - '--xperf_path', 09:54:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:14 INFO - 'tests': ['tp5n']}, 09:54:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:54:14 INFO - 'talos_options': ['--xperf_path', 09:54:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:14 INFO - 'tests': ['tp5n']}}, 09:54:14 INFO - 'talos.zip': {'path': '', 09:54:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:54:14 INFO - Running post-action listener: _resource_record_post_action 09:54:14 INFO - [mozharness: 2017-03-14 16:54:14.146868Z] Finished populate-webroot step (success) 09:54:14 INFO - [mozharness: 2017-03-14 16:54:14.146974Z] Running create-virtualenv step. 09:54:14 INFO - Running pre-action listener: _resource_record_pre_action 09:54:14 INFO - Running main action method: create_virtualenv 09:54:14 INFO - Creating virtualenv /builds/slave/test/build/venv 09:54:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:54:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:54:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:54:14 INFO - Using real prefix '/tools/python27' 09:54:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:54:15 INFO - Installing distribute.............................................................................................................................................................................................done. 09:54:17 INFO - Installing pip.................done. 09:54:17 INFO - Return code: 0 09:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:54:18 INFO - Reading from file tmpfile_stdout 09:54:18 INFO - Output received: 09:54:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:54:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:54:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:18 INFO - 'HOME': '/Users/cltbld', 09:54:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:18 INFO - 'LOGNAME': 'cltbld', 09:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:18 INFO - 'MOZ_NO_REMOTE': '1', 09:54:18 INFO - 'NO_EM_RESTART': '1', 09:54:18 INFO - 'PAGER': '/bin/cat', 09:54:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:18 INFO - 'PWD': '/builds/slave/test', 09:54:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:18 INFO - 'SHELL': '/bin/bash', 09:54:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:18 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:18 INFO - 'USER': 'cltbld', 09:54:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:18 INFO - 'XPC_FLAGS': '0x0', 09:54:18 INFO - 'XPC_SERVICE_NAME': '0', 09:54:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:54:18 INFO - Cleaning up... 09:54:18 INFO - Return code: 0 09:54:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:54:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:18 INFO - 'HOME': '/Users/cltbld', 09:54:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:18 INFO - 'LOGNAME': 'cltbld', 09:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:18 INFO - 'MOZ_NO_REMOTE': '1', 09:54:18 INFO - 'NO_EM_RESTART': '1', 09:54:18 INFO - 'PAGER': '/bin/cat', 09:54:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:18 INFO - 'PWD': '/builds/slave/test', 09:54:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:18 INFO - 'SHELL': '/bin/bash', 09:54:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:18 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:18 INFO - 'USER': 'cltbld', 09:54:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:18 INFO - 'XPC_FLAGS': '0x0', 09:54:18 INFO - 'XPC_SERVICE_NAME': '0', 09:54:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:18 INFO - Downloading/unpacking psutil>=3.1.1 09:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:54:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:54:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:54:22 INFO - Installing collected packages: psutil 09:54:22 INFO - Running setup.py install for psutil 09:54:22 INFO - building 'psutil._psutil_osx' extension 09:54:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:54:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:54:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:54:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:54:22 INFO - building 'psutil._psutil_posix' extension 09:54:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:54:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:54:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:54:22 INFO - ^ 09:54:22 INFO - 1 warning generated. 09:54:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:54:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:54:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:54:23 INFO - Successfully installed psutil 09:54:23 INFO - Cleaning up... 09:54:23 INFO - Return code: 0 09:54:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:54: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')]} 09:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54: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')]} 09:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:54: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 mozsystemmonitor==0.3 09:54:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:23 INFO - 'HOME': '/Users/cltbld', 09:54:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:23 INFO - 'LOGNAME': 'cltbld', 09:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:23 INFO - 'MOZ_NO_REMOTE': '1', 09:54:23 INFO - 'NO_EM_RESTART': '1', 09:54:23 INFO - 'PAGER': '/bin/cat', 09:54:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:23 INFO - 'PWD': '/builds/slave/test', 09:54:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:23 INFO - 'SHELL': '/bin/bash', 09:54:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:23 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:23 INFO - 'USER': 'cltbld', 09:54:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:23 INFO - 'XPC_FLAGS': '0x0', 09:54:23 INFO - 'XPC_SERVICE_NAME': '0', 09:54:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:54:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:54:27 INFO - Installing collected packages: mozsystemmonitor 09:54:27 INFO - Running setup.py install for mozsystemmonitor 09:54:27 INFO - Successfully installed mozsystemmonitor 09:54:27 INFO - Cleaning up... 09:54:27 INFO - Return code: 0 09:54:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:27 INFO - 'HOME': '/Users/cltbld', 09:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:27 INFO - 'LOGNAME': 'cltbld', 09:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:27 INFO - 'MOZ_NO_REMOTE': '1', 09:54:27 INFO - 'NO_EM_RESTART': '1', 09:54:27 INFO - 'PAGER': '/bin/cat', 09:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:27 INFO - 'PWD': '/builds/slave/test', 09:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:27 INFO - 'SHELL': '/bin/bash', 09:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:27 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:27 INFO - 'USER': 'cltbld', 09:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:27 INFO - 'XPC_FLAGS': '0x0', 09:54:27 INFO - 'XPC_SERVICE_NAME': '0', 09:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:27 INFO - Downloading/unpacking jsonschema==2.5.1 09:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:54:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:54:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:54:30 INFO - warning: no files found matching '*.txt' 09:54:30 INFO - no previously-included directories found matching 'build' 09:54:30 INFO - no previously-included directories found matching 'dist' 09:54:30 INFO - no previously-included directories found matching '.git*' 09:54:30 INFO - Installing collected packages: jsonschema, functools32 09:54:31 INFO - Running setup.py install for functools32 09:54:31 INFO - warning: no files found matching '*.txt' 09:54:31 INFO - no previously-included directories found matching 'build' 09:54:31 INFO - no previously-included directories found matching 'dist' 09:54:31 INFO - no previously-included directories found matching '.git*' 09:54:31 INFO - Successfully installed jsonschema functools32 09:54:31 INFO - Cleaning up... 09:54:31 INFO - Return code: 0 09:54:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:31 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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:31 INFO - 'HOME': '/Users/cltbld', 09:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:31 INFO - 'LOGNAME': 'cltbld', 09:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:31 INFO - 'MOZ_NO_REMOTE': '1', 09:54:31 INFO - 'NO_EM_RESTART': '1', 09:54:31 INFO - 'PAGER': '/bin/cat', 09:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:31 INFO - 'PWD': '/builds/slave/test', 09:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:31 INFO - 'SHELL': '/bin/bash', 09:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:31 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:31 INFO - 'USER': 'cltbld', 09:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:31 INFO - 'XPC_FLAGS': '0x0', 09:54:31 INFO - 'XPC_SERVICE_NAME': '0', 09:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:54:31 INFO - Cleaning up... 09:54:31 INFO - Return code: 0 09:54:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:31 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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:31 INFO - 'HOME': '/Users/cltbld', 09:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:31 INFO - 'LOGNAME': 'cltbld', 09:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:31 INFO - 'MOZ_NO_REMOTE': '1', 09:54:31 INFO - 'NO_EM_RESTART': '1', 09:54:31 INFO - 'PAGER': '/bin/cat', 09:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:31 INFO - 'PWD': '/builds/slave/test', 09:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:31 INFO - 'SHELL': '/bin/bash', 09:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:31 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:31 INFO - 'USER': 'cltbld', 09:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:31 INFO - 'XPC_FLAGS': '0x0', 09:54:31 INFO - 'XPC_SERVICE_NAME': '0', 09:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:54:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:54:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:35 INFO - Downloading docopt-0.6.1.tar.gz 09:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:54:35 INFO - Installing collected packages: blobuploader, requests, docopt 09:54:35 INFO - Running setup.py install for blobuploader 09:54:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:54:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:54:35 INFO - Running setup.py install for requests 09:54:36 INFO - Running setup.py install for docopt 09:54:36 INFO - Successfully installed blobuploader requests docopt 09:54:36 INFO - Cleaning up... 09:54:36 INFO - Return code: 0 09:54:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:36 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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:54:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:36 INFO - 'HOME': '/Users/cltbld', 09:54:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:36 INFO - 'LOGNAME': 'cltbld', 09:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:36 INFO - 'MOZ_NO_REMOTE': '1', 09:54:36 INFO - 'NO_EM_RESTART': '1', 09:54:36 INFO - 'PAGER': '/bin/cat', 09:54:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:36 INFO - 'PWD': '/builds/slave/test', 09:54:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:36 INFO - 'SHELL': '/bin/bash', 09:54:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:36 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:36 INFO - 'USER': 'cltbld', 09:54:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:36 INFO - 'XPC_FLAGS': '0x0', 09:54:36 INFO - 'XPC_SERVICE_NAME': '0', 09:54:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-gbBbVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-AguSm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Hduw4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-cXesBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-5vHErE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-gxtHGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6GvyfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-2N5aRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-2aCToF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:54:37 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-4xkoEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-j4eZkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-1zmOZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-e47FAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-UKojkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-WjhWY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-2yy3gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:54:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:54:38 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-4DhvZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:54:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:54:38 INFO - Running setup.py install for manifestparser 09:54:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:54:38 INFO - Running setup.py install for mozcrash 09:54:39 INFO - Running setup.py install for mozdebug 09:54:39 INFO - Running setup.py install for mozdevice 09:54:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Running setup.py install for mozfile 09:54:39 INFO - Running setup.py install for mozhttpd 09:54:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Running setup.py install for mozinfo 09:54:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Running setup.py install for mozInstall 09:54:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:54:39 INFO - Running setup.py install for mozleak 09:54:40 INFO - Running setup.py install for mozlog 09:54:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:54:40 INFO - Running setup.py install for moznetwork 09:54:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:54:40 INFO - Running setup.py install for mozprocess 09:54:40 INFO - Running setup.py install for mozprofile 09:54:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:54:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:54:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:54:40 INFO - Running setup.py install for mozrunner 09:54:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:54:40 INFO - Running setup.py install for mozscreenshot 09:54:41 INFO - Running setup.py install for moztest 09:54:41 INFO - Running setup.py install for mozversion 09:54:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:54:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:54:41 INFO - Cleaning up... 09:54:41 INFO - Return code: 0 09:54:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:41 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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:41 INFO - 'HOME': '/Users/cltbld', 09:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:41 INFO - 'LOGNAME': 'cltbld', 09:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:41 INFO - 'MOZ_NO_REMOTE': '1', 09:54:41 INFO - 'NO_EM_RESTART': '1', 09:54:41 INFO - 'PAGER': '/bin/cat', 09:54:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:41 INFO - 'PWD': '/builds/slave/test', 09:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:41 INFO - 'SHELL': '/bin/bash', 09:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:41 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:41 INFO - 'USER': 'cltbld', 09:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:41 INFO - 'XPC_FLAGS': '0x0', 09:54:41 INFO - 'XPC_SERVICE_NAME': '0', 09:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:54:41 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-vAGqk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:54:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:54:41 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-hU9hVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:54:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:54:41 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-960GKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6ixf1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-3WPmZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-cJmHA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Kk85hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-GQdjMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-jyqtDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-cOiMF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-qHmoq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:54:42 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-WEaxaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:54:42 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)) 09:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:54:43 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-UtspLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:54:43 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-gO_pWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:43 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-RaZpEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:54:43 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-PRNvQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:54:43 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-eBIZ42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:54:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:54:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:46 INFO - Downloading blessings-1.6.tar.gz 09:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:54:46 INFO - Installing collected packages: blessings 09:54:46 INFO - Running setup.py install for blessings 09:54:46 INFO - Successfully installed blessings 09:54:46 INFO - Cleaning up... 09:54:46 INFO - Return code: 0 09:54:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:54:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:54:46 INFO - Reading from file tmpfile_stdout 09:54:46 INFO - Current package versions: 09:54:46 INFO - blessings == 1.6 09:54:46 INFO - blobuploader == 1.2.4 09:54:46 INFO - docopt == 0.6.1 09:54:46 INFO - functools32 == 3.2.3-2 09:54:46 INFO - jsonschema == 2.5.1 09:54:46 INFO - manifestparser == 1.1 09:54:46 INFO - mozInstall == 1.12 09:54:46 INFO - mozcrash == 1.0 09:54:46 INFO - mozdebug == 0.1 09:54:46 INFO - mozdevice == 0.49 09:54:46 INFO - mozfile == 1.2 09:54:46 INFO - mozhttpd == 0.7 09:54:46 INFO - mozinfo == 0.9 09:54:46 INFO - mozleak == 0.1 09:54:46 INFO - mozlog == 3.4 09:54:46 INFO - moznetwork == 0.27 09:54:46 INFO - mozprocess == 0.25 09:54:46 INFO - mozprofile == 0.28 09:54:46 INFO - mozrunner == 6.13 09:54:46 INFO - mozscreenshot == 0.1 09:54:46 INFO - mozsystemmonitor == 0.3 09:54:46 INFO - moztest == 0.8 09:54:46 INFO - mozversion == 1.4 09:54:46 INFO - psutil == 3.1.1 09:54:46 INFO - requests == 1.2.3 09:54:46 INFO - wsgiref == 0.1.2 09:54:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:46 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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:54:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:46 INFO - 'HOME': '/Users/cltbld', 09:54:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:46 INFO - 'LOGNAME': 'cltbld', 09:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:46 INFO - 'MOZ_NO_REMOTE': '1', 09:54:46 INFO - 'NO_EM_RESTART': '1', 09:54:46 INFO - 'PAGER': '/bin/cat', 09:54:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:46 INFO - 'PWD': '/builds/slave/test', 09:54:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:46 INFO - 'SHELL': '/bin/bash', 09:54:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:46 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:46 INFO - 'USER': 'cltbld', 09:54:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:46 INFO - 'XPC_FLAGS': '0x0', 09:54:46 INFO - 'XPC_SERVICE_NAME': '0', 09:54:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:54:47 INFO - Cleaning up... 09:54:47 INFO - Return code: 0 09:54:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:54: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')]} 09:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54: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')]} 09:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:47 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 0x108858b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb140cd3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, '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.3qH40Yjaxz/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.VryxjCoTYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:47 INFO - 'HOME': '/Users/cltbld', 09:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:47 INFO - 'LOGNAME': 'cltbld', 09:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:47 INFO - 'MOZ_NO_REMOTE': '1', 09:54:47 INFO - 'NO_EM_RESTART': '1', 09:54:47 INFO - 'PAGER': '/bin/cat', 09:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:47 INFO - 'PWD': '/builds/slave/test', 09:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:47 INFO - 'SHELL': '/bin/bash', 09:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:54:47 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:47 INFO - 'USER': 'cltbld', 09:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:47 INFO - 'XPC_FLAGS': '0x0', 09:54:47 INFO - 'XPC_SERVICE_NAME': '0', 09:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:54:47 INFO - Cleaning up... 09:54:47 INFO - Return code: 0 09:54:47 INFO - Running post-action listener: _resource_record_post_action 09:54:47 INFO - Running post-action listener: _start_resource_monitoring 09:54:47 INFO - Starting resource monitoring. 09:54:47 INFO - [mozharness: 2017-03-14 16:54:47.420394Z] Finished create-virtualenv step (success) 09:54:47 INFO - [mozharness: 2017-03-14 16:54:47.421478Z] Running install step. 09:54:47 INFO - Running pre-action listener: _resource_record_pre_action 09:54:47 INFO - Running main action method: install 09:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:54:47 INFO - Reading from file tmpfile_stdout 09:54:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:54:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:54:47 INFO - Reading from file tmpfile_stdout 09:54:47 INFO - Output received: 09:54:47 INFO - Usage: mozinstall [options] installer 09:54:47 INFO - Options: 09:54:47 INFO - -h, --help show this help message and exit 09:54:47 INFO - -d DEST, --destination=DEST 09:54:47 INFO - Directory to install application into. [default: 09:54:47 INFO - "/builds/slave/test"] 09:54:47 INFO - --app=APP Application being installed. [default: firefox] 09:54:47 INFO - mkdir: /builds/slave/test/build/application 09:54:47 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'] 09:54:47 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 09:55:07 INFO - Reading from file tmpfile_stdout 09:55:07 INFO - Output received: 09:55:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:55:07 INFO - Running post-action listener: _resource_record_post_action 09:55:07 INFO - [mozharness: 2017-03-14 16:55:07.849833Z] Finished install step (success) 09:55:07 INFO - [mozharness: 2017-03-14 16:55:07.849939Z] Running run-tests step. 09:55:07 INFO - Running pre-action listener: _resource_record_pre_action 09:55:07 INFO - Running main action method: run_tests 09:55:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:07 INFO - 2017-03-14 09:55:07.863 screenresolution[1903:18427] starting screenresolution argv=screenresolution get 09:55:07 INFO - 2017-03-14 09:55:07.865 screenresolution[1903:18427] Display 0: 1600x1200x32@60 09:55:07 INFO - 2017-03-14 09:55:07.870 screenresolution[1904:18430] starting screenresolution argv=screenresolution list 09:55:07 INFO - Available Modes on Display 0 09:55:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:08 INFO - Intel Iris: 09:55:08 INFO - Chipset Model: Intel Iris 09:55:08 INFO - Type: GPU 09:55:08 INFO - Bus: Built-In 09:55:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:08 INFO - Vendor: Intel (0x8086) 09:55:08 INFO - Device ID: 0x0a2e 09:55:08 INFO - Revision ID: 0x0009 09:55:08 INFO - Displays: 09:55:08 INFO - Display: 09:55:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:08 INFO - Display Serial Number: 1600x1200 60 09:55:08 INFO - Main Display: Yes 09:55:08 INFO - Mirror: Off 09:55:08 INFO - Online: Yes 09:55:08 INFO - Rotation: Supported 09:55:08 INFO - Return code: 0 09:55:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:55:08 INFO - Python 2.7.3 09:55:08 INFO - Return code: 0 09:55:08 INFO - grabbing minidump binary from tooltool 09:55: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')]} 09:55:08 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 0x108636d30>, '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 0x10855ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:55:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:55:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:55:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:55:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:55:08 INFO - Return code: 0 09:55:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:55:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:55:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:55:08 INFO - ENV: RUST_BACKTRACE is now 1 09:55:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:55:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:55:08 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/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:55:08 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/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:55:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3qH40Yjaxz/Render', 09:55:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:08 INFO - 'HOME': '/Users/cltbld', 09:55:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:08 INFO - 'LOGNAME': 'cltbld', 09:55:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:55:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:08 INFO - 'MOZ_NO_REMOTE': '1', 09:55:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:55:08 INFO - 'NO_EM_RESTART': '1', 09:55:08 INFO - 'PAGER': '/bin/cat', 09:55:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:08 INFO - 'PWD': '/builds/slave/test', 09:55:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:55:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:08 INFO - 'RUST_BACKTRACE': '1', 09:55:08 INFO - 'SHELL': '/bin/bash', 09:55:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VryxjCoTYy/Listeners', 09:55:08 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 09:55:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:08 INFO - 'USER': 'cltbld', 09:55:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:08 INFO - 'XPC_FLAGS': '0x0', 09:55:08 INFO - 'XPC_SERVICE_NAME': '0', 09:55:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:08 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/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:55:08 INFO - mozversion application_buildid: 20170314081436 09:55:08 INFO - mozversion application_changeset: 2baef2ffbaedb7354286726660ebd36e84b432f0 09:55:08 INFO - mozversion application_display_name: Nightly 09:55:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:55:08 INFO - mozversion application_name: Firefox 09:55:08 INFO - mozversion application_remotingname: firefox 09:55:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:55:08 INFO - mozversion application_vendor: Mozilla 09:55:08 INFO - mozversion application_version: 55.0a1 09:55:08 INFO - mozversion platform_buildid: 20170314081436 09:55:08 INFO - mozversion platform_changeset: 2baef2ffbaedb7354286726660ebd36e84b432f0 09:55:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:55:08 INFO - mozversion platform_version: 55.0a1 09:55:08 INFO - using testdate: 1489510508 09:55:08 INFO - actual date: 1489510508 09:55:08 INFO - starting webserver on 'localhost:54495' 09:55:08 INFO - SUITE-START | Running 2 tests 09:55:08 INFO - TEST-START | dromaeo_css 09:55:08 INFO - operating with platform_type : mac_ 09:55:08 INFO - Initialising browser for dromaeo_css test... 09:55:08 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpQxFulk/profile http://localhost:54495/getInfo.html) 09:55:10 INFO - PID 1914 | __metrics Screen width/height:1600/1200 09:55:11 INFO - PID 1914 | colorDepth:24 09:55:11 INFO - PID 1914 | Browser inner width/height: 1024/689 09:55:11 INFO - PID 1914 | __metrics 09:55:11 INFO - PID 1914 | 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" 09:55:11 INFO - PID 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:55:11 INFO - TEST-INFO | 1914: exit 0 09:55:11 INFO - Browser initialized. 09:55:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:55:11 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpQxFulk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:55:24 INFO - PID 1916 | RSS: Main: 233435136 09:55:24 INFO - PID 1916 | 09:58:28 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:54495/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54495/tests/dromaeo/cssquery-ext.html) 09:58:30 INFO - PID 1916 | RSS: Main: 376713216 09:58:30 INFO - PID 1916 | 10:00:14 INFO - PID 1916 | ************************* 10:00:14 INFO - PID 1916 | A coding exception was thrown and uncaught in a Task. 10:00:14 INFO - PID 1916 | 10:00:14 ERROR - PID 1916 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:00:14 INFO - PID 1916 | Full stack: 10:00:14 INFO - PID 1916 | ************************* 10:01:33 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:54495/tests/dromaeo/cssquery-ext.html (next: http://localhost:54495/tests/dromaeo/cssquery-jquery.html) 10:01:33 INFO - PID 1916 | RSS: Main: 371195904 10:01:33 INFO - PID 1916 | 10:04:36 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:54495/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54495/tests/dromaeo/cssquery-mootools.html) 10:04:36 INFO - PID 1916 | RSS: Main: 349003776 10:04:36 INFO - PID 1916 | 10:07:39 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:54495/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54495/tests/dromaeo/cssquery-prototype.html) 10:07:39 INFO - PID 1916 | RSS: Main: 359424000 10:07:39 INFO - PID 1916 | 10:10:43 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:54495/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54495/tests/dromaeo/cssquery-yui.html) 10:10:44 INFO - PID 1916 | RSS: Main: 341024768 10:10:44 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:54495/tests/dromaeo/cssquery-yui.html (next: http://localhost:54495/tests/dromaeo/cssquery-dojo.html) 10:13:47 INFO - PID 1916 | RSS: Main: 518365184 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | __start_tp_report 10:13:47 INFO - PID 1916 | _x_x_mozilla_page_load 10:13:47 INFO - PID 1916 | _x_x_mozilla_page_load_details 10:13:47 INFO - PID 1916 | |i|pagename|runs| 10:13:47 INFO - PID 1916 | |0;dojo.html;2843.445528122016;3283.1463819406945;3309.5697559317287;3315.4198015347306;3331.783434076785;5568.136938774496;5639.577031722617;5753.971230143854;5769.04810706234;5802.419758024195;5564.221009058725;5665.3201615806165;5667.178259152421;5674.914876276859;5675.914861277084;5131.33292671953;5148.5881129509735;5160.741962901859;5173.560247378978;5174.534291913727;5255.658382205144;5266.973665131688;5267.0256002639635;5269.6311258211945;5323.387810401795;5246.632735708483;5270.341207349082;5280.6039547034125;5282.9207561886415;5283.788648454077;5257.737113144328;5271.156614941629;5271.657342272759;5277.472252774711;5290.867728306785;5109.489051094898;5217.8173763918085;5230.790768369261;5231.450697676765;5240.3973543042275;5818.069108942549;5836.9124463133085;5842.5618078644275;5846.50304724099;5866.911996320058;6175.567710260284;6189.597676151036;6221.004639257697;6247.375262473762;6257.18656574647;6143.034229046707;6152.507799376084;6195.690215489208;6235.906461403082;6236.96881515594;5946.989011867993;5969.8806023879715;5972.850678733066;5980.342162362137;6011.699415029275;6276.184096067482;6279.612614849764;6323.430891219812;6333.334999825044;6349.52378571609;6060.424259695322;6109.75560977556;6128.601640893327;6198.907016394758;6205.193324867739;6129.14485165794;6164.845878852975;6171.938280617226;6176.227971503562;6177.444030037318;6011.6693581853415;6150.04674275488;6158.907616385758;6164.47601953839;6165.0444181152;5328.946710532845;6021.277446706424;6115.270764408881;6180;6186.319504854462;4025.3961905714377;4980.053789780002;4981.800727970841;5050;5086.0845047891735;4382.364557139197;4409.757463339483;4421.867343979686;4461;4461.531539188339;5010.248462730619;5713.371529131792;5739.540836733051;5757.769689212469;5799.797007104731;6253.218347706536;6261.436470717566;6262.5616206866;6265.091561723616;6290.402411770874;5567.693776842231;5623.718814059281;5635.605507614509;5639.746211420496;5644.068728659808;5412.323459567554;5419.349678038582;5428.37573679032;5431.864203394946;5447.53695935842;6178.320384757583;6278.089676996809;6283.2460104787715;6290.4653104485715;6295.213098362705;6186.381361863778;6262.5929314594405;6267.185265915402;6268.310485846644;6281.937180628135;6269.717862696099;6323.525735569942;6328.557001009885;6331.556791024584;6365.363463653597;6111.083337499411;6117.888303246575;6294.961331380271;6295.496360291074;6307.085472606538;6194;6197.938020619736;6257.749690012349;6293.716782744879;6309.652969086561;6109.602875813058;6118.235220597426;6142.697436716463;6193.473554747897;6201;6164.013757798731;6173.59871608344;6179.104029915727;6203;6206.348333424925;5792.623479473821;5858.091995740667;5871.089981052944;6001.3698561652145;6019.277686677627;5714.02861513552;5773.8556536086935;6147.047207682816;6221.471174949999;6248.0003199487865;6003.099535069775;6069.605475644069;6086.269647642134;6088.543359248163;6090.573659843769 10:13:47 INFO - PID 1916 | |1;ext.html;735.7277807211333;749.4678778067572;768.55039801716;789.3014682006425;853.3429259470208;27910.86044569777;28326.71673283264;28337;28366.858165709167;36841.07897302562;38959.22081558367;39042;39153.80423097888;39176;39259;9102.954485227598;9312.115349041831;9337.253019758436;9341.95329023354;9349.158575728183;7234.674439650228;7409.184989651134;9037.819243615124;9237.353385263033;10768.461576921161;11124.276922000085;11153.665390038312;11161.888381116207;11179.66461006171;11193.27243729155;10983.780324393507;11122.388268645222;11133.888661113408;11134;11136.053435457996;7081.89377159343;7238.674259658328;10445;10870.130389568816;10976.231663783537;38389.23221535581;38744;38789.98755192921;38837.80581097105;38875;3499.4750787382095;3833.5208098987628;3861.575226725058;3872.1674839909556;3990.700697447703;28116.437671246462;28328.00851970171;28628.284292892637;29363.41273174546;29413.852930735422;3085.922851928697;3087.783855130142;3110.751139908802;3207.0378886334006;3215.823129727864;335574;336714;336773;337177;337234;50882.236766448535;50889.982200355786;50937.72655683637;50947.7452612742;51026.74486627543;53366.466335336925;53400;53543.46456535385;53609.65975850635;53612.73193634007;14317.140971541741;14628.049176803474;14651.34068966899;14652.413903443956;14670.486532971508;11889.643310700692;11901.76196476083;11919.3444360561;11929.224600400947;11936.582219622445;10769.9461502692;10919.617813376493;10949.343039417501;12229.449674764657;12912.418941147484;5285.814996475182;5379.327584051994;5398;5406.351237851404;5416.183584536019;946.4431010987025;1026.9473789365811;1089.7711480589007;1090.9853835932659;1097.9231453798157;24715.62926556103;24724.629130563055;24822.5035499289;25051.373715657253;25912.870435647703;971.5530855806267;1172.9589464368705;1181.2381014245764;1224.8468941382328;1226.1171956191529;12666;13026.80459793104;13165.077416682907;13276.867231327757;13291.335433228493;326941;413389;414250;414400;414920;53445.7327713359;53454.19818702723;53548;53619.463805362226;53660.92678146415;1349.6693310139078;1414.1161773891317;1415.0660564027899;1425.921574313402;1443.992780036093;23693.526129477657;24598.016079356625;24649.38376540529;24967.750322496544;25174.74825251724;45409;45509;45546.31680524861;45570;45602.77198614118;399707;399806;400255;401966;402043;33105.83447082749;33426.83286583552;34198.487022695175;34369.82815085909;34420.82789586036;43240.78379608082;43729.781351094316;43810;44593.55406445894;45820;35182.47226291659;35449.46825797563;35732.64267357293;36707.81646091752;36900.630993689716;34974.82512587421;35071.824640876635;36058.63941360553;36278.45582316215;36390.81804591066;35391.82304088463;35678.64321356857;35726;36167.63832361748;36257;29913.252168695955;31550.526742099348;31625.5256171153;31701.524477133324;31802.681973179973;21699.674504882754;23654.763452365256;23678.57928524294;24260;24454 10:13:47 INFO - PID 1916 | |2;jquery.html;356.3389911713771;383.21058619244343;400.88775142959963;419.3856000958595;435.14928315143896;13900.721985560296;14114.858851411484;18265.54336141597;20573.691394629055;21437.57124857502;20966.89516552419;22048;22339.6649050264;22655.320340389775;23011.654825177637;4141.875743727678;6599.67001649918;6824.7952561423235;6841.692123854414;6911.447084233275;3232.773705840592;3272.181954511372;3275.16483296759;3277.049655599885;6797.52417330787;3266.4447044002577;3275.5086736989524;3276.393867134576;3280.1963518937773;5192.532672059514;4375.956240437603;7681.039870016248;7703.422243331773;7710.537367757953;7725.806854828618;7172.2469140739995;7427.071616047994;7470.3276705096805;7514.4364172687265;7517.248275172494;26759.795809188632;27189.86405067982;27414.862925685444;27530.724692753018;28067.29831754202;2835.277004363895;2903.2886942698965;2905.8547072646284;2908.447394994965;2916.4458752836895;15904.363825447084;16343.591410214722;16476.917615411847;16711.91644041772;17256.050917199547;1962.3249522597027;1969.8227159555709;1984.6130004649099;1995.3814317561603;2008.0562135796151;149080.50919490884;154233;154997;155459;158455.2077239618;20017.399478015683;21342.395921835236;21451.57096858054;21614.891925540585;21742.891285543472;35668.28663426717;35677;35767.821160894026;35782.64217357845;35823.64176358255;8801.955990220007;10189.03204195615;10206.591736330613;10370.481475926324;10425.270231083752;8239.176082391712;8308.792280193044;8326.04250511199;8526.82946341082;8532.360072994426;8586.613602387908;9285.58214880332;9840.557174927146;9936.801263974825;9953.452560109119;3869.349495595526;4044.656204222674;4048.2308361411237;4054.4526488923616;4054.459305463905;819.7950512371907;838.8909441772653;898.7528429681889;900.59923334117;1049.2078480747139;22313.281429585306;23240.65139022916;25326;25869.870650647004;26073.73926260751;874.4709450782312;905.800723840754;915.9084091590921;939.9718008459757;949.9762505937407;6824.829379265558;6853.725850965906;6931.653417329113;7072.717091316393;7073.328033836778;557957;586458;670866;699010;705693;133093.66906330813;140468;155690;163049.36950630342;165538;1313.1005261395974;1327.8672132786644;1365.2627581106092;1372.5882235329361;1376.9311534423439;17616.647667046844;20822.1671133153;21591.568168636855;21906.12375504962;22308.77691223067;61808.69095654673;62063.37936620757;64044;64898.67550662217;65227.34772652403;89490.55254723685;89581;91246.17507649677;91501;92212.53893730757;9987.500624968869;11186.664400068012;11724.062075034148;11935.940320298343;11986.520539178335;13224.933875330562;13637.727245454837;13653.317334133451;13969.650758731113;14080.507182248572;11655.125865560287;11683.240589361665;11822.645320640395;12007;12030.218035827644;11580.478878450322;11596;11683.357415342238;11783.52865885355;12049.156559040783;10063.647772327933;11778.587749428729;12033.63899083029;12099.697507562381;12228.205166664138;11895.643130706092;11939.582114625946;12185.781421857744;12194.329311887932;12294.446749896455;11577.826332604849;11589.710257243636;11838.230515016496;11928.880711192776;12010.879891201326 10:13:47 INFO - PID 1916 | |3;mootools.html;1454.8433994974;1497.8726808221302;1565.686862627474;1584.5563242292158;1599.77603135561;10350.223733220011;10855.51150198241;10873.782524349508;10888.183386246043;10893.618723344665;9747.756306092351;10102.696919092421;10128.341657792238;10145.644902428416;10148.69553913382;2298.436882963672;2332.3586013846243;2334.5914464968646;2334.871582062986;2335.159342636658;2246.8314876384256;2277.6925115109498;2281.7033785607846;2286.542691461706;2289.4161988692945;2252.3693365857503;2262.016023029979;2267.8979445924974;2269.0810221860092;2278.5670722562745;2198.955496139337;2212.048819007826;2221.6334304839684;2222.9333120006427;2225.7551669316363;1961.2939341836927;2250.561140577588;2338.38032921276;2342.8711420871846;2343.285297984114;10896;11015.339079655167;11022.834657480144;11030.944845275722;11037.83443248352;5499.175123731432;5736.139579063132;5741.598088133832;5745.368009518949;5753.137029445575;3259.445894197992;3345.2305969627123;3569.447290428646;3572.8570857165682;3577.9802756214613;1173.2901594535354;1209.3832145605716;1724.1120822776281;1744.97382539262;1827.826356496144;12667.493300267979;12856.935715321364;13013.86986130136;14942.477013304482;15094.924525377302;3765.359888818907;4324.697271190987;4682.789274482657;4683.039976804746;4685.156671799109;11393.601223957121;11393.829092563617;11394;11400.372979486043;11420;5649.745761440745;5655.773769049274;5655.9253741788925;5659.858503537363;5664.68844213564;4702.153612349742;4998.625103117208;5008.549230569193;5168.3797944246935;5196.168613021899;3407.4496104272994;3500.8774692885627;3535.6641119093647;3558.5729712434154;3590.461430785403;1000.3247807729754;1002.6891663584312;1015.1777060580953;1018.5620183321095;1024.6413755185624;734.6620554544862;892.1390857822103;961.6778379243032;966.9129778319985;973.6981535723949;6477.77327793525;6495.545311828127;6567.158222417276;6729.966350168218;6767.2894346093935;958.7057472412328;967.9932869815314;991.8958509356557;1015.1878497202209;1044.9059584637419;3773.660370566662;4189.7695626740215;4387.5393083726385;4404.515503294635;4409.470863496401;13225.206487610774;13717.725645487035;13722;13738.244396558286;13760.587182384545;13183.67040823987;13302.600921972356;13449.731005379837;13562.186268823902;13606.591802245948;9468.763280918032;9753.02469753019;9861.211103111735;9863.358881672668;9926.851097233548;6127.295361033538;6433.806985790434;6654.268030516542;6927.93072069293;6946.548474349152;11818.349990750592;12042.096842736655;12048;12192.695182620506;12230.755384892074;11973.341466219441;12098.09264305163;12383.257004579755;12462.376881155724;12507.687307817378;9112.635494580143;9223.815523689354;9337.813243735223;9366.06339366058;9373.85939210926;10195;10206.23453240995;10241.48792560354;10303.021212985055;10379.948100259451;9085.863712044193;9086.909130908607;9094.727158185266;9291.117343852324;9312.534373281173;8157.469764465291;8919.687810926845;9007.144321289466;9065.138811812867;9138.13187747163;7281.817954551178;7472.925270747441;7502.98709674187;7543.886841697269;7573.166951635426;5810.5061069809535;5847.532197424281;5987;6069.666168360783;6080.331163572092;5280.551153152025;5859.355470898113;5925.9407405925385;6172.19761431029;6224.595401298901 10:13:47 INFO - PID 1916 | |4;prototype.html;328.45804422702554;365.9908502287444;371.3649659082968;372.99067523311913;384.8768394113884;5417.431169727181;5597.244372009777;5692.772289108436;5778.971105144468;5797.130430435432;5387.838364849051;5519.751611177497;5524.591229236549;5540.889182216353;5653.293338332708;4272.764997925113;4743.814046488369;4861.805527778884;4868.853934381974;4902.142125128106;4545.863624091266;4606.953930460686;4867.537583929521;4869.293952376903;4913.26301054841;4193.1404062167185;4296.699231053827;4754.858833879861;4755.904881902377;4841.298011788289;4556.329761993949;4751.691140075884;4760.381150450419;4844.231855372799;4845.152098382769;4602.608778253819;4625.329327247564;4626.329182268552;4643.8374656886845;4657.3246879202325;4485.730856148642;4685.578297953167;5143.408508021588;5580.804671836507;5612.522935550483;4678.321643361727;4924;5694.202811606378;5736;5757.942420575782;6112.135798581431;6117.256581874189;6157.2919114301985;6181.536384771161;6184.6907654617535;5083.694978301277;5281;5286.180642000496;5298.390685071227;5315.388730295988;5920.289565252154;6402.391772781578;6431.324710905381;6476.255230648441;6488.772892948724;5363.678179309254;5404.83785486436;5416.485433883775;5419.945800541944;5421.349438067457;4870.6620822004925;5164.8967020659375;5276.469823751146;5464.972675136599;5516.503514683698;5229.4770522948165;5267.787529237729;5634.774609015675;5750.568707346882;5752.453516915886;5528.474794894479;5686.744096515667;5760.337561180518;6150.569460137837;6175.166352542439;1553.5572361876812;1799.8650101242197;1889.7448844406103;1905.8570607204515;1908.799576044523;1463.9121652700871;1505.284989629917;1523.7276873810272;1528.388644542192;1543.2515230113386;5689.374168841424;5705.659170095024;5711.628744131618;5714.771409143587;5769.221155144086;5485.985092757853;5773.797917072882;6040.728167232397;6281.654509001957;6287.402696743802;4523.773811309421;5272.235525848807;5522.502974732293;5693.832764283312;5707.71461426927;2933;3292.6213485449002;3311.602607687093;3320.269540701042;3327.2513684421297;6198.241782406786;6494.253160886464;6505.284418713938;6512;6541.967290163614;6252.562320637512;6346.555741098171;6393.328700486382;6396.680165991682;6477.481801455872;5365.302510673588;5837.781642975642;6090.147379366892;6408.775692850728;6538.411542961157;5042.193249080129;5904.291485021825;5980.1328807321825;6317.5893566918;6334.714937827716;5515.310586176728;6236.34518375564;6250.031245156826;6258.624482531062;6286.214223222098;6374.553781235454;6469.870602587827;6494.870102598016;6542.2149342077355;6556.737730490727;4347.021920067959;4883.389576302962;4901.264810278487;4922.409310882574;4945.332380128637;4777.665563410528;5666.773329066791;6153.446189842935;6256.468200202851;6302.117703521419;5652.915206271827;5918.467337939606;6093.177421048279;6098.146259523759;6167.599106058092;5826.592138550261;6070.605410648293;6089.177960975212;6089.969550152221;6097.695115244132;5031.195008798574;5258.687027172857;5449.809256675997;5952.244087993966;5963.880722385441;4963.975180124221;5093.719845408538;5453.836384908459;6024.072296864962;6134.969325153345;5217;5709.486146246858;5898.64608123514;5941.7623295067715;5948.762049517971 10:13:47 INFO - PID 1916 | |5;yui.html;712.7113519024797;723.0997408226758;756.6935391166576;778.5095389904361;789.63676708714;14962.77555836662;15692;15779.368825246976;15783;15797.210139493023;13944.790828137586;14679;14942;14971.550853474386;15255.618609534768;920.6777627830256;954.3558098283665;957.5786653872274;958.108958668438;961.4808003678005;785.2461636828651;965.4448692002092;968.5060619084283;971.2763990826844;980.8038392321529;1014.5789497358625;1015.8679371681671;1019.0115587879744;1020.2959957629249;1020.6784862768204;1009.1018993096077;1011.8735158105237;1012.7873146639215;1013.5287091502449;1014.1734486393566;753.6533194730431;753.8416932444138;754.1026178847209;754.909410870699;754.9245075492407;15829.762553561706;15876.920615396964;16271.430499932563;16370.099644519547;16373.91813040939;10710.518026688818;12678.302693351861;12678.619641410773;13097.738045239137;13396.306569707835;10738.731531711692;11023.44882755867;11172.553097876074;11301.547938082467;11351.829722554086;3138.7601897250456;3704.685101766353;3720.274546463441;3862.382018876994;3899.8245078971513;92645.53677231638;106662.93337066607;107025.46487267592;107468.46265768699;110728.4463577677;9514.38156519824;11360.886391135982;11477.540898363972;11521.942390288163;11751.590923860587;22477.4380640482;23262.53474930492;24922.252332430056;24939.376515586893;24969.87515062413;7909.7231596893835;7999.28006479408;8095.959520202361;8232.753017409488;8302.252797248277;3788.981055094707;3829.6744776694295;3880.8059597020037;3910;3914.5693973662874;3086.830224337638;3482.6865582097735;3550.2366991097037;3580.588232353313;3582.9104272393397;641.0608458608161;644.8774732800753;645.6771614192903;663.0385940386459;664.2493981800534;659.2846761263986;660.2242365220847;666.4302021771413;688.3151264491862;690.3545185251805;9431.811363772686;9698.660546880967;9876.65431709901;10610.840837387444;10903.618373357052;540.8689762336383;543.665645627936;580.9390014048548;629.2260519560965;703.1210986267166;4683.929741053887;4903.53416425439;4937;4969.701817890938;4997.550220480174;74644;76643.61678191685;77886;79144.20855791484;81320;55105.72447137738;70517;77702;79440;81648.367032659;53108.46891531035;56200;74119.62940185373;74244.62877685577;74488.62755686295;10536.434726303974;10635.840462392916;10697.572097116028;10851.728706782078;10852.565897364017;40691.59308406878;43451;46230;46786.532134678215;47681.523184769096;61139;62046;62062.689686551275;62507.68684661855;63125;12866.935665321613;13134.737305254033;13171.078024538574;13485.190888546718;13788;11703.88296117062;14071.859281407464;14133.787993179905;14146.575602731935;14173.929130354281;8797.868031979397;12163.331016794162;12655.557055503014;13092;13685.178889266675;13317.334133293103;13488.662783430493;13609.834844157456;13776.862231377558;13838.792418113535;12078;12743.426545805276;13199.934000329937;13263.403146858222;13457.596272111852;8885.099464137897;10906.563737450731;10916.781664366827;11073.213056876975;11560.595379162025;8693.869591956;9431.245500360092;9894.752631184278;11078;11177.497012634289 10:13:47 INFO - PID 1916 | __end_tp_report 10:13:47 INFO - PID 1916 | __start_cc_report 10:13:47 INFO - PID 1916 | _x_x_mozilla_cycle_collect,1353 10:13:47 INFO - PID 1916 | __end_cc_report 10:13:47 INFO - PID 1916 | __startTimestamp1489511627740__endTimestamp 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | ------- Summary: start ------- 10:13:47 INFO - PID 1916 | Number of tests: 6 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | [#0] dojo.html Cycles:165 Average:5743.29 Median:6011.67 stddev:663.59 (11.0%) stddev-sans-first:625.42 10:13:47 INFO - PID 1916 | Values: 2843.4 3283.1 3309.6 3315.4 3331.8 5568.1 5639.6 5754.0 5769.0 5802.4 5564.2 5665.3 5667.2 5674.9 5675.9 5131.3 5148.6 5160.7 5173.6 5174.5 5255.7 5267.0 5267.0 5269.6 5323.4 5246.6 5270.3 5280.6 5282.9 5283.8 5257.7 5271.2 5271.7 5277.5 5290.9 5109.5 5217.8 5230.8 5231.5 5240.4 5818.1 5836.9 5842.6 5846.5 5866.9 6175.6 6189.6 6221.0 6247.4 6257.2 6143.0 6152.5 6195.7 6235.9 6237.0 5947.0 5969.9 5972.9 5980.3 6011.7 6276.2 6279.6 6323.4 6333.3 6349.5 6060.4 6109.8 6128.6 6198.9 6205.2 6129.1 6164.8 6171.9 6176.2 6177.4 6011.7 6150.0 6158.9 6164.5 6165.0 5328.9 6021.3 6115.3 6180.0 6186.3 4025.4 4980.1 4981.8 5050.0 5086.1 4382.4 4409.8 4421.9 4461.0 4461.5 5010.2 5713.4 5739.5 5757.8 5799.8 6253.2 6261.4 6262.6 6265.1 6290.4 5567.7 5623.7 5635.6 5639.7 5644.1 5412.3 5419.3 5428.4 5431.9 5447.5 6178.3 6278.1 6283.2 6290.5 6295.2 6186.4 6262.6 6267.2 6268.3 6281.9 6269.7 6323.5 6328.6 6331.6 6365.4 6111.1 6117.9 6295.0 6295.5 6307.1 6194.0 6197.9 6257.7 6293.7 6309.7 6109.6 6118.2 6142.7 6193.5 6201.0 6164.0 6173.6 6179.1 6203.0 6206.3 5792.6 5858.1 5871.1 6001.4 6019.3 5714.0 5773.9 6147.0 6221.5 6248.0 6003.1 6069.6 6086.3 6088.5 6090.6 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | [#1] ext.html Cycles:180 Average:52279.55 Median:34395.33 stddev:100328.19 (291.7%) stddev-sans-first:100534.99 10:13:47 INFO - PID 1916 | Values: 735.7 749.5 768.6 789.3 853.3 27910.9 28326.7 28337.0 28366.9 36841.1 38959.2 39042.0 39153.8 39176.0 39259.0 9103.0 9312.1 9337.3 9342.0 9349.2 7234.7 7409.2 9037.8 9237.4 10768.5 11124.3 11153.7 11161.9 11179.7 11193.3 10983.8 11122.4 11133.9 11134.0 11136.1 7081.9 7238.7 10445.0 10870.1 10976.2 38389.2 38744.0 38790.0 38837.8 38875.0 3499.5 3833.5 3861.6 3872.2 3990.7 28116.4 28328.0 28628.3 29363.4 29413.9 3085.9 3087.8 3110.8 3207.0 3215.8 335574.0 336714.0 336773.0 337177.0 337234.0 50882.2 50890.0 50937.7 50947.7 51026.7 53366.5 53400.0 53543.5 53609.7 53612.7 14317.1 14628.0 14651.3 14652.4 14670.5 11889.6 11901.8 11919.3 11929.2 11936.6 10769.9 10919.6 10949.3 12229.4 12912.4 5285.8 5379.3 5398.0 5406.4 5416.2 946.4 1026.9 1089.8 1091.0 1097.9 24715.6 24724.6 24822.5 25051.4 25912.9 971.6 1173.0 1181.2 1224.8 1226.1 12666.0 13026.8 13165.1 13276.9 13291.3 326941.0 413389.0 414250.0 414400.0 414920.0 53445.7 53454.2 53548.0 53619.5 53660.9 1349.7 1414.1 1415.1 1425.9 1444.0 23693.5 24598.0 24649.4 24967.8 25174.7 45409.0 45509.0 45546.3 45570.0 45602.8 399707.0 399806.0 400255.0 401966.0 402043.0 33105.8 33426.8 34198.5 34369.8 34420.8 43240.8 43729.8 43810.0 44593.6 45820.0 35182.5 35449.5 35732.6 36707.8 36900.6 34974.8 35071.8 36058.6 36278.5 36390.8 35391.8 35678.6 35726.0 36167.6 36257.0 29913.3 31550.5 31625.5 31701.5 31802.7 21699.7 23654.8 23678.6 24260.0 24454.0 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | [#2] jquery.html Cycles:180 Average:40290.77 Median:27302.36 stddev:109006.02 (399.3%) stddev-sans-first:109270.57 10:13:47 INFO - PID 1916 | Values: 356.3 383.2 400.9 419.4 435.1 13900.7 14114.9 18265.5 20573.7 21437.6 20966.9 22048.0 22339.7 22655.3 23011.7 4141.9 6599.7 6824.8 6841.7 6911.4 3232.8 3272.2 3275.2 3277.0 6797.5 3266.4 3275.5 3276.4 3280.2 5192.5 4376.0 7681.0 7703.4 7710.5 7725.8 7172.2 7427.1 7470.3 7514.4 7517.2 26759.8 27189.9 27414.9 27530.7 28067.3 2835.3 2903.3 2905.9 2908.4 2916.4 15904.4 16343.6 16476.9 16711.9 17256.1 1962.3 1969.8 1984.6 1995.4 2008.1 149080.5 154233.0 154997.0 155459.0 158455.2 20017.4 21342.4 21451.6 21614.9 21742.9 35668.3 35677.0 35767.8 35782.6 35823.6 8802.0 10189.0 10206.6 10370.5 10425.3 8239.2 8308.8 8326.0 8526.8 8532.4 8586.6 9285.6 9840.6 9936.8 9953.5 3869.3 4044.7 4048.2 4054.5 4054.5 819.8 838.9 898.8 900.6 1049.2 22313.3 23240.7 25326.0 25869.9 26073.7 874.5 905.8 915.9 940.0 950.0 6824.8 6853.7 6931.7 7072.7 7073.3 557957.0 586458.0 670866.0 699010.0 705693.0 133093.7 140468.0 155690.0 163049.4 165538.0 1313.1 1327.9 1365.3 1372.6 1376.9 17616.6 20822.2 21591.6 21906.1 22308.8 61808.7 62063.4 64044.0 64898.7 65227.3 89490.6 89581.0 91246.2 91501.0 92212.5 9987.5 11186.7 11724.1 11935.9 11986.5 13224.9 13637.7 13653.3 13969.7 14080.5 11655.1 11683.2 11822.6 12007.0 12030.2 11580.5 11596.0 11683.4 11783.5 12049.2 10063.6 11778.6 12033.6 12099.7 12228.2 11895.6 11939.6 12185.8 12194.3 12294.4 11577.8 11589.7 11838.2 11928.9 12010.9 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | [#3] mootools.html Cycles:180 Average:6599.71 Median:2801.37 stddev:4096.85 (146.2%) stddev-sans-first:4090.10 10:13:47 INFO - PID 1916 | Values: 1454.8 1497.9 1565.7 1584.6 1599.8 10350.2 10855.5 10873.8 10888.2 10893.6 9747.8 10102.7 10128.3 10145.6 10148.7 2298.4 2332.4 2334.6 2334.9 2335.2 2246.8 2277.7 2281.7 2286.5 2289.4 2252.4 2262.0 2267.9 2269.1 2278.6 2199.0 2212.0 2221.6 2222.9 2225.8 1961.3 2250.6 2338.4 2342.9 2343.3 10896.0 11015.3 11022.8 11030.9 11037.8 5499.2 5736.1 5741.6 5745.4 5753.1 3259.4 3345.2 3569.4 3572.9 3578.0 1173.3 1209.4 1724.1 1745.0 1827.8 12667.5 12856.9 13013.9 14942.5 15094.9 3765.4 4324.7 4682.8 4683.0 4685.2 11393.6 11393.8 11394.0 11400.4 11420.0 5649.7 5655.8 5655.9 5659.9 5664.7 4702.2 4998.6 5008.5 5168.4 5196.2 3407.4 3500.9 3535.7 3558.6 3590.5 1000.3 1002.7 1015.2 1018.6 1024.6 734.7 892.1 961.7 966.9 973.7 6477.8 6495.5 6567.2 6730.0 6767.3 958.7 968.0 991.9 1015.2 1044.9 3773.7 4189.8 4387.5 4404.5 4409.5 13225.2 13717.7 13722.0 13738.2 13760.6 13183.7 13302.6 13449.7 13562.2 13606.6 9468.8 9753.0 9861.2 9863.4 9926.9 6127.3 6433.8 6654.3 6927.9 6946.5 11818.3 12042.1 12048.0 12192.7 12230.8 11973.3 12098.1 12383.3 12462.4 12507.7 9112.6 9223.8 9337.8 9366.1 9373.9 10195.0 10206.2 10241.5 10303.0 10379.9 9085.9 9086.9 9094.7 9291.1 9312.5 8157.5 8919.7 9007.1 9065.1 9138.1 7281.8 7472.9 7503.0 7543.9 7573.2 5810.5 5847.5 5987.0 6069.7 6080.3 5280.6 5859.4 5925.9 6172.2 6224.6 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | [#4] prototype.html Cycles:180 Average:5158.02 Median:5526.53 stddev:1390.50 (25.2%) stddev-sans-first:1346.32 10:13:47 INFO - PID 1916 | Values: 328.5 366.0 371.4 373.0 384.9 5417.4 5597.2 5692.8 5779.0 5797.1 5387.8 5519.8 5524.6 5540.9 5653.3 4272.8 4743.8 4861.8 4868.9 4902.1 4545.9 4607.0 4867.5 4869.3 4913.3 4193.1 4296.7 4754.9 4755.9 4841.3 4556.3 4751.7 4760.4 4844.2 4845.2 4602.6 4625.3 4626.3 4643.8 4657.3 4485.7 4685.6 5143.4 5580.8 5612.5 4678.3 4924.0 5694.2 5736.0 5757.9 6112.1 6117.3 6157.3 6181.5 6184.7 5083.7 5281.0 5286.2 5298.4 5315.4 5920.3 6402.4 6431.3 6476.3 6488.8 5363.7 5404.8 5416.5 5419.9 5421.3 4870.7 5164.9 5276.5 5465.0 5516.5 5229.5 5267.8 5634.8 5750.6 5752.5 5528.5 5686.7 5760.3 6150.6 6175.2 1553.6 1799.9 1889.7 1905.9 1908.8 1463.9 1505.3 1523.7 1528.4 1543.3 5689.4 5705.7 5711.6 5714.8 5769.2 5486.0 5773.8 6040.7 6281.7 6287.4 4523.8 5272.2 5522.5 5693.8 5707.7 2933.0 3292.6 3311.6 3320.3 3327.3 6198.2 6494.3 6505.3 6512.0 6542.0 6252.6 6346.6 6393.3 6396.7 6477.5 5365.3 5837.8 6090.1 6408.8 6538.4 5042.2 5904.3 5980.1 6317.6 6334.7 5515.3 6236.3 6250.0 6258.6 6286.2 6374.6 6469.9 6494.9 6542.2 6556.7 4347.0 4883.4 4901.3 4922.4 4945.3 4777.7 5666.8 6153.4 6256.5 6302.1 5652.9 5918.5 6093.2 6098.1 6167.6 5826.6 6070.6 6089.2 6090.0 6097.7 5031.2 5258.7 5449.8 5952.2 5963.9 4964.0 5093.7 5453.8 6024.1 6135.0 5217.0 5709.5 5898.6 5941.8 5948.8 10:13:47 INFO - PID 1916 | 10:13:47 INFO - PID 1916 | [#5] yui.html Cycles:180 Average:18697.73 Median:3643.80 stddev:25615.32 (703.0%) stddev-sans-first:25651.58 10:13:47 INFO - PID 1916 | Values: 712.7 723.1 756.7 778.5 789.6 14962.8 15692.0 15779.4 15783.0 15797.2 13944.8 14679.0 14942.0 14971.6 15255.6 920.7 954.4 957.6 958.1 961.5 785.2 965.4 968.5 971.3 980.8 1014.6 1015.9 1019.0 1020.3 1020.7 1009.1 1011.9 1012.8 1013.5 1014.2 753.7 753.8 754.1 754.9 754.9 15829.8 15876.9 16271.4 16370.1 16373.9 10710.5 12678.3 12678.6 13097.7 13396.3 10738.7 11023.4 11172.6 11301.5 11351.8 3138.8 3704.7 3720.3 3862.4 3899.8 92645.5 106662.9 107025.5 107468.5 110728.4 9514.4 11360.9 11477.5 11521.9 11751.6 22477.4 23262.5 24922.3 24939.4 24969.9 7909.7 7999.3 8096.0 8232.8 8302.3 3789.0 3829.7 3880.8 3910.0 3914.6 3086.8 3482.7 3550.2 3580.6 3582.9 641.1 644.9 645.7 663.0 664.2 659.3 660.2 666.4 688.3 690.4 9431.8 9698.7 9876.7 10610.8 10903.6 540.9 543.7 580.9 629.2 703.1 4683.9 4903.5 4937.0 4969.7 4997.6 74644.0 76643.6 77886.0 79144.2 81320.0 55105.7 70517.0 77702.0 79440.0 81648.4 53108.5 56200.0 74119.6 74244.6 74488.6 10536.4 10635.8 10697.6 10851.7 10852.6 40691.6 43451.0 46230.0 46786.5 47681.5 61139.0 62046.0 62062.7 62507.7 63125.0 12866.9 13134.7 13171.1 13485.2 13788.0 11703.9 14071.9 14133.8 14146.6 14173.9 8797.9 12163.3 12655.6 13092.0 13685.2 13317.3 13488.7 13609.8 13776.9 13838.8 12078.0 12743.4 13199.9 13263.4 13457.6 8885.1 10906.6 10916.8 11073.2 11560.6 8693.9 9431.2 9894.8 11078.0 11177.5 10:13:47 INFO - PID 1916 | -------- Summary: end -------- 10:13:47 INFO - PID 1916 | 10:13:48 INFO - TEST-INFO | 1916: exit 0 10:13:48 INFO - TEST-OK | dromaeo_css | took 1120003ms 10:13:48 INFO - TEST-START | kraken 10:13:48 INFO - operating with platform_type : mac_ 10:13:48 INFO - Initialising browser for kraken test... 10:13:48 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpBEY0Mo/profile http://localhost:54495/getInfo.html) 10:13:50 INFO - PID 1919 | __metrics Screen width/height:1600/1200 10:13:50 INFO - PID 1919 | colorDepth:24 10:13:50 INFO - PID 1919 | Browser inner width/height: 1024/689 10:13:50 INFO - PID 1919 | __metrics 10:13:51 INFO - PID 1919 | 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" 10:13:51 INFO - PID 1919 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:13:51 INFO - TEST-INFO | 1919: exit 0 10:13:51 INFO - Browser initialized. 10:13:51 INFO - Running cycle 1/1 for kraken test... 10:13:51 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpBEY0Mo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:03 INFO - PID 1920 | RSS: Main: 233701376 10:14:03 INFO - PID 1920 | 10:14:06 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54495/tests/kraken/driver.html?testName=audio-beat-detection) 10:14:07 INFO - PID 1920 | RSS: Main: 284434432 10:14:07 INFO - PID 1920 | 10:14:10 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54495/tests/kraken/driver.html?testName=audio-dft) 10:14:11 INFO - PID 1920 | RSS: Main: 285949952 10:14:11 INFO - PID 1920 | 10:14:14 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54495/tests/kraken/driver.html?testName=audio-fft) 10:14:14 INFO - PID 1920 | RSS: Main: 378159104 10:14:14 INFO - PID 1920 | 10:14:17 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54495/tests/kraken/driver.html?testName=audio-oscillator) 10:14:17 INFO - PID 1920 | RSS: Main: 282214400 10:14:17 INFO - PID 1920 | 10:14:19 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54495/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:14:20 INFO - PID 1920 | RSS: Main: 284852224 10:14:20 INFO - PID 1920 | 10:14:23 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54495/tests/kraken/driver.html?testName=imaging-darkroom) 10:14:23 INFO - PID 1920 | RSS: Main: 326389760 10:14:23 INFO - PID 1920 | 10:14:26 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54495/tests/kraken/driver.html?testName=imaging-desaturate) 10:14:27 INFO - PID 1920 | RSS: Main: 332185600 10:14:27 INFO - PID 1920 | 10:14:30 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54495/tests/kraken/driver.html?testName=json-parse-financial) 10:14:31 INFO - PID 1920 | RSS: Main: 335294464 10:14:31 INFO - PID 1920 | 10:14:32 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54495/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:14:33 INFO - PID 1920 | RSS: Main: 303857664 10:14:33 INFO - PID 1920 | 10:14:35 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:14:35 INFO - PID 1920 | RSS: Main: 305905664 10:14:35 INFO - PID 1920 | 10:14:37 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:14:38 INFO - PID 1920 | RSS: Main: 308367360 10:14:38 INFO - PID 1920 | 10:14:41 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:14:42 INFO - PID 1920 | RSS: Main: 326213632 10:14:42 INFO - PID 1920 | 10:14:45 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:14:46 INFO - PID 1920 | RSS: Main: 319115264 10:14:46 INFO - PID 1920 | 10:14:47 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:54495/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54495/tests/kraken/driver.html?testName=ai-astar) 10:14:48 INFO - PID 1920 | RSS: Main: 381538304 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | __start_tp_report 10:14:48 INFO - PID 1920 | _x_x_mozilla_page_load 10:14:48 INFO - PID 1920 | _x_x_mozilla_page_load_details 10:14:48 INFO - PID 1920 | |i|pagename|runs| 10:14:48 INFO - PID 1920 | |0;ai-astar;116;115;115;115;115;116;116;116;113;116 10:14:48 INFO - PID 1920 | |1;audio-beat-detection;148;115;140;190;133;114;113;113;135;112 10:14:48 INFO - PID 1920 | |2;audio-dft;189;186;182;187;187;181;186;185;187;195 10:14:48 INFO - PID 1920 | |3;audio-fft;79;57;60;92;62;60;58;63;80;60 10:14:48 INFO - PID 1920 | |4;audio-oscillator;93;71;89;70;69;71;85;69;68;85 10:14:48 INFO - PID 1920 | |5;imaging-gaussian-blur;135;89;89;89;88;89;88;92;89;88 10:14:48 INFO - PID 1920 | |6;imaging-darkroom;95;94;96;94;102;94;94;94;93;94 10:14:48 INFO - PID 1920 | |7;imaging-desaturate;84;85;85;83;84;83;84;84;87;83 10:14:48 INFO - PID 1920 | |8;json-parse-financial;63;60;62;61;61;61;62;62;61;61 10:14:48 INFO - PID 1920 | |9;json-stringify-tinderbox;53;52;64;67;64;59;60;59;60;59 10:14:48 INFO - PID 1920 | |10;stanford-crypto-aes;82;84;83;83;83;80;81;84;82;84 10:14:48 INFO - PID 1920 | |11;stanford-crypto-ccm;146;139;138;141;137;139;140;137;187;148 10:14:48 INFO - PID 1920 | |12;stanford-crypto-pbkdf2;187;187;188;184;188;184;182;228;220;195 10:14:48 INFO - PID 1920 | |13;stanford-crypto-sha256-iterative;68;68;68;69;69;69;68;69;68;69 10:14:48 INFO - PID 1920 | __end_tp_report 10:14:48 INFO - PID 1920 | __start_cc_report 10:14:48 INFO - PID 1920 | _x_x_mozilla_cycle_collect,2137 10:14:48 INFO - PID 1920 | __end_cc_report 10:14:48 INFO - PID 1920 | __startTimestamp1489511688170__endTimestamp 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | ------- Summary: start ------- 10:14:48 INFO - PID 1920 | Number of tests: 14 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.00 stddev:0.95 (0.8%) stddev-sans-first:0.97 10:14:48 INFO - PID 1920 | Values: 116.0 115.0 115.0 115.0 115.0 116.0 116.0 116.0 113.0 116.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:134.00 stddev:24.55 (18.3%) stddev-sans-first:25.28 10:14:48 INFO - PID 1920 | Values: 148.0 115.0 140.0 190.0 133.0 114.0 113.0 113.0 135.0 112.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#2] audio-dft Cycles:10 Average:186.50 Median:187.00 stddev:3.84 (2.1%) stddev-sans-first:3.96 10:14:48 INFO - PID 1920 | Values: 189.0 186.0 182.0 187.0 187.0 181.0 186.0 185.0 187.0 195.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#3] audio-fft Cycles:10 Average:67.10 Median:62.50 stddev:12.05 (19.3%) stddev-sans-first:11.99 10:14:48 INFO - PID 1920 | Values: 79.0 57.0 60.0 92.0 62.0 60.0 58.0 63.0 80.0 60.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.00 stddev:9.76 (12.5%) stddev-sans-first:8.47 10:14:48 INFO - PID 1920 | Values: 93.0 71.0 89.0 70.0 69.0 71.0 85.0 69.0 68.0 85.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:89.00 stddev:14.59 (16.4%) stddev-sans-first:1.22 10:14:48 INFO - PID 1920 | Values: 135.0 89.0 89.0 89.0 88.0 89.0 88.0 92.0 89.0 88.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:94.00 stddev:2.58 (2.7%) stddev-sans-first:2.74 10:14:48 INFO - PID 1920 | Values: 95.0 94.0 96.0 94.0 102.0 94.0 94.0 94.0 93.0 94.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.30 10:14:48 INFO - PID 1920 | Values: 84.0 85.0 85.0 83.0 84.0 83.0 84.0 84.0 87.0 83.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.50 stddev:0.84 (1.4%) stddev-sans-first:0.67 10:14:48 INFO - PID 1920 | Values: 63.0 60.0 62.0 61.0 61.0 61.0 62.0 62.0 61.0 61.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:4.67 (7.8%) stddev-sans-first:4.28 10:14:48 INFO - PID 1920 | Values: 53.0 52.0 64.0 67.0 64.0 59.0 60.0 59.0 60.0 59.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 10:14:48 INFO - PID 1920 | Values: 82.0 84.0 83.0 83.0 83.0 80.0 81.0 84.0 82.0 84.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:140.50 stddev:15.14 (10.8%) stddev-sans-first:16.06 10:14:48 INFO - PID 1920 | Values: 146.0 139.0 138.0 141.0 137.0 139.0 140.0 137.0 187.0 148.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.30 Median:188.00 stddev:16.15 (8.6%) stddev-sans-first:16.91 10:14:48 INFO - PID 1920 | Values: 187.0 187.0 188.0 184.0 188.0 184.0 182.0 228.0 220.0 195.0 10:14:48 INFO - PID 1920 | 10:14:48 INFO - PID 1920 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 10:14:48 INFO - PID 1920 | Values: 68.0 68.0 68.0 69.0 69.0 69.0 68.0 69.0 68.0 69.0 10:14:48 INFO - PID 1920 | -------- Summary: end -------- 10:14:48 INFO - PID 1920 | 10:14:49 INFO - TEST-INFO | 1920: exit 0 10:14:49 INFO - TEST-OK | kraken | took 60644ms 10:14:49 INFO - SUITE-END | took 1180s 10:14:49 INFO - Completed test suite (00:19:40) 10:14:49 INFO - javascript benchmark 10:14:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7693.954687429418, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2843.445528122016, 3283.1463819406945, 3309.5697559317287, 3315.4198015347306, 3331.783434076785, 5568.136938774496, 5639.577031722617, 5753.971230143854, 5769.04810706234, 5802.419758024195, 5564.221009058725, 5665.3201615806165, 5667.178259152421, 5674.914876276859, 5675.914861277084, 5131.33292671953, 5148.5881129509735, 5160.741962901859, 5173.560247378978, 5174.534291913727, 5255.658382205144, 5266.973665131688, 5267.0256002639635, 5269.6311258211945, 5323.387810401795, 5246.632735708483, 5270.341207349082, 5280.6039547034125, 5282.9207561886415, 5283.788648454077, 5257.737113144328, 5271.156614941629, 5271.657342272759, 5277.472252774711, 5290.867728306785, 5109.489051094898, 5217.8173763918085, 5230.790768369261, 5231.450697676765, 5240.3973543042275, 5818.069108942549, 5836.9124463133085, 5842.5618078644275, 5846.50304724099, 5866.911996320058, 6175.567710260284, 6189.597676151036, 6221.004639257697, 6247.375262473762, 6257.18656574647, 6143.034229046707, 6152.507799376084, 6195.690215489208, 6235.906461403082, 6236.96881515594, 5946.989011867993, 5969.8806023879715, 5972.850678733066, 5980.342162362137, 6011.699415029275, 6276.184096067482, 6279.612614849764, 6323.430891219812, 6333.334999825044, 6349.52378571609, 6060.424259695322, 6109.75560977556, 6128.601640893327, 6198.907016394758, 6205.193324867739, 6129.14485165794, 6164.845878852975, 6171.938280617226, 6176.227971503562, 6177.444030037318, 6011.6693581853415, 6150.04674275488, 6158.907616385758, 6164.47601953839, 6165.0444181152, 5328.946710532845, 6021.277446706424, 6115.270764408881, 6180.0, 6186.319504854462, 4025.3961905714377, 4980.053789780002, 4981.800727970841, 5050.0, 5086.0845047891735, 4382.364557139197, 4409.757463339483, 4421.867343979686, 4461.0, 4461.531539188339, 5010.248462730619, 5713.371529131792, 5739.540836733051, 5757.769689212469, 5799.797007104731, 6253.218347706536, 6261.436470717566, 6262.5616206866, 6265.091561723616, 6290.402411770874, 5567.693776842231, 5623.718814059281, 5635.605507614509, 5639.746211420496, 5644.068728659808, 5412.323459567554, 5419.349678038582, 5428.37573679032, 5431.864203394946, 5447.53695935842, 6178.320384757583, 6278.089676996809, 6283.2460104787715, 6290.4653104485715, 6295.213098362705, 6186.381361863778, 6262.5929314594405, 6267.185265915402, 6268.310485846644, 6281.937180628135, 6269.717862696099, 6323.525735569942, 6328.557001009885, 6331.556791024584, 6365.363463653597, 6111.083337499411, 6117.888303246575, 6294.961331380271, 6295.496360291074, 6307.085472606538, 6194.0, 6197.938020619736, 6257.749690012349, 6293.716782744879, 6309.652969086561, 6109.602875813058, 6118.235220597426, 6142.697436716463, 6193.473554747897, 6201.0, 6164.013757798731, 6173.59871608344, 6179.104029915727, 6203.0, 6206.348333424925, 5792.623479473821, 5858.091995740667, 5871.089981052944, 6001.3698561652145, 6019.277686677627, 5714.02861513552, 5773.8556536086935, 6147.047207682816, 6221.471174949999, 6248.0003199487865, 6003.099535069775, 6069.605475644069, 6086.269647642134, 6088.543359248163, 6090.573659843769], "value": 5698.327626807108, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.7277807211333, 749.4678778067572, 768.55039801716, 789.3014682006425, 853.3429259470208, 27910.86044569777, 28326.71673283264, 28337.0, 28366.858165709167, 36841.07897302562, 38959.22081558367, 39042.0, 39153.80423097888, 39176.0, 39259.0, 9102.954485227598, 9312.115349041831, 9337.253019758436, 9341.95329023354, 9349.158575728183, 7234.674439650228, 7409.184989651134, 9037.819243615124, 9237.353385263033, 10768.461576921161, 11124.276922000085, 11153.665390038312, 11161.888381116207, 11179.66461006171, 11193.27243729155, 10983.780324393507, 11122.388268645222, 11133.888661113408, 11134.0, 11136.053435457996, 7081.89377159343, 7238.674259658328, 10445.0, 10870.130389568816, 10976.231663783537, 38389.23221535581, 38744.0, 38789.98755192921, 38837.80581097105, 38875.0, 3499.4750787382095, 3833.5208098987628, 3861.575226725058, 3872.1674839909556, 3990.700697447703, 28116.437671246462, 28328.00851970171, 28628.284292892637, 29363.41273174546, 29413.852930735422, 3085.922851928697, 3087.783855130142, 3110.751139908802, 3207.0378886334006, 3215.823129727864, 335574.0, 336714.0, 336773.0, 337177.0, 337234.0, 50882.236766448535, 50889.982200355786, 50937.72655683637, 50947.7452612742, 51026.74486627543, 53366.466335336925, 53400.0, 53543.46456535385, 53609.65975850635, 53612.73193634007, 14317.140971541741, 14628.049176803474, 14651.34068966899, 14652.413903443956, 14670.486532971508, 11889.643310700692, 11901.76196476083, 11919.3444360561, 11929.224600400947, 11936.582219622445, 10769.9461502692, 10919.617813376493, 10949.343039417501, 12229.449674764657, 12912.418941147484, 5285.814996475182, 5379.327584051994, 5398.0, 5406.351237851404, 5416.183584536019, 946.4431010987025, 1026.9473789365811, 1089.7711480589007, 1090.9853835932659, 1097.9231453798157, 24715.62926556103, 24724.629130563055, 24822.5035499289, 25051.373715657253, 25912.870435647703, 971.5530855806267, 1172.9589464368705, 1181.2381014245764, 1224.8468941382328, 1226.1171956191529, 12666.0, 13026.80459793104, 13165.077416682907, 13276.867231327757, 13291.335433228493, 326941.0, 413389.0, 414250.0, 414400.0, 414920.0, 53445.7327713359, 53454.19818702723, 53548.0, 53619.463805362226, 53660.92678146415, 1349.6693310139078, 1414.1161773891317, 1415.0660564027899, 1425.921574313402, 1443.992780036093, 23693.526129477657, 24598.016079356625, 24649.38376540529, 24967.750322496544, 25174.74825251724, 45409.0, 45509.0, 45546.31680524861, 45570.0, 45602.77198614118, 399707.0, 399806.0, 400255.0, 401966.0, 402043.0, 33105.83447082749, 33426.83286583552, 34198.487022695175, 34369.82815085909, 34420.82789586036, 43240.78379608082, 43729.781351094316, 43810.0, 44593.55406445894, 45820.0, 35182.47226291659, 35449.46825797563, 35732.64267357293, 36707.81646091752, 36900.630993689716, 34974.82512587421, 35071.824640876635, 36058.63941360553, 36278.45582316215, 36390.81804591066, 35391.82304088463, 35678.64321356857, 35726.0, 36167.63832361748, 36257.0, 29913.252168695955, 31550.526742099348, 31625.5256171153, 31701.524477133324, 31802.681973179973, 21699.674504882754, 23654.763452365256, 23678.57928524294, 24260.0, 24454.0], "value": 18350.961874496734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.3389911713771, 383.21058619244343, 400.88775142959963, 419.3856000958595, 435.14928315143896, 13900.721985560296, 14114.858851411484, 18265.54336141597, 20573.691394629055, 21437.57124857502, 20966.89516552419, 22048.0, 22339.6649050264, 22655.320340389775, 23011.654825177637, 4141.875743727678, 6599.67001649918, 6824.7952561423235, 6841.692123854414, 6911.447084233275, 3232.773705840592, 3272.181954511372, 3275.16483296759, 3277.049655599885, 6797.52417330787, 3266.4447044002577, 3275.5086736989524, 3276.393867134576, 3280.1963518937773, 5192.532672059514, 4375.956240437603, 7681.039870016248, 7703.422243331773, 7710.537367757953, 7725.806854828618, 7172.2469140739995, 7427.071616047994, 7470.3276705096805, 7514.4364172687265, 7517.248275172494, 26759.795809188632, 27189.86405067982, 27414.862925685444, 27530.724692753018, 28067.29831754202, 2835.277004363895, 2903.2886942698965, 2905.8547072646284, 2908.447394994965, 2916.4458752836895, 15904.363825447084, 16343.591410214722, 16476.917615411847, 16711.91644041772, 17256.050917199547, 1962.3249522597027, 1969.8227159555709, 1984.6130004649099, 1995.3814317561603, 2008.0562135796151, 149080.50919490884, 154233.0, 154997.0, 155459.0, 158455.2077239618, 20017.399478015683, 21342.395921835236, 21451.57096858054, 21614.891925540585, 21742.891285543472, 35668.28663426717, 35677.0, 35767.821160894026, 35782.64217357845, 35823.64176358255, 8801.955990220007, 10189.03204195615, 10206.591736330613, 10370.481475926324, 10425.270231083752, 8239.176082391712, 8308.792280193044, 8326.04250511199, 8526.82946341082, 8532.360072994426, 8586.613602387908, 9285.58214880332, 9840.557174927146, 9936.801263974825, 9953.452560109119, 3869.349495595526, 4044.656204222674, 4048.2308361411237, 4054.4526488923616, 4054.459305463905, 819.7950512371907, 838.8909441772653, 898.7528429681889, 900.59923334117, 1049.2078480747139, 22313.281429585306, 23240.65139022916, 25326.0, 25869.870650647004, 26073.73926260751, 874.4709450782312, 905.800723840754, 915.9084091590921, 939.9718008459757, 949.9762505937407, 6824.829379265558, 6853.725850965906, 6931.653417329113, 7072.717091316393, 7073.328033836778, 557957.0, 586458.0, 670866.0, 699010.0, 705693.0, 133093.66906330813, 140468.0, 155690.0, 163049.36950630342, 165538.0, 1313.1005261395974, 1327.8672132786644, 1365.2627581106092, 1372.5882235329361, 1376.9311534423439, 17616.647667046844, 20822.1671133153, 21591.568168636855, 21906.12375504962, 22308.77691223067, 61808.69095654673, 62063.37936620757, 64044.0, 64898.67550662217, 65227.34772652403, 89490.55254723685, 89581.0, 91246.17507649677, 91501.0, 92212.53893730757, 9987.500624968869, 11186.664400068012, 11724.062075034148, 11935.940320298343, 11986.520539178335, 13224.933875330562, 13637.727245454837, 13653.317334133451, 13969.650758731113, 14080.507182248572, 11655.125865560287, 11683.240589361665, 11822.645320640395, 12007.0, 12030.218035827644, 11580.478878450322, 11596.0, 11683.357415342238, 11783.52865885355, 12049.156559040783, 10063.647772327933, 11778.587749428729, 12033.63899083029, 12099.697507562381, 12228.205166664138, 11895.643130706092, 11939.582114625946, 12185.781421857744, 12194.329311887932, 12294.446749896455, 11577.826332604849, 11589.710257243636, 11838.230515016496, 11928.880711192776, 12010.879891201326], "value": 11198.974479239638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.8433994974, 1497.8726808221302, 1565.686862627474, 1584.5563242292158, 1599.77603135561, 10350.223733220011, 10855.51150198241, 10873.782524349508, 10888.183386246043, 10893.618723344665, 9747.756306092351, 10102.696919092421, 10128.341657792238, 10145.644902428416, 10148.69553913382, 2298.436882963672, 2332.3586013846243, 2334.5914464968646, 2334.871582062986, 2335.159342636658, 2246.8314876384256, 2277.6925115109498, 2281.7033785607846, 2286.542691461706, 2289.4161988692945, 2252.3693365857503, 2262.016023029979, 2267.8979445924974, 2269.0810221860092, 2278.5670722562745, 2198.955496139337, 2212.048819007826, 2221.6334304839684, 2222.9333120006427, 2225.7551669316363, 1961.2939341836927, 2250.561140577588, 2338.38032921276, 2342.8711420871846, 2343.285297984114, 10896.0, 11015.339079655167, 11022.834657480144, 11030.944845275722, 11037.83443248352, 5499.175123731432, 5736.139579063132, 5741.598088133832, 5745.368009518949, 5753.137029445575, 3259.445894197992, 3345.2305969627123, 3569.447290428646, 3572.8570857165682, 3577.9802756214613, 1173.2901594535354, 1209.3832145605716, 1724.1120822776281, 1744.97382539262, 1827.826356496144, 12667.493300267979, 12856.935715321364, 13013.86986130136, 14942.477013304482, 15094.924525377302, 3765.359888818907, 4324.697271190987, 4682.789274482657, 4683.039976804746, 4685.156671799109, 11393.601223957121, 11393.829092563617, 11394.0, 11400.372979486043, 11420.0, 5649.745761440745, 5655.773769049274, 5655.9253741788925, 5659.858503537363, 5664.68844213564, 4702.153612349742, 4998.625103117208, 5008.549230569193, 5168.3797944246935, 5196.168613021899, 3407.4496104272994, 3500.8774692885627, 3535.6641119093647, 3558.5729712434154, 3590.461430785403, 1000.3247807729754, 1002.6891663584312, 1015.1777060580953, 1018.5620183321095, 1024.6413755185624, 734.6620554544862, 892.1390857822103, 961.6778379243032, 966.9129778319985, 973.6981535723949, 6477.77327793525, 6495.545311828127, 6567.158222417276, 6729.966350168218, 6767.2894346093935, 958.7057472412328, 967.9932869815314, 991.8958509356557, 1015.1878497202209, 1044.9059584637419, 3773.660370566662, 4189.7695626740215, 4387.5393083726385, 4404.515503294635, 4409.470863496401, 13225.206487610774, 13717.725645487035, 13722.0, 13738.244396558286, 13760.587182384545, 13183.67040823987, 13302.600921972356, 13449.731005379837, 13562.186268823902, 13606.591802245948, 9468.763280918032, 9753.02469753019, 9861.211103111735, 9863.358881672668, 9926.851097233548, 6127.295361033538, 6433.806985790434, 6654.268030516542, 6927.93072069293, 6946.548474349152, 11818.349990750592, 12042.096842736655, 12048.0, 12192.695182620506, 12230.755384892074, 11973.341466219441, 12098.09264305163, 12383.257004579755, 12462.376881155724, 12507.687307817378, 9112.635494580143, 9223.815523689354, 9337.813243735223, 9366.06339366058, 9373.85939210926, 10195.0, 10206.23453240995, 10241.48792560354, 10303.021212985055, 10379.948100259451, 9085.863712044193, 9086.909130908607, 9094.727158185266, 9291.117343852324, 9312.534373281173, 8157.469764465291, 8919.687810926845, 9007.144321289466, 9065.138811812867, 9138.13187747163, 7281.817954551178, 7472.925270747441, 7502.98709674187, 7543.886841697269, 7573.166951635426, 5810.5061069809535, 5847.532197424281, 5987.0, 6069.666168360783, 6080.331163572092, 5280.551153152025, 5859.355470898113, 5925.9407405925385, 6172.19761431029, 6224.595401298901], "value": 5051.195631042924, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.45804422702554, 365.9908502287444, 371.3649659082968, 372.99067523311913, 384.8768394113884, 5417.431169727181, 5597.244372009777, 5692.772289108436, 5778.971105144468, 5797.130430435432, 5387.838364849051, 5519.751611177497, 5524.591229236549, 5540.889182216353, 5653.293338332708, 4272.764997925113, 4743.814046488369, 4861.805527778884, 4868.853934381974, 4902.142125128106, 4545.863624091266, 4606.953930460686, 4867.537583929521, 4869.293952376903, 4913.26301054841, 4193.1404062167185, 4296.699231053827, 4754.858833879861, 4755.904881902377, 4841.298011788289, 4556.329761993949, 4751.691140075884, 4760.381150450419, 4844.231855372799, 4845.152098382769, 4602.608778253819, 4625.329327247564, 4626.329182268552, 4643.8374656886845, 4657.3246879202325, 4485.730856148642, 4685.578297953167, 5143.408508021588, 5580.804671836507, 5612.522935550483, 4678.321643361727, 4924.0, 5694.202811606378, 5736.0, 5757.942420575782, 6112.135798581431, 6117.256581874189, 6157.2919114301985, 6181.536384771161, 6184.6907654617535, 5083.694978301277, 5281.0, 5286.180642000496, 5298.390685071227, 5315.388730295988, 5920.289565252154, 6402.391772781578, 6431.324710905381, 6476.255230648441, 6488.772892948724, 5363.678179309254, 5404.83785486436, 5416.485433883775, 5419.945800541944, 5421.349438067457, 4870.6620822004925, 5164.8967020659375, 5276.469823751146, 5464.972675136599, 5516.503514683698, 5229.4770522948165, 5267.787529237729, 5634.774609015675, 5750.568707346882, 5752.453516915886, 5528.474794894479, 5686.744096515667, 5760.337561180518, 6150.569460137837, 6175.166352542439, 1553.5572361876812, 1799.8650101242197, 1889.7448844406103, 1905.8570607204515, 1908.799576044523, 1463.9121652700871, 1505.284989629917, 1523.7276873810272, 1528.388644542192, 1543.2515230113386, 5689.374168841424, 5705.659170095024, 5711.628744131618, 5714.771409143587, 5769.221155144086, 5485.985092757853, 5773.797917072882, 6040.728167232397, 6281.654509001957, 6287.402696743802, 4523.773811309421, 5272.235525848807, 5522.502974732293, 5693.832764283312, 5707.71461426927, 2933.0, 3292.6213485449002, 3311.602607687093, 3320.269540701042, 3327.2513684421297, 6198.241782406786, 6494.253160886464, 6505.284418713938, 6512.0, 6541.967290163614, 6252.562320637512, 6346.555741098171, 6393.328700486382, 6396.680165991682, 6477.481801455872, 5365.302510673588, 5837.781642975642, 6090.147379366892, 6408.775692850728, 6538.411542961157, 5042.193249080129, 5904.291485021825, 5980.1328807321825, 6317.5893566918, 6334.714937827716, 5515.310586176728, 6236.34518375564, 6250.031245156826, 6258.624482531062, 6286.214223222098, 6374.553781235454, 6469.870602587827, 6494.870102598016, 6542.2149342077355, 6556.737730490727, 4347.021920067959, 4883.389576302962, 4901.264810278487, 4922.409310882574, 4945.332380128637, 4777.665563410528, 5666.773329066791, 6153.446189842935, 6256.468200202851, 6302.117703521419, 5652.915206271827, 5918.467337939606, 6093.177421048279, 6098.146259523759, 6167.599106058092, 5826.592138550261, 6070.605410648293, 6089.177960975212, 6089.969550152221, 6097.695115244132, 5031.195008798574, 5258.687027172857, 5449.809256675997, 5952.244087993966, 5963.880722385441, 4963.975180124221, 5093.719845408538, 5453.836384908459, 6024.072296864962, 6134.969325153345, 5217.0, 5709.486146246858, 5898.64608123514, 5941.7623295067715, 5948.762049517971], "value": 4746.698810320007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.7113519024797, 723.0997408226758, 756.6935391166576, 778.5095389904361, 789.63676708714, 14962.77555836662, 15692.0, 15779.368825246976, 15783.0, 15797.210139493023, 13944.790828137586, 14679.0, 14942.0, 14971.550853474386, 15255.618609534768, 920.6777627830256, 954.3558098283665, 957.5786653872274, 958.108958668438, 961.4808003678005, 785.2461636828651, 965.4448692002092, 968.5060619084283, 971.2763990826844, 980.8038392321529, 1014.5789497358625, 1015.8679371681671, 1019.0115587879744, 1020.2959957629249, 1020.6784862768204, 1009.1018993096077, 1011.8735158105237, 1012.7873146639215, 1013.5287091502449, 1014.1734486393566, 753.6533194730431, 753.8416932444138, 754.1026178847209, 754.909410870699, 754.9245075492407, 15829.762553561706, 15876.920615396964, 16271.430499932563, 16370.099644519547, 16373.91813040939, 10710.518026688818, 12678.302693351861, 12678.619641410773, 13097.738045239137, 13396.306569707835, 10738.731531711692, 11023.44882755867, 11172.553097876074, 11301.547938082467, 11351.829722554086, 3138.7601897250456, 3704.685101766353, 3720.274546463441, 3862.382018876994, 3899.8245078971513, 92645.53677231638, 106662.93337066607, 107025.46487267592, 107468.46265768699, 110728.4463577677, 9514.38156519824, 11360.886391135982, 11477.540898363972, 11521.942390288163, 11751.590923860587, 22477.4380640482, 23262.53474930492, 24922.252332430056, 24939.376515586893, 24969.87515062413, 7909.7231596893835, 7999.28006479408, 8095.959520202361, 8232.753017409488, 8302.252797248277, 3788.981055094707, 3829.6744776694295, 3880.8059597020037, 3910.0, 3914.5693973662874, 3086.830224337638, 3482.6865582097735, 3550.2366991097037, 3580.588232353313, 3582.9104272393397, 641.0608458608161, 644.8774732800753, 645.6771614192903, 663.0385940386459, 664.2493981800534, 659.2846761263986, 660.2242365220847, 666.4302021771413, 688.3151264491862, 690.3545185251805, 9431.811363772686, 9698.660546880967, 9876.65431709901, 10610.840837387444, 10903.618373357052, 540.8689762336383, 543.665645627936, 580.9390014048548, 629.2260519560965, 703.1210986267166, 4683.929741053887, 4903.53416425439, 4937.0, 4969.701817890938, 4997.550220480174, 74644.0, 76643.61678191685, 77886.0, 79144.20855791484, 81320.0, 55105.72447137738, 70517.0, 77702.0, 79440.0, 81648.367032659, 53108.46891531035, 56200.0, 74119.62940185373, 74244.62877685577, 74488.62755686295, 10536.434726303974, 10635.840462392916, 10697.572097116028, 10851.728706782078, 10852.565897364017, 40691.59308406878, 43451.0, 46230.0, 46786.532134678215, 47681.523184769096, 61139.0, 62046.0, 62062.689686551275, 62507.68684661855, 63125.0, 12866.935665321613, 13134.737305254033, 13171.078024538574, 13485.190888546718, 13788.0, 11703.88296117062, 14071.859281407464, 14133.787993179905, 14146.575602731935, 14173.929130354281, 8797.868031979397, 12163.331016794162, 12655.557055503014, 13092.0, 13685.178889266675, 13317.334133293103, 13488.662783430493, 13609.834844157456, 13776.862231377558, 13838.792418113535, 12078.0, 12743.426545805276, 13199.934000329937, 13263.403146858222, 13457.596272111852, 8885.099464137897, 10906.563737450731, 10916.781664366827, 11073.213056876975, 11560.595379162025, 8693.869591956, 9431.245500360092, 9894.752631184278, 11078.0, 11177.497012634289], "value": 7387.391963820927, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1461.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 115.0, 115.0, 116.0, 116.0, 116.0, 113.0, 116.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 140.0, 190.0, 133.0, 114.0, 113.0, 113.0, 135.0, 112.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 186.0, 182.0, 187.0, 187.0, 181.0, 186.0, 185.0, 187.0, 195.0], "value": 186.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 60.0, 92.0, 62.0, 60.0, 58.0, 63.0, 80.0, 60.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 89.0, 70.0, 69.0, 71.0, 85.0, 69.0, 68.0, 85.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 89.0, 89.0, 89.0, 88.0, 89.0, 88.0, 92.0, 89.0, 88.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 102.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 83.0, 84.0, 83.0, 84.0, 84.0, 87.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 62.0, 61.0, 61.0, 61.0, 62.0, 62.0, 61.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 64.0, 67.0, 64.0, 59.0, 60.0, 59.0, 60.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 83.0, 83.0, 83.0, 80.0, 81.0, 84.0, 82.0, 84.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 138.0, 141.0, 137.0, 139.0, 140.0, 137.0, 187.0, 148.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 188.0, 184.0, 188.0, 184.0, 182.0, 228.0, 220.0, 195.0], "value": 194.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 69.0], "value": 68.5, "unit": "score"}]}]} 10:14:49 INFO - Return code: 0 10:14:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:14:49 ERROR - # TBPL SUCCESS # 10:14:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:49 INFO - 2017-03-14 10:14:49.488 screenresolution[1924:20376] starting screenresolution argv=screenresolution get 10:14:49 INFO - 2017-03-14 10:14:49.491 screenresolution[1924:20376] Display 0: 1600x1200x32@60 10:14:49 INFO - 2017-03-14 10:14:49.497 screenresolution[1925:20379] starting screenresolution argv=screenresolution list 10:14:49 INFO - Available Modes on Display 0 10:14:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:49 INFO - Intel Iris: 10:14:49 INFO - Chipset Model: Intel Iris 10:14:49 INFO - Type: GPU 10:14:49 INFO - Bus: Built-In 10:14:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:49 INFO - Vendor: Intel (0x8086) 10:14:49 INFO - Device ID: 0x0a2e 10:14:49 INFO - Revision ID: 0x0009 10:14:49 INFO - Displays: 10:14:49 INFO - Display: 10:14:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:49 INFO - Display Serial Number: 1600x1200 60 10:14:49 INFO - Main Display: Yes 10:14:49 INFO - Mirror: Off 10:14:49 INFO - Online: Yes 10:14:49 INFO - Rotation: Supported 10:14:49 INFO - Return code: 0 10:14:49 INFO - Running post-action listener: _resource_record_post_action 10:14:49 INFO - [mozharness: 2017-03-14 17:14:49.734567Z] Finished run-tests step (success) 10:14:49 INFO - Running post-run listener: _resource_record_post_run 10:14:49 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 659456; Write bytes: 508993536; Read time: 8; Write time: 1625 10:14:49 INFO - TinderboxPrint: CPU usage
53.4% 10:14:49 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 8 10:14:49 INFO - TinderboxPrint: I/O write bytes / time
508,993,536 / 1,625 10:14:49 INFO - TinderboxPrint: CPU idle
2,240.5 (46.6%) 10:14:49 INFO - TinderboxPrint: CPU system
957.9 (19.9%) 10:14:49 INFO - TinderboxPrint: CPU user
1,607.3 (33.4%) 10:14:49 INFO - TinderboxPrint: Swap in / out
210,415,616 / 0 10:14:49 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205496832; Write bytes: 201166848; Read time: 15649; Write time: 412 10:14:49 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 659456; Write bytes: 296927232; Read time: 8; Write time: 1180 10:14:50 INFO - Running post-run listener: _upload_blobber_files 10:14:50 INFO - Blob upload gear active. 10:14:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:14:50 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 10:14:50 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'] 10:14:50 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 10:14:50 INFO - (blobuploader) - INFO - Open directory for files ... 10:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:14:51 INFO - (blobuploader) - INFO - Done attempting. 10:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:14:53 INFO - (blobuploader) - INFO - Done attempting. 10:14:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:14:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:14:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:14:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:14:54 INFO - (blobuploader) - INFO - Done attempting. 10:14:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:14:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:14:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:14:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:14:57 INFO - (blobuploader) - INFO - Done attempting. 10:14:57 INFO - (blobuploader) - INFO - Iteration through files over. 10:14:57 INFO - Return code: 0 10:14:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:14:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:14:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a017db6bc30eb5f3a778589c710e30461872afe1b6a17d6652d49a377da7674561a1a431627bde1d8e0849cd8ff479b9cd7c98934a77374b1cdd97d45d31de48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08121ff6bed8b802f0e505778351213579bb12b7a324e01d167dcb1a9dcea265bbdb8333245f789093210265b1d6a4c0048a4f0aabfe86355e140c9a717034f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/013a03dd66256c793e71d0c8f567f583c87219f986c740cf25f2210bb0114669d6b8cf09ffd30457af05bd2e619b62b9eb76b1bf680f27f0659c1c72af05fd99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c33c14aaa02239750ed68ba2a0ebcd41bdc791ef48288983ed0afd96148f5dcc8cff6ee40c6f499947ce00fdd092774ca37d39354d973d409172d022245e7d2"} 10:14:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:14:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:14:57 INFO - Contents: 10:14:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a017db6bc30eb5f3a778589c710e30461872afe1b6a17d6652d49a377da7674561a1a431627bde1d8e0849cd8ff479b9cd7c98934a77374b1cdd97d45d31de48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08121ff6bed8b802f0e505778351213579bb12b7a324e01d167dcb1a9dcea265bbdb8333245f789093210265b1d6a4c0048a4f0aabfe86355e140c9a717034f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/013a03dd66256c793e71d0c8f567f583c87219f986c740cf25f2210bb0114669d6b8cf09ffd30457af05bd2e619b62b9eb76b1bf680f27f0659c1c72af05fd99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c33c14aaa02239750ed68ba2a0ebcd41bdc791ef48288983ed0afd96148f5dcc8cff6ee40c6f499947ce00fdd092774ca37d39354d973d409172d022245e7d2"} 10:14:57 INFO - Running post-run listener: copy_logs_to_upload_dir 10:14:57 INFO - Copying logs to upload dir... 10:14:57 INFO - mkdir: /builds/slave/test/build/upload/logs 10:14:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.984444 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-14 10:14:57.317687) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 10:14:57.322954) ========= 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.3qH40Yjaxz/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.VryxjCoTYy/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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/a017db6bc30eb5f3a778589c710e30461872afe1b6a17d6652d49a377da7674561a1a431627bde1d8e0849cd8ff479b9cd7c98934a77374b1cdd97d45d31de48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08121ff6bed8b802f0e505778351213579bb12b7a324e01d167dcb1a9dcea265bbdb8333245f789093210265b1d6a4c0048a4f0aabfe86355e140c9a717034f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/013a03dd66256c793e71d0c8f567f583c87219f986c740cf25f2210bb0114669d6b8cf09ffd30457af05bd2e619b62b9eb76b1bf680f27f0659c1c72af05fd99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c33c14aaa02239750ed68ba2a0ebcd41bdc791ef48288983ed0afd96148f5dcc8cff6ee40c6f499947ce00fdd092774ca37d39354d973d409172d022245e7d2"} build_url:https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/GJRfiTFBRaCH3uFACCazXQ/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/a017db6bc30eb5f3a778589c710e30461872afe1b6a17d6652d49a377da7674561a1a431627bde1d8e0849cd8ff479b9cd7c98934a77374b1cdd97d45d31de48", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08121ff6bed8b802f0e505778351213579bb12b7a324e01d167dcb1a9dcea265bbdb8333245f789093210265b1d6a4c0048a4f0aabfe86355e140c9a717034f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/013a03dd66256c793e71d0c8f567f583c87219f986c740cf25f2210bb0114669d6b8cf09ffd30457af05bd2e619b62b9eb76b1bf680f27f0659c1c72af05fd99", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c33c14aaa02239750ed68ba2a0ebcd41bdc791ef48288983ed0afd96148f5dcc8cff6ee40c6f499947ce00fdd092774ca37d39354d973d409172d022245e7d2"}' ========= master_lag: 0.17 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 10:14:57.502419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 10:14:57.502781) ========= 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.3qH40Yjaxz/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.VryxjCoTYy/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.005421 ========= master_lag: 0.26 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 10:14:57.767883) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 10:14:57.768196) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 10:14:57.768520) ========= ========= Total master_lag: 0.96 =========