builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0181 starttime: 1490010539.34 results: success (0) buildid: 20170320031743 builduid: e966bdfeb2f3478c91904d09a5e578ca revision: 1c9cf05afab07f505e360d404dfbaba81a1b4721 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.336433) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.336906) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.337187) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.389295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.389691) ========= 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.K3vMzXUH09/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.uRYpDpUFK2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-20 04:48:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.4M=0.001s 2017-03-20 04:48:59 (19.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.356428 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.770829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.771158) ========= 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.K3vMzXUH09/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.uRYpDpUFK2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.027272 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:48:59.819938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-20 04:48:59.820292) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 1c9cf05afab07f505e360d404dfbaba81a1b4721 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 1c9cf05afab07f505e360d404dfbaba81a1b4721 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K3vMzXUH09/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.uRYpDpUFK2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-20 04:48:59,927 truncating revision to first 12 chars 2017-03-20 04:48:59,928 Setting DEBUG logging. 2017-03-20 04:48:59,928 attempt 1/10 2017-03-20 04:48:59,928 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/1c9cf05afab0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 04:49:01,683 unpacking tar archive at: pine-1c9cf05afab0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.047545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-20 04:49:01.891577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:49:01.891905) ========= script_repo_revision: 1c9cf05afab07f505e360d404dfbaba81a1b4721 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:49:01.892295) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 04:49:01.892577) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 04:49:01.931399) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-20 04:49:01.931808) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K3vMzXUH09/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.uRYpDpUFK2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:49:02 INFO - MultiFileLogger online at 20170320 04:49:02 in /builds/slave/test 04:49:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 04:49:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:49:02 INFO - {'append_to_log': False, 04:49:02 INFO - 'base_work_dir': '/builds/slave/test', 04:49:02 INFO - 'blob_upload_branch': 'Pine', 04:49:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:49:02 INFO - 'branch': 'Pine', 04:49:02 INFO - 'buildbot_json_path': 'buildprops.json', 04:49:02 INFO - 'config_files': ('talos/mac_config.py',), 04:49:02 INFO - 'default_actions': ('clobber', 04:49:02 INFO - 'read-buildbot-config', 04:49:02 INFO - 'download-and-extract', 04:49:02 INFO - 'populate-webroot', 04:49:02 INFO - 'create-virtualenv', 04:49:02 INFO - 'install', 04:49:02 INFO - 'run-tests'), 04:49:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:49:02 INFO - 'download_minidump_stackwalk': True, 04:49:02 INFO - 'download_symbols': 'ondemand', 04:49:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:49:02 INFO - 'tooltool.py': '/tools/tooltool.py', 04:49:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:49:02 INFO - '/tools/misc-python/virtualenv.py')}, 04:49:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:49:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:49:02 INFO - 'gecko_profile': False, 04:49:02 INFO - 'gecko_profile_interval': 0, 04:49:02 INFO - 'installer_path': 'installer.exe', 04:49:02 INFO - 'log_level': 'info', 04:49:02 INFO - 'log_name': 'talos', 04:49:02 INFO - 'log_to_console': True, 04:49:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:49:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:49:02 INFO - 'opt_config_files': (), 04:49:02 INFO - 'pip_index': False, 04:49:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:49:02 INFO - 'cmd': ('bash', 04:49:02 INFO - '-c', 04:49:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:49:02 INFO - 'enabled': True, 04:49:02 INFO - 'halt_on_failure': False, 04:49:02 INFO - 'name': 'check_screen_resolution'},), 04:49:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:49:02 INFO - 'cmd': ('bash', 04:49:02 INFO - '-c', 04:49:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:49:02 INFO - 'enabled': True, 04:49:02 INFO - 'halt_on_failure': False, 04:49:02 INFO - 'name': 'check_screen_resolution'},), 04:49:02 INFO - 'run_cmd_checks_enabled': True, 04:49:02 INFO - 'suite': 'dromaeojs', 04:49:02 INFO - 'system_bits': '32', 04:49:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:49:02 INFO - 'title': 't-yosemite-r7-0181', 04:49:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:49:02 INFO - 'use_talos_json': True, 04:49:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:49:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:49:02 INFO - 'work_dir': 'build'} 04:49:02 INFO - [mozharness: 2017-03-20 11:49:02.158274Z] Running clobber step. 04:49:02 INFO - Running pre-action listener: _resource_record_pre_action 04:49:02 INFO - Running main action method: clobber 04:49:02 INFO - rmtree: /builds/slave/test/build 04:49:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:49:04 INFO - Running post-action listener: _resource_record_post_action 04:49:04 INFO - [mozharness: 2017-03-20 11:49:04.613337Z] Finished clobber step (success) 04:49:04 INFO - [mozharness: 2017-03-20 11:49:04.613594Z] Running read-buildbot-config step. 04:49:04 INFO - Running pre-action listener: _resource_record_pre_action 04:49:04 INFO - Running main action method: read_buildbot_config 04:49:04 INFO - Using buildbot properties: 04:49:04 INFO - { 04:49:04 INFO - "project": "", 04:49:04 INFO - "product": "firefox", 04:49:04 INFO - "script_repo_revision": "default", 04:49:04 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 04:49:04 INFO - "repository": "", 04:49:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 04:49:04 INFO - "buildid": "20170320031743", 04:49:04 INFO - "pgo_build": "False", 04:49:04 INFO - "basedir": "/builds/slave/test", 04:49:04 INFO - "buildnumber": 0, 04:49:04 INFO - "slavename": "t-yosemite-r7-0181", 04:49:04 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:49:04 INFO - "platform": "yosemite_r7", 04:49:04 INFO - "scheduler": "tests-pine-macosx64-talos", 04:49:04 INFO - "branch": "pine", 04:49:04 INFO - "repo_path": "projects/pine", 04:49:04 INFO - "revision": "1c9cf05afab07f505e360d404dfbaba81a1b4721", 04:49:04 INFO - "stage_platform": "macosx64", 04:49:04 INFO - "builduid": "e966bdfeb2f3478c91904d09a5e578ca", 04:49:04 INFO - "slavebuilddir": "test" 04:49:04 INFO - } 04:49:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:49:04 INFO - Running post-action listener: _resource_record_post_action 04:49:04 INFO - [mozharness: 2017-03-20 11:49:04.619429Z] Finished read-buildbot-config step (success) 04:49:04 INFO - [mozharness: 2017-03-20 11:49:04.619660Z] Running download-and-extract step. 04:49:04 INFO - Running pre-action listener: _resource_record_pre_action 04:49:04 INFO - Running main action method: download_and_extract 04:49:04 INFO - mkdir: /builds/slave/test/build/tests 04:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:04 INFO - trying https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:49:04 INFO - Downloading https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/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 04:49:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/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 04:49:05 INFO - Downloaded 1325 bytes. 04:49:05 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:49:05 INFO - Using the following test package requirements: 04:49:05 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:49:05 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:49:05 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:49:05 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:49:05 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'jsshell-mac.zip'], 04:49:05 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:49:05 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:49:05 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:49:05 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:49:05 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:49:05 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:49:05 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:49:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:49:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:49:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:49:05 INFO - Fetch https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:49:07 INFO - Expected file size: 52473358 04:49:07 INFO - Obtained file size: 52473358 04:49:23 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 04:49:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:49:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:49:23 INFO - Fetch https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:49:25 INFO - Expected file size: 52473358 04:49:25 INFO - Obtained file size: 52473358 04:49:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:49:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:49:41 INFO - Fetch https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:49:41 INFO - Expected file size: 14404171 04:49:41 INFO - Obtained file size: 14404171 04:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:42 INFO - trying https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:42 INFO - Downloading https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:49:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/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 04:49:44 INFO - Downloaded 75189884 bytes. 04:49:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:44 INFO - mkdir: /builds/slave/test/properties 04:49:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:49:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:49:44 INFO - Contents: 04:49:44 INFO - build_url:https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:45 INFO - Running post-action listener: _resource_record_post_action 04:49:45 INFO - Running post-action listener: set_extra_try_arguments 04:49:45 INFO - [mozharness: 2017-03-20 11:49:45.029710Z] Finished download-and-extract step (success) 04:49:45 INFO - [mozharness: 2017-03-20 11:49:45.029864Z] Running populate-webroot step. 04:49:45 INFO - Running pre-action listener: _resource_record_pre_action 04:49:45 INFO - Running main action method: populate_webroot 04:49:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:49:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:49:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:49:45 INFO - '--tppagecycles', 04:49:45 INFO - '7'], 04:49:45 INFO - 'tests': ['tsvgm']}}, 04:49:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:49:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:49:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:49:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:49:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['damp', 'tps']}, 04:49:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'tests': ['damp', 'tps']}, 04:49:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['dromaeo_dom']}, 04:49:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:49:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['basic_compositor_video']}, 04:49:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:49:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['a11yr', 04:49:45 INFO - 'ts_paint', 04:49:45 INFO - 'tpaint', 04:49:45 INFO - 'sessionrestore', 04:49:45 INFO - 'sessionrestore_no_auto_restore', 04:49:45 INFO - 'tabpaint']}, 04:49:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:49:45 INFO - 'ts_paint', 04:49:45 INFO - 'tpaint', 04:49:45 INFO - 'sessionrestore', 04:49:45 INFO - 'sessionrestore_no_auto_restore', 04:49:45 INFO - 'tabpaint']}, 04:49:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:49:45 INFO - 'ts_paint', 04:49:45 INFO - 'tpaint', 04:49:45 INFO - 'sessionrestore', 04:49:45 INFO - 'sessionrestore_no_auto_restore', 04:49:45 INFO - 'tabpaint']}, 04:49:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:49:45 INFO - 'ts_paint', 04:49:45 INFO - 'tpaint', 04:49:45 INFO - 'sessionrestore', 04:49:45 INFO - 'sessionrestore_no_auto_restore', 04:49:45 INFO - 'tabpaint']}, 04:49:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['a11yr', 04:49:45 INFO - 'ts_paint', 04:49:45 INFO - 'tpaint', 04:49:45 INFO - 'sessionrestore', 04:49:45 INFO - 'sessionrestore_no_auto_restore', 04:49:45 INFO - 'tabpaint']}, 04:49:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['a11yr', 04:49:45 INFO - 'ts_paint', 04:49:45 INFO - 'tpaint', 04:49:45 INFO - 'sessionrestore', 04:49:45 INFO - 'sessionrestore_no_auto_restore', 04:49:45 INFO - 'tabpaint']}, 04:49:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['tsvgx', 04:49:45 INFO - 'tsvgr_opacity', 04:49:45 INFO - 'tart', 04:49:45 INFO - 'tscrollx', 04:49:45 INFO - 'cart', 04:49:45 INFO - 'tsvg_static']}, 04:49:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:49:45 INFO - 'tsvgr_opacity', 04:49:45 INFO - 'tart', 04:49:45 INFO - 'tscrollx', 04:49:45 INFO - 'cart', 04:49:45 INFO - 'tsvg_static']}, 04:49:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'talos_options': ['--disable-e10s'], 04:49:45 INFO - 'tests': ['tp5o']}, 04:49:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:49:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'talos_options': ['--disable-e10s', 04:49:45 INFO - '--xperf_path', 04:49:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:45 INFO - 'tests': ['tp5n']}, 04:49:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:49:45 INFO - 'talos_options': ['--xperf_path', 04:49:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:45 INFO - 'tests': ['tp5n']}}, 04:49:45 INFO - 'talos.zip': {'path': '', 04:49:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:49:45 INFO - Running post-action listener: _resource_record_post_action 04:49:45 INFO - [mozharness: 2017-03-20 11:49:45.048573Z] Finished populate-webroot step (success) 04:49:45 INFO - [mozharness: 2017-03-20 11:49:45.048714Z] Running create-virtualenv step. 04:49:45 INFO - Running pre-action listener: _resource_record_pre_action 04:49:45 INFO - Running main action method: create_virtualenv 04:49:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:49:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:49:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:49:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:49:45 INFO - Using real prefix '/tools/python27' 04:49:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:49:45 INFO - Installing distribute.............................................................................................................................................................................................done. 04:49:48 INFO - Installing pip.................done. 04:49:48 INFO - Return code: 0 04:49:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:49:49 INFO - Reading from file tmpfile_stdout 04:49:49 INFO - Output received: 04:49:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:49:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:49:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:49:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:49 INFO - 'HOME': '/Users/cltbld', 04:49:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:49 INFO - 'LOGNAME': 'cltbld', 04:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:49 INFO - 'MOZ_NO_REMOTE': '1', 04:49:49 INFO - 'NO_EM_RESTART': '1', 04:49:49 INFO - 'PAGER': '/bin/cat', 04:49:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:49 INFO - 'PWD': '/builds/slave/test', 04:49:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:49 INFO - 'SHELL': '/bin/bash', 04:49:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:49:49 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:49:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:49 INFO - 'USER': 'cltbld', 04:49:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:49 INFO - 'XPC_FLAGS': '0x0', 04:49:49 INFO - 'XPC_SERVICE_NAME': '0', 04:49:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:49:49 INFO - Cleaning up... 04:49:49 INFO - Return code: 0 04:49:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:49:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:49:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:49 INFO - 'HOME': '/Users/cltbld', 04:49:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:49 INFO - 'LOGNAME': 'cltbld', 04:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:49 INFO - 'MOZ_NO_REMOTE': '1', 04:49:49 INFO - 'NO_EM_RESTART': '1', 04:49:49 INFO - 'PAGER': '/bin/cat', 04:49:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:49 INFO - 'PWD': '/builds/slave/test', 04:49:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:49 INFO - 'SHELL': '/bin/bash', 04:49:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:49:49 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:49:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:49 INFO - 'USER': 'cltbld', 04:49:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:49 INFO - 'XPC_FLAGS': '0x0', 04:49:49 INFO - 'XPC_SERVICE_NAME': '0', 04:49:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:49 INFO - Downloading/unpacking psutil>=3.1.1 04:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:49:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:53 INFO - Installing collected packages: psutil 04:49:53 INFO - Running setup.py install for psutil 04:49:53 INFO - building 'psutil._psutil_osx' extension 04:49:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:49:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:49:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:49:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:49:54 INFO - building 'psutil._psutil_posix' extension 04:49:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:49:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:49:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:49:54 INFO - ^ 04:49:54 INFO - 1 warning generated. 04:49:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:49:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:54 INFO - Successfully installed psutil 04:49:54 INFO - Cleaning up... 04:49:54 INFO - Return code: 0 04:49:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:55 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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:55 INFO - 'HOME': '/Users/cltbld', 04:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:55 INFO - 'LOGNAME': 'cltbld', 04:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:55 INFO - 'MOZ_NO_REMOTE': '1', 04:49:55 INFO - 'NO_EM_RESTART': '1', 04:49:55 INFO - 'PAGER': '/bin/cat', 04:49:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:55 INFO - 'PWD': '/builds/slave/test', 04:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:55 INFO - 'SHELL': '/bin/bash', 04:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:49:55 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:55 INFO - 'USER': 'cltbld', 04:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:55 INFO - 'XPC_FLAGS': '0x0', 04:49:55 INFO - 'XPC_SERVICE_NAME': '0', 04:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:49:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:49:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:49:58 INFO - Installing collected packages: mozsystemmonitor 04:49:58 INFO - Running setup.py install for mozsystemmonitor 04:49:58 INFO - Successfully installed mozsystemmonitor 04:49:58 INFO - Cleaning up... 04:49:58 INFO - Return code: 0 04:49:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:49:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:49:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:58 INFO - 'HOME': '/Users/cltbld', 04:49:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:58 INFO - 'LOGNAME': 'cltbld', 04:49:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:58 INFO - 'MOZ_NO_REMOTE': '1', 04:49:58 INFO - 'NO_EM_RESTART': '1', 04:49:58 INFO - 'PAGER': '/bin/cat', 04:49:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:58 INFO - 'PWD': '/builds/slave/test', 04:49:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:58 INFO - 'SHELL': '/bin/bash', 04:49:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:49:58 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:49:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:58 INFO - 'USER': 'cltbld', 04:49:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:58 INFO - 'XPC_FLAGS': '0x0', 04:49:58 INFO - 'XPC_SERVICE_NAME': '0', 04:49:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:58 INFO - Downloading/unpacking jsonschema==2.5.1 04:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:50:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:02 INFO - Downloading functools32-3.2.3-2.tar.gz 04:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:50:02 INFO - warning: no files found matching '*.txt' 04:50:02 INFO - no previously-included directories found matching 'build' 04:50:02 INFO - no previously-included directories found matching 'dist' 04:50:02 INFO - no previously-included directories found matching '.git*' 04:50:02 INFO - Installing collected packages: jsonschema, functools32 04:50:02 INFO - Running setup.py install for functools32 04:50:02 INFO - warning: no files found matching '*.txt' 04:50:02 INFO - no previously-included directories found matching 'build' 04:50:02 INFO - no previously-included directories found matching 'dist' 04:50:02 INFO - no previously-included directories found matching '.git*' 04:50:02 INFO - Successfully installed jsonschema functools32 04:50:02 INFO - Cleaning up... 04:50:02 INFO - Return code: 0 04:50:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:02 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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:50:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:50:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:02 INFO - 'HOME': '/Users/cltbld', 04:50:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:02 INFO - 'LOGNAME': 'cltbld', 04:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:02 INFO - 'MOZ_NO_REMOTE': '1', 04:50:02 INFO - 'NO_EM_RESTART': '1', 04:50:02 INFO - 'PAGER': '/bin/cat', 04:50:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:02 INFO - 'PWD': '/builds/slave/test', 04:50:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:02 INFO - 'SHELL': '/bin/bash', 04:50:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:02 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:02 INFO - 'USER': 'cltbld', 04:50:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:02 INFO - 'XPC_FLAGS': '0x0', 04:50:02 INFO - 'XPC_SERVICE_NAME': '0', 04:50:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:50:02 INFO - Cleaning up... 04:50:02 INFO - Return code: 0 04:50:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:02 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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:50:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:50:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:50:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:02 INFO - 'HOME': '/Users/cltbld', 04:50:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:02 INFO - 'LOGNAME': 'cltbld', 04:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:02 INFO - 'MOZ_NO_REMOTE': '1', 04:50:02 INFO - 'NO_EM_RESTART': '1', 04:50:02 INFO - 'PAGER': '/bin/cat', 04:50:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:02 INFO - 'PWD': '/builds/slave/test', 04:50:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:02 INFO - 'SHELL': '/bin/bash', 04:50:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:02 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:02 INFO - 'USER': 'cltbld', 04:50:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:02 INFO - 'XPC_FLAGS': '0x0', 04:50:02 INFO - 'XPC_SERVICE_NAME': '0', 04:50:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:02 INFO - Downloading/unpacking blobuploader==1.2.4 04:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:05 INFO - Downloading blobuploader-1.2.4.tar.gz 04:50:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:50:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:50:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:07 INFO - Downloading docopt-0.6.1.tar.gz 04:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:50:07 INFO - Installing collected packages: blobuploader, requests, docopt 04:50:07 INFO - Running setup.py install for blobuploader 04:50:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:50:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:50:07 INFO - Running setup.py install for requests 04:50:07 INFO - Running setup.py install for docopt 04:50:07 INFO - Successfully installed blobuploader requests docopt 04:50:07 INFO - Cleaning up... 04:50:08 INFO - Return code: 0 04:50:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:08 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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:50:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:08 INFO - 'HOME': '/Users/cltbld', 04:50:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:08 INFO - 'LOGNAME': 'cltbld', 04:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:08 INFO - 'MOZ_NO_REMOTE': '1', 04:50:08 INFO - 'NO_EM_RESTART': '1', 04:50:08 INFO - 'PAGER': '/bin/cat', 04:50:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:08 INFO - 'PWD': '/builds/slave/test', 04:50:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:08 INFO - 'SHELL': '/bin/bash', 04:50:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:08 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:08 INFO - 'USER': 'cltbld', 04:50:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:08 INFO - 'XPC_FLAGS': '0x0', 04:50:08 INFO - 'XPC_SERVICE_NAME': '0', 04:50:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-CYwbvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-GrWq_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-rWJa2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-m2fbOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-zvkSp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Fn46zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:08 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JZLGgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-84_Zgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-EwWVXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-zOkMrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-lilBDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-fPL31k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-6QP2x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-D0Uy7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-grAbGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-X1wzIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HnD4L9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:50:10 INFO - Running setup.py install for manifestparser 04:50:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:50:10 INFO - Running setup.py install for mozcrash 04:50:10 INFO - Running setup.py install for mozdebug 04:50:10 INFO - Running setup.py install for mozdevice 04:50:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:50:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:50:10 INFO - Running setup.py install for mozfile 04:50:10 INFO - Running setup.py install for mozhttpd 04:50:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Running setup.py install for mozinfo 04:50:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Running setup.py install for mozInstall 04:50:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Running setup.py install for mozleak 04:50:11 INFO - Running setup.py install for mozlog 04:50:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Running setup.py install for moznetwork 04:50:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:50:11 INFO - Running setup.py install for mozprocess 04:50:11 INFO - Running setup.py install for mozprofile 04:50:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:50:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:50:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:50:12 INFO - Running setup.py install for mozrunner 04:50:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:50:12 INFO - Running setup.py install for mozscreenshot 04:50:12 INFO - Running setup.py install for moztest 04:50:12 INFO - Running setup.py install for mozversion 04:50:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:50:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:50:12 INFO - Cleaning up... 04:50:12 INFO - Return code: 0 04:50:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:12 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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:50:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:50:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:50:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:12 INFO - 'HOME': '/Users/cltbld', 04:50:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:12 INFO - 'LOGNAME': 'cltbld', 04:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:12 INFO - 'MOZ_NO_REMOTE': '1', 04:50:12 INFO - 'NO_EM_RESTART': '1', 04:50:12 INFO - 'PAGER': '/bin/cat', 04:50:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:12 INFO - 'PWD': '/builds/slave/test', 04:50:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:12 INFO - 'SHELL': '/bin/bash', 04:50:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:12 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:12 INFO - 'USER': 'cltbld', 04:50:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:12 INFO - 'XPC_FLAGS': '0x0', 04:50:12 INFO - 'XPC_SERVICE_NAME': '0', 04:50:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5qkp47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-BbZ8S5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xe0ttD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-oKUYPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tikG90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-875vP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JnXZH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-2BYPoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-eMIe55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:13 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-UVnOSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-h1VqnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uHrW3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:14 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)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-usnXTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bPNqdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-v160Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-CHtRec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-AwrN8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:50:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:17 INFO - Downloading blessings-1.6.tar.gz 04:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:50:17 INFO - Installing collected packages: blessings 04:50:17 INFO - Running setup.py install for blessings 04:50:17 INFO - Successfully installed blessings 04:50:17 INFO - Cleaning up... 04:50:17 INFO - Return code: 0 04:50:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:50:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:18 INFO - Reading from file tmpfile_stdout 04:50:18 INFO - Current package versions: 04:50:18 INFO - blessings == 1.6 04:50:18 INFO - blobuploader == 1.2.4 04:50:18 INFO - docopt == 0.6.1 04:50:18 INFO - functools32 == 3.2.3-2 04:50:18 INFO - jsonschema == 2.5.1 04:50:18 INFO - manifestparser == 1.1 04:50:18 INFO - mozInstall == 1.12 04:50:18 INFO - mozcrash == 1.0 04:50:18 INFO - mozdebug == 0.1 04:50:18 INFO - mozdevice == 0.49 04:50:18 INFO - mozfile == 1.2 04:50:18 INFO - mozhttpd == 0.7 04:50:18 INFO - mozinfo == 0.9 04:50:18 INFO - mozleak == 0.1 04:50:18 INFO - mozlog == 3.4 04:50:18 INFO - moznetwork == 0.27 04:50:18 INFO - mozprocess == 0.25 04:50:18 INFO - mozprofile == 0.28 04:50:18 INFO - mozrunner == 6.13 04:50:18 INFO - mozscreenshot == 0.1 04:50:18 INFO - mozsystemmonitor == 0.3 04:50:18 INFO - moztest == 0.8 04:50:18 INFO - mozversion == 1.4 04:50:18 INFO - psutil == 3.1.1 04:50:18 INFO - requests == 1.2.3 04:50:18 INFO - wsgiref == 0.1.2 04:50:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:18 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 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:50:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:50:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:50:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:18 INFO - 'HOME': '/Users/cltbld', 04:50:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:18 INFO - 'LOGNAME': 'cltbld', 04:50:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:18 INFO - 'MOZ_NO_REMOTE': '1', 04:50:18 INFO - 'NO_EM_RESTART': '1', 04:50:18 INFO - 'PAGER': '/bin/cat', 04:50:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:18 INFO - 'PWD': '/builds/slave/test', 04:50:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:18 INFO - 'SHELL': '/bin/bash', 04:50:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:18 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:18 INFO - 'USER': 'cltbld', 04:50:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:18 INFO - 'XPC_FLAGS': '0x0', 04:50:18 INFO - 'XPC_SERVICE_NAME': '0', 04:50:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:50:18 INFO - Cleaning up... 04:50:18 INFO - Return code: 0 04:50:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:50: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')]} 04:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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')]} 04:50:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029d7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029da768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd1a545870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, '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.K3vMzXUH09/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.uRYpDpUFK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:50: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', 'jsonschema'] in /builds/slave/test/build 04:50: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 jsonschema 04:50:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:18 INFO - 'HOME': '/Users/cltbld', 04:50:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:18 INFO - 'LOGNAME': 'cltbld', 04:50:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:18 INFO - 'MOZ_NO_REMOTE': '1', 04:50:18 INFO - 'NO_EM_RESTART': '1', 04:50:18 INFO - 'PAGER': '/bin/cat', 04:50:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:18 INFO - 'PWD': '/builds/slave/test', 04:50:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:18 INFO - 'SHELL': '/bin/bash', 04:50:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:18 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:18 INFO - 'USER': 'cltbld', 04:50:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:18 INFO - 'XPC_FLAGS': '0x0', 04:50:18 INFO - 'XPC_SERVICE_NAME': '0', 04:50:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:50:18 INFO - Cleaning up... 04:50:18 INFO - Return code: 0 04:50:18 INFO - Running post-action listener: _resource_record_post_action 04:50:18 INFO - Running post-action listener: _start_resource_monitoring 04:50:18 INFO - Starting resource monitoring. 04:50:18 INFO - [mozharness: 2017-03-20 11:50:18.601848Z] Finished create-virtualenv step (success) 04:50:18 INFO - [mozharness: 2017-03-20 11:50:18.602780Z] Running install step. 04:50:18 INFO - Running pre-action listener: _resource_record_pre_action 04:50:18 INFO - Running main action method: install 04:50:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:18 INFO - Reading from file tmpfile_stdout 04:50:18 INFO - Detecting whether we're running mozinstall >=1.0... 04:50:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:50:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:50:18 INFO - Reading from file tmpfile_stdout 04:50:18 INFO - Output received: 04:50:18 INFO - Usage: mozinstall [options] installer 04:50:18 INFO - Options: 04:50:18 INFO - -h, --help show this help message and exit 04:50:18 INFO - -d DEST, --destination=DEST 04:50:18 INFO - Directory to install application into. [default: 04:50:18 INFO - "/builds/slave/test"] 04:50:18 INFO - --app=APP Application being installed. [default: firefox] 04:50:18 INFO - mkdir: /builds/slave/test/build/application 04:50:18 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'] 04:50:18 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 04:50:39 INFO - Reading from file tmpfile_stdout 04:50:39 INFO - Output received: 04:50:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:50:39 INFO - Running post-action listener: _resource_record_post_action 04:50:39 INFO - [mozharness: 2017-03-20 11:50:39.670478Z] Finished install step (success) 04:50:39 INFO - [mozharness: 2017-03-20 11:50:39.670635Z] Running run-tests step. 04:50:39 INFO - Running pre-action listener: _resource_record_pre_action 04:50:39 INFO - Running main action method: run_tests 04:50:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:39 INFO - 2017-03-20 04:50:39.685 screenresolution[1640:7608] starting screenresolution argv=screenresolution get 04:50:39 INFO - 2017-03-20 04:50:39.687 screenresolution[1640:7608] Display 0: 1600x1200x32@60 04:50:39 INFO - 2017-03-20 04:50:39.693 screenresolution[1641:7610] starting screenresolution argv=screenresolution list 04:50:39 INFO - Available Modes on Display 0 04:50:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:39 INFO - Intel Iris: 04:50:39 INFO - Chipset Model: Intel Iris 04:50:39 INFO - Type: GPU 04:50:39 INFO - Bus: Built-In 04:50:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:39 INFO - Vendor: Intel (0x8086) 04:50:39 INFO - Device ID: 0x0a2e 04:50:39 INFO - Revision ID: 0x0009 04:50:39 INFO - Displays: 04:50:39 INFO - Display: 04:50:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:39 INFO - Display Serial Number: 1600x1200 60 04:50:39 INFO - Main Display: Yes 04:50:39 INFO - Mirror: Off 04:50:39 INFO - Online: Yes 04:50:39 INFO - Rotation: Supported 04:50:39 INFO - Return code: 0 04:50:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:50:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:50:39 INFO - Python 2.7.3 04:50:39 INFO - Return code: 0 04:50:39 INFO - grabbing minidump binary from tooltool 04:50:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:39 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 0x1027b5d30>, '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 0x1026dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027aabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:50:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:50:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:50:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:50:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:50:40 INFO - Return code: 0 04:50:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:50:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:50:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:50:40 INFO - ENV: RUST_BACKTRACE is now 1 04:50:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:50:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K3vMzXUH09/Render', 04:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:40 INFO - 'HOME': '/Users/cltbld', 04:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:40 INFO - 'LOGNAME': 'cltbld', 04:50:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:50:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:40 INFO - 'MOZ_NO_REMOTE': '1', 04:50:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:50:40 INFO - 'NO_EM_RESTART': '1', 04:50:40 INFO - 'PAGER': '/bin/cat', 04:50:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:40 INFO - 'PWD': '/builds/slave/test', 04:50:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:40 INFO - 'RUST_BACKTRACE': '1', 04:50:40 INFO - 'SHELL': '/bin/bash', 04:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uRYpDpUFK2/Listeners', 04:50:40 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 04:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:40 INFO - 'USER': 'cltbld', 04:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:40 INFO - 'XPC_FLAGS': '0x0', 04:50:40 INFO - 'XPC_SERVICE_NAME': '0', 04:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:50:40 INFO - mozversion application_buildid: 20170320031743 04:50:40 INFO - mozversion application_changeset: 1c9cf05afab07f505e360d404dfbaba81a1b4721 04:50:40 INFO - mozversion application_display_name: Nightly 04:50:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:50:40 INFO - mozversion application_name: Firefox 04:50:40 INFO - mozversion application_remotingname: firefox 04:50:40 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 04:50:40 INFO - mozversion application_vendor: Mozilla 04:50:40 INFO - mozversion application_version: 55.0a1 04:50:40 INFO - mozversion platform_buildid: 20170320031743 04:50:40 INFO - mozversion platform_changeset: 1c9cf05afab07f505e360d404dfbaba81a1b4721 04:50:40 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 04:50:40 INFO - mozversion platform_version: 55.0a1 04:50:40 INFO - using testdate: 1490010640 04:50:40 INFO - actual date: 1490010640 04:50:40 INFO - starting webserver on 'localhost:49240' 04:50:40 INFO - SUITE-START | Running 2 tests 04:50:40 INFO - TEST-START | dromaeo_css 04:50:40 INFO - operating with platform_type : mac_ 04:50:40 INFO - Initialising browser for dromaeo_css test... 04:50:40 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpoAY9uj/profile http://localhost:49240/getInfo.html) 04:50:42 INFO - PID 1651 | __metrics Screen width/height:1600/1200 04:50:42 INFO - PID 1651 | colorDepth:24 04:50:42 INFO - PID 1651 | Browser inner width/height: 1024/689 04:50:42 INFO - PID 1651 | __metrics 04:50:42 INFO - PID 1651 | 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" 04:50:42 INFO - PID 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:50:43 INFO - TEST-INFO | 1651: exit 0 04:50:43 INFO - Browser initialized. 04:50:43 INFO - Running cycle 1/1 for dromaeo_css test... 04:50:43 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpoAY9uj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:55 INFO - PID 1653 | RSS: Main: 233119744 04:50:55 INFO - PID 1653 | 04:53:59 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 04:54:01 INFO - PID 1653 | RSS: Main: 417869824 04:54:01 INFO - PID 1653 | 04:55:45 INFO - PID 1653 | ************************* 04:55:45 INFO - PID 1653 | A coding exception was thrown and uncaught in a Task. 04:55:45 INFO - PID 1653 | 04:55:45 ERROR - PID 1653 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:45 INFO - PID 1653 | Full stack: 04:55:45 INFO - PID 1653 | ************************* 04:57:04 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:57:05 INFO - PID 1653 | RSS: Main: 369762304 04:57:05 INFO - PID 1653 | 05:00:07 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 05:00:08 INFO - PID 1653 | RSS: Main: 339791872 05:00:08 INFO - PID 1653 | 05:03:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 05:03:11 INFO - PID 1653 | RSS: Main: 359120896 05:03:11 INFO - PID 1653 | 05:06:15 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:06:16 INFO - PID 1653 | RSS: Main: 334327808 05:06:16 INFO - PID 1653 | 05:09:19 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:09:20 INFO - PID 1653 | RSS: Main: 390365184 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | __start_tp_report 05:09:20 INFO - PID 1653 | _x_x_mozilla_page_load 05:09:20 INFO - PID 1653 | _x_x_mozilla_page_load_details 05:09:20 INFO - PID 1653 | |i|pagename|runs| 05:09:20 INFO - PID 1653 | |0;dojo.html;3382.8477718502663;3397.303552771682;3404.13194635368;3447.1726785571464;3475.3396854597627;5705.201271821938;5814.7092645367775;5974.044152935531;5980.745549090877;6073.453389194971;4876.853694389174;5950.464458198761;5981.551383646222;5985.281766188053;6009.789657361993;4516.277395616702;5093.974530127344;5130.820421285256;5207.505286997729;5225.790968361261;5324.467553244684;5358.2766326546;5367.141257398817;5375.489328513804;5423.647462914898;5213.556847667953;5216.295800067019;5261.263423120766;5266.710330931797;5329.813456529022;5294.92057619136;5320.600954928396;5332.066888294572;5341.866453338659;5366.651167674088;5249.553787928031;5257.342832145981;5257.7108259045735;5266.052110620087;5295.788168473295;5865.266841644794;5868.767558812655;5899.91150132744;5913.763449462016;5939.970300148515;6166.167567378437;6221.564490485668;6233.937660623381;6237.688115594202;6281.497480201573;6216.595921265149;6242.188515492957;6273.12176295319;6281.089242059876;6291.842703932392;5719.48524632785;5865.090910908773;5867.76528938842;5870.178175055496;5910.0248459004215;6227.408396202353;6253.7185826637915;6289.119523266746;6290.056491526216;6297.874042519124;6175.135481032659;6177.1969643945995;6211.2857021443115;6313.463355614732;6330.873382532415;6286.748530058747;6301.117843501914;6302.4958003359625;6307.653079080695;6313.558050936482;6138.232720909887;6252.874942501216;6269.153664255267;6292.874142517124;6308.022256550242;5351.464853514695;6210.441060304594;6235.688215589203;6241.687915604201;6279.811605651837;5057.974710126426;5058.241263810384;5073.061483625541;5093.643444958891;5110.565601923878;4475.037866858641;4496.640268778555;4508.436445444319;4516.728996260234;4556.84051058218;5802.448767367094;5869.7945571904775;5951.613145145551;5980.252468441445;6012.488938440194;6260.060990851409;6260.186175797117;6294.559380843389;6301.212348456443;6308.8422789429715;5852.975729247365;5861.267341582302;5862.179294898718;5868.589198756132;5877.353491115974;5472.398036216013;5478.808241711601;5479.04116779562;5482.451754824486;5547.972260138674;5511.972440137773;6270.372962703693;6347.777827776098;6348.492120630339;6357.268914074941;6241.344658810941;6279.183706118358;6294.590851594632;6300.9684951574945;6301.117843502005;6365.61806291624;6402.263739669997;6412.583182093335;6450.0969864218105;6474.384933431316;6308.905366419415;6319.2732835722645;6325;6336;6340.017297318923;5558.194061861089;6275.3724627536885;6289.9056514153235;6291.150694656163;6306.747730090745;6109.556495504338;6205.596636218631;6226.59527130735;6264.091706702594;6288.968555157195;6103.328633850362;6244.656543889948;6247.875042499216;6253.155823963889;6261.592996455216;6141.631502109888;6145.661988590492;6146.354632763674;6147.293061298008;6181.938180618136;6102.420270074336;6121.209103132385;6172.444479996821;6246.843828904132;6274.623522588659;6131.662758548323;6142.007694998287;6153.6923153841235;6167.044108163242;6175.320714721287 05:09:20 INFO - PID 1653 | |1;ext.html;708.2846325211535;772.6870617399952;791.2522666080555;862.3316929379733;870.3994243971663;28609.42781144376;29025.56461653077;29037.709622903763;29049.273768155806;29162.125136245893;28049.719502804965;37631;38019.61980380195;38088.80955595218;38128.809355953184;8803.691870784523;9016.549172541347;9116.8632470513;9120.726378208665;9121.817563648756;8972.820543589125;9149.67976120836;9154.908450915505;9160.541972901328;9161.725148245563;7145.214026457085;7314.305141011595;7316.451266155033;7316.890246646304;7318.231585683507;7285.526440781358;10555.47222638865;10768.138548916146;10784.460776961121;10797.676069717843;10461.476926153662;10527.73680657982;10569.894301056967;10572.735681607945;10576.52405641748;36989.815050924844;37346.25307493862;37365.62634373648;37392.626073739186;37432.877013689635;2852.7432531072304;3743.2139250757373;3758.586555478895;3791.33651610967;3932.3118454270675;27095.9540786938;27525.724742752518;28012.439751205067;28020.719792802014;28936.27659308513;3104.611923509561;3112.2063873712286;3124.156477751017;3220.677932206784;3237.255431250802;336630.31684841664;338011;338107;338194;338754;50866.745666272174;50906.49093509017;50918.49081509212;51007.724806879385;51041;53390.7330463353;53486.66283342947;53490.197647035326;53520.73239633777;53549.73225133849;14651.633709157144;14816.40734370613;14830.184339861195;14835.777463338058;14853.777193342108;12015.639530814089;12058.638240852788;12060.638180854588;12065.215760975509;12080.154389192849;11156.832647510293;12310.692232694255;12323.50705971751;12383.504659813507;12447.564335248222;4448.199324121689;4455.977720111444;4503.099380123988;4956.306117143603;5199.246109314204;721.4300702445114;787.6494959742975;849.2229609906866;863.9524826134623;879.0857508191409;24886.626700599507;25485.23544293674;25914.74085259161;25987.22038338853;26037.869810650827;908.5593487158723;916.863089768682;932.2029664636774;936.281922671891;953.9856902146473;13327.533536326182;13451.865481345258;13587.45650173982;13651.976101792405;13734.587962361145;343078;345723;346155;346291;361980;55959.72020139955;56979.43020569824;57048;57068.714656426455;57233;1385.8544852790515;1427.9428822847178;1434.684369438722;1435.1532595768128;1453.8255409350734;26368.8681556591;28121;28194.577081343385;28822.567661484674;28972.420551588428;44794;44861.551384485734;44869;44948.65154045384;44962.10075798531;372987;374318;374897;375017.1249143737;376147;30471;31376.372472550876;31430;31431.685683142874;31475;39787;40140.79929600333;40255.99360016023;40505.78482645525;41132.76601701954;33610;33644.49533256954;33700.662993369755;34579.4813077799;34898.82550587231;33407.33185336231;33438;34236.82881585576;34465.31069378649;34934.825325874226;33754.662453376135;34414;34463.82768086143;34489.48265776066;34512;25132.874335628207;29335.26661833386;29738.702612973593;30890.53664195084;31211.063668089995;19531.51171220674;22959.196428125604;23013.29776645316;23130.53738925246;23810.88094559516 05:09:20 INFO - PID 1653 | |2;jquery.html;381.1975790960029;386.70610336144523;398.3785294939892;419.26837668268865;429.6112018623147;12740;14973.625659358522;17368.826311736862;17482.300707971666;21120.683189752166;21100.472488187763;21729;21750.456238594044;22284.88857555714;22963.311100666964;6489.35106489352;6634.104395906539;6645.202575690924;6753.223379311393;6877.449804015691;4192.392103145042;6546.672666366662;6695.196576410814;6703;6720.899186512182;4433.157700036999;4529.524399938008;7881.17247688993;7892.092409372909;7913.485623434458;7625.428092892999;7780.377569794403;7903.841923161504;7976.202379762007;7992.720254791084;7245.311695388929;7637;7652.617369131521;7665.233476652346;7669.846603067907;27381.589276160874;27782.861085694443;28113.85943070292;28258;28695.71304286972;2818.6053952446673;2867.856607169633;2881.106856874355;2910.7962442629023;2942.7498662613702;13817.378217980215;16175.595610109724;16562.089085100313;16679.166041697867;16689.24898379576;1984.2063174730079;2018.7072874433125;2027.320847516069;2045.089934978935;2045.263705066175;157561;160024.199879001;161022;163793.36206638018;165011;17134.742978855076;21377.731571265846;21656.133754649953;21771.455713607287;21877.062128816964;36363;36433.81783091067;36579.634203657624;36607;36614.45078323827;10398.220133489867;10467.162626989822;10657.946710266398;10700.678979630624;10831;8378.874316885252;8395.25550345705;8419;8585.399022068521;8722.28384144218;10245.964452574623;10537.89462105369;10685.946570267099;10702.571897124028;10735.194860385502;4037.2038514825636;4196.496420429568;4221.282381995068;4247.17180149874;4252.787360631956;982.5873133284038;999.8250306196387;1010.5750891243312;1017.4056156800277;1022.2537547590152;22227.66658500124;22605.43486412853;22619.88690056539;22662.66006009911;22690;1014.8731408573929;1015.669907280137;1039.812833689943;1042.6663467690266;1053.1627356251797;9439.910520298696;10833.187510936712;10901.400422976812;11111.83332250017;11665.650030499099;667362.253340872;675011;675595.9727184131;679427;684273;112445;146853;151923;152213;153395.2330238364;1270.4092596942282;1320.0891384944357;1350.216874212954;1352.0738294268654;1356.816829727974;19078.904605476884;19278.421647350602;19474.513137171685;19525.414237572328;19551;52217;55176.72411637916;60225.69887150536;63002.36997629965;63228;72176.63911680409;75649.86525202016;84117.15882841093;86261.56869215865;88504;7970.521768693897;8080.636371363185;11570.537178512765;11776.646700598996;12512.937435312766;13742.244176570384;13789.586312410644;13795.862041379458;14340.928295358457;14466.493672721404;9979.800403992025;11197.104231661611;11326.660200194008;11573.942130289295;11799.76400472003;9917.355371900803;11248.887511124783;11714;11795.29228246308;11823.994960428116;10608.310459820086;12017.158798883995;12118.394080296126;12176.45205965716;12241.816372754594;12228.388580570758;12466.189697669623;12526.248425094524;12549.988002879425;12573.622791316275;11740.647780566596;11773.587924422603;12095.697607559881;12129.029677625593;12180.451879665614 05:09:20 INFO - PID 1653 | |3;mootools.html;1519.5973067137206;1560.3850015234227;1576.1016220754175;1597.9201039948;1599.032585285902;10296.330738501993;10446.425446600433;10953.452327383618;10958;10962.149841041337;10153.238507111959;10914.945425272863;10921;10927.617533386334;10931.45342732864;2322.3497420722224;2368.08828600989;2369.2536850891915;2369.2892132360307;2369.5853225685523;2311.7572654871246;2339.251439539348;2340.438294809242;2344.347235199189;2345.94135146621;2306.5041016181517;2314.8611083335054;2327.208749025331;2333.195047708546;2340.96488552671;2226.4100013496272;2231.6987206727044;2233.653783663535;2245.2702871566644;2245.5396643688086;2263.3775711679255;2322.7096612923383;2325.837191396603;2326.4532834783813;2328.4178955261186;11028.50371733274;11103.333799972028;11109.166812489015;11126.61056863006;11132.220744547885;5632.971835140798;5865.266841644794;5871.5596330274975;5880.029795083806;5886.175935369051;3270.771046026779;3380.9830950845335;3552.8223588820456;3589.174489867319;3612.3678356287546;1011.3931641015405;1098.8022156011914;1145.604766355606;1149.6953307373553;1225.4485481533345;14761.55715328542;14834.961552691317;14955;14971.401143954228;15012.249387530579;4205.96953746328;4229.196452673982;4280.315149576115;4515.887102822456;4595.4025976622825;11205.66383008511;11215.775684486265;11216;11218.607348742918;11229.38238396896;5496.835094947158;5498.5876059295715;5498.945010549924;5499.697516636624;5505.146702261156;4989.525995030466;5090.643654944119;5107.029664363833;5150.587952963754;5234;3022.5189657067876;3468.0809585459874;3565.4295312750355;3593.299306635181;3605.2694706541006;1013.2856336282903;1014.7868947521104;1026.9897301027042;1027.814993301198;1030.5568605499707;903.3721563513295;927.3091546797679;933.6498812945146;942.528735632184;974.9470571782458;5617.185508101385;6513.316101809241;6531.477481801445;6557.737690492327;6576.769813056616;925.3707478914403;944.9669261575949;968.0948313327061;973.7662960889336;987.3511236235504;3486;3944.36890097583;4015.297322968469;4107.466029416157;4170.749755014648;13087.541936032194;13568.253746043865;13593.320333983262;13620.387082581308;13743.450261989608;11844.940775296069;12292.754144917051;12641.936790316173;13098.738025239441;13189.868101318863;7330.853382932418;9023;9030.652030472125;9034.503102329434;9041.81916361669;5087.64386492942;6578.8355291118105;6663.267040625624;6767.661616919233;6820.897686534805;11165;11405.916437938382;11729.941350293195;11737.647870563897;11756.470958807045;10208.285420020527;11900.345480998629;11967.281963082243;12218.572349967708;12283.447244874182;8857.11428857109;9216.677416290398;9241.937177224436;9249.491277979776;9249.583768730557;9824.164945979386;9933.00669933001;10040.347377420445;10060;10070;8522.360822938428;8683.131686831266;8894.599743011451;8991.640334386551;9089.545522723705;8680.956595216983;8692.608832602675;8719.78200544966;8777.122287771428;8823.955880220814;7733.7679869604;7754.883676744967;7835.725749598737;7839.882401763864;7847.882281765664;5719.971400142973;5735.71321433935;5930.970345148246;5952.315483719428;5997.460228579274;5777.711114444345;5792.710364481674;6008.128821320972;6013.9699301503215;6164 05:09:20 INFO - PID 1653 | |4;prototype.html;367.82344474652166;373.55173791450255;381.5535823086988;384.84221469197615;387.2081593142024;5194.935038317145;5667.0932650775885;5798.855028624282;5807.390224026469;5845.912311315334;5158.638895277332;5539.972300138494;5578.41426650201;5627.971860140704;5638.520725738317;4679.976600116995;4699.415321573129;4703.247480403136;4715.457722361921;4718.97640511797;3956.604339566049;4566.703164294327;4729.52704729526;4733.148033353995;4739.317542272478;3943.507061617298;4617.30740388941;4618.35343051973;4650.930236046445;4671.182543054969;4607.262837945946;4616.561426664462;4629.560191781776;4632.907341853178;4654.930176047362;4252.213340531981;4276.444062271916;4283.993261583557;4285.271503844354;4319.287317592625;4962.379702537183;5322.33470816148;5534.972325138388;5548.167774833767;5647.29408823897;5408;5423.891522169534;5437.700926449043;5441.238226648273;5441.347038355383;5135.845924622267;5844;5998.3701711320555;6051.576389652726;6081.3310535841465;4376.715513491613;4550.340200670884;4753.35829662998;4764.166270902613;4770.499097594738;5255.86860328491;6087.878242435127;6295.118683384376;6344.6827658616885;6516.2506311773805;4292.785360732013;4763.166445871959;4896.951030489649;4915.852524424273;4957.380327459067;4981.875453113628;5036.345275114211;5040.974795126001;5051.696898186121;5058.620603454756;4706.764661766898;5269.496988930304;5321.760520776575;5405.702686352289;5463.235147079412;5905.257948905083;6002.459778619946;6081.817545473643;6095.725692343855;6110.883449440728;1581.9841801582068;1620.4814459372888;1742.7734394528884;1744.7208446648729;1744.842964133234;1323.530146797873;1376.1742954227384;1460.889723809891;1482.629342664334;1543.4135028689027;5007.198848184273;5331.760070796746;5556.333240011144;5608.803691870765;5645.915311270334;4804.25534042224;5866.266716660418;6147.323794382614;6186.319504854462;6216.129741836146;5384.488473595002;5639.971800140973;5647.548196144298;5692.203091567184;5712.714364281769;3262.857999700086;3281.8667865887364;3286.375588638151;3295.2091498040777;3300.1584595927884;6145.26256849172;6451.935480645134;6462.547621666438;6485.319041500669;6504.317046710122;5730.776335957914;6305.495560355161;6356.237251529754;6391.360863913665;6436.549441539048;5921.73352199152;6417.1657684500715;6452.903206452001;6467.482601391877;6516.478681705452;5233.540236089032;5292.708901010481;5667.4332566743;5903.144044113666;6106.1451396803595;5244.423113457593;5758.913616295843;6000.0699541800195;6159.692015399301;6203.348648391854;5549.805756798492;5927.4665280125;6230.345813689497;6257.468115210076;6433.839154021187;4780.306855505863;4845.6608037438455;4854.563089321978;4856.757162141949;4867.269909513601;5256.316678831728;5860.853478663067;6172.975242131233;6274.968625157027;6297.496200303873;5645.68948707825;5975.715221227457;6047.969760151347;6048.60684055535;6072.453479186895;5058.797648094035;5248.212768084818;5788.652680839163;6022.879542409215;6077.361877003028;5924.703764811656;6010.87978240424;6024.252966639626;6051.485623721856;6054.152418661303;5861.79833134215;6065.420607441353;6077.969610151921;6093.36019717941;6135.417135372132;5841.744025034638;5910.881782364415;5916.04770425767;5943.316518600243;5951.553633477593 05:09:20 INFO - PID 1653 | |5;yui.html;754.5020286610838;773.0993392697504;799.1289494451049;808.696738722979;820.2859594221303;15720.764188537181;15933.601659958507;15940.760888586681;16034.759478607815;16041.919790401033;11877.287362758241;14348.569542913729;14373.065750726222;14377.137371757703;14398.640033999156;918.6371383303591;932.2914584915462;949.3307218411004;951.4671783801059;954.3987288008545;948.3314263444255;968.2737946540095;969.2004096620282;970.1559643110504;970.4225985538593;1003.8343673293898;1008.9344192627456;1010.9191264698843;1010.9292349535535;1011.0092109732467;1004.0912973758724;1004.6182450668699;1005.0602686488083;1007.1338648762058;1007.2848277722862;743.3978477433242;743.5575832379725;745.0649434959146;745.2100773180446;745.8769303064988;15836.970596911164;16183.676326473522;16245.18774061304;16323.34706611746;16417.67164656712;12425.62723118308;12530.310832904186;12767.463656848835;12841;12895.806562901564;9807.656732014417;11764.470598823074;11779.411029448578;11921.582744603897;12078.094142939315;3648.1973965727498;3813.771173729585;3857;3892.0853599404118;3899.7075219358376;108142.37786433131;110186;110905.9999999992;110924;114463.42768286105;9071.501067441353;10542.631007914686;10917.235793494538;11076.335419874673;11322.547098115983;23523.059077636703;23837.404064898168;23889;24140.155094571604;24141.51716965651;7713.460057796013;7790.8052298691855;7887.053553573608;7892.368610511146;8099.230573095547;4157.833686652561;4214.931767692767;4235.53409124996;4310.504292006397;4469.865904022885;3426.6059403168456;3455.3780319542725;3526.2947410518;3526.488659144461;3534.0281422608987;602.3825578781783;607.7601692547154;614.827848202504;620.9658468784261;643.5655932245716;633.3539789414771;663.9800805975732;681.9659017049128;686.193722376216;697.9286794770105;8920.506774309612;10254.384736915808;10265.743356416;10401.27191096616;10544.525496352682;659.5416185750953;682.3279070115932;690.6477696374784;701.5930760159096;735.88961655751;4850.216830871926;4904.754762261872;4912.803487860517;4938.407391113089;4951.232558953368;84588;94587.52706236424;98435;100406.49390259152;103233.48383258036;85683;87030.56484717535;88287.55856220677;89633.20733585423;90512.54743726371;59722;78348;78821.60589197018;79810;80399.79400308883;8643.740687779376;10587.417692027013;10608.787824243625;10788.460576971276;11031.558737650725;45665;46442.37394769188;47951.5204847961;48851.31317777556;49333.26000110073;56036;66606.6768649321;67235;68341;68846.65576672084;13426;13581.932090339486;13667.658308542366;13772.173669579857;13800.585982420544;14507.51360328795;14631.707365852837;14753.926230369208;14773.556793296219;15096.698066038838;13651;13753.312334383441;13777.448902043807;13811.654708631962;14356;13231.536955969626;13419.664508387368;13743.725125497635;14071;14307.57077287683;13198.550638077773;13418.261995590336;13892.23592702411;13905.72188556203;14021.719565608426;10771.838422423512;10994.780104397707;11225.158113141384;11418.200725949104;11487;10432.947835261079;10655.20085993569;10719.303245289031;11162.665120046411;11292.943535282271 05:09:20 INFO - PID 1653 | __end_tp_report 05:09:20 INFO - PID 1653 | __start_cc_report 05:09:20 INFO - PID 1653 | _x_x_mozilla_cycle_collect,1950 05:09:20 INFO - PID 1653 | __end_cc_report 05:09:20 INFO - PID 1653 | __startTimestamp1490011759998__endTimestamp 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | ------- Summary: start ------- 05:09:20 INFO - PID 1653 | Number of tests: 6 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | [#0] dojo.html Cycles:165 Average:5822.50 Median:6131.66 stddev:645.25 (10.5%) stddev-sans-first:618.19 05:09:20 INFO - PID 1653 | Values: 3382.8 3397.3 3404.1 3447.2 3475.3 5705.2 5814.7 5974.0 5980.7 6073.5 4876.9 5950.5 5981.6 5985.3 6009.8 4516.3 5094.0 5130.8 5207.5 5225.8 5324.5 5358.3 5367.1 5375.5 5423.6 5213.6 5216.3 5261.3 5266.7 5329.8 5294.9 5320.6 5332.1 5341.9 5366.7 5249.6 5257.3 5257.7 5266.1 5295.8 5865.3 5868.8 5899.9 5913.8 5940.0 6166.2 6221.6 6233.9 6237.7 6281.5 6216.6 6242.2 6273.1 6281.1 6291.8 5719.5 5865.1 5867.8 5870.2 5910.0 6227.4 6253.7 6289.1 6290.1 6297.9 6175.1 6177.2 6211.3 6313.5 6330.9 6286.7 6301.1 6302.5 6307.7 6313.6 6138.2 6252.9 6269.2 6292.9 6308.0 5351.5 6210.4 6235.7 6241.7 6279.8 5058.0 5058.2 5073.1 5093.6 5110.6 4475.0 4496.6 4508.4 4516.7 4556.8 5802.4 5869.8 5951.6 5980.3 6012.5 6260.1 6260.2 6294.6 6301.2 6308.8 5853.0 5861.3 5862.2 5868.6 5877.4 5472.4 5478.8 5479.0 5482.5 5548.0 5512.0 6270.4 6347.8 6348.5 6357.3 6241.3 6279.2 6294.6 6301.0 6301.1 6365.6 6402.3 6412.6 6450.1 6474.4 6308.9 6319.3 6325.0 6336.0 6340.0 5558.2 6275.4 6289.9 6291.2 6306.7 6109.6 6205.6 6226.6 6264.1 6289.0 6103.3 6244.7 6247.9 6253.2 6261.6 6141.6 6145.7 6146.4 6147.3 6181.9 6102.4 6121.2 6172.4 6246.8 6274.6 6131.7 6142.0 6153.7 6167.0 6175.3 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | [#1] ext.html Cycles:180 Average:49757.10 Median:34464.57 stddev:93382.45 (271.0%) stddev-sans-first:93571.80 05:09:20 INFO - PID 1653 | Values: 708.3 772.7 791.3 862.3 870.4 28609.4 29025.6 29037.7 29049.3 29162.1 28049.7 37631.0 38019.6 38088.8 38128.8 8803.7 9016.5 9116.9 9120.7 9121.8 8972.8 9149.7 9154.9 9160.5 9161.7 7145.2 7314.3 7316.5 7316.9 7318.2 7285.5 10555.5 10768.1 10784.5 10797.7 10461.5 10527.7 10569.9 10572.7 10576.5 36989.8 37346.3 37365.6 37392.6 37432.9 2852.7 3743.2 3758.6 3791.3 3932.3 27096.0 27525.7 28012.4 28020.7 28936.3 3104.6 3112.2 3124.2 3220.7 3237.3 336630.3 338011.0 338107.0 338194.0 338754.0 50866.7 50906.5 50918.5 51007.7 51041.0 53390.7 53486.7 53490.2 53520.7 53549.7 14651.6 14816.4 14830.2 14835.8 14853.8 12015.6 12058.6 12060.6 12065.2 12080.2 11156.8 12310.7 12323.5 12383.5 12447.6 4448.2 4456.0 4503.1 4956.3 5199.2 721.4 787.6 849.2 864.0 879.1 24886.6 25485.2 25914.7 25987.2 26037.9 908.6 916.9 932.2 936.3 954.0 13327.5 13451.9 13587.5 13652.0 13734.6 343078.0 345723.0 346155.0 346291.0 361980.0 55959.7 56979.4 57048.0 57068.7 57233.0 1385.9 1427.9 1434.7 1435.2 1453.8 26368.9 28121.0 28194.6 28822.6 28972.4 44794.0 44861.6 44869.0 44948.7 44962.1 372987.0 374318.0 374897.0 375017.1 376147.0 30471.0 31376.4 31430.0 31431.7 31475.0 39787.0 40140.8 40256.0 40505.8 41132.8 33610.0 33644.5 33700.7 34579.5 34898.8 33407.3 33438.0 34236.8 34465.3 34934.8 33754.7 34414.0 34463.8 34489.5 34512.0 25132.9 29335.3 29738.7 30890.5 31211.1 19531.5 22959.2 23013.3 23130.5 23810.9 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | [#2] jquery.html Cycles:180 Average:40960.82 Median:21516.93 stddev:113382.96 (526.9%) stddev-sans-first:113660.09 05:09:20 INFO - PID 1653 | Values: 381.2 386.7 398.4 419.3 429.6 12740.0 14973.6 17368.8 17482.3 21120.7 21100.5 21729.0 21750.5 22284.9 22963.3 6489.4 6634.1 6645.2 6753.2 6877.4 4192.4 6546.7 6695.2 6703.0 6720.9 4433.2 4529.5 7881.2 7892.1 7913.5 7625.4 7780.4 7903.8 7976.2 7992.7 7245.3 7637.0 7652.6 7665.2 7669.8 27381.6 27782.9 28113.9 28258.0 28695.7 2818.6 2867.9 2881.1 2910.8 2942.7 13817.4 16175.6 16562.1 16679.2 16689.2 1984.2 2018.7 2027.3 2045.1 2045.3 157561.0 160024.2 161022.0 163793.4 165011.0 17134.7 21377.7 21656.1 21771.5 21877.1 36363.0 36433.8 36579.6 36607.0 36614.5 10398.2 10467.2 10657.9 10700.7 10831.0 8378.9 8395.3 8419.0 8585.4 8722.3 10246.0 10537.9 10685.9 10702.6 10735.2 4037.2 4196.5 4221.3 4247.2 4252.8 982.6 999.8 1010.6 1017.4 1022.3 22227.7 22605.4 22619.9 22662.7 22690.0 1014.9 1015.7 1039.8 1042.7 1053.2 9439.9 10833.2 10901.4 11111.8 11665.7 667362.3 675011.0 675596.0 679427.0 684273.0 112445.0 146853.0 151923.0 152213.0 153395.2 1270.4 1320.1 1350.2 1352.1 1356.8 19078.9 19278.4 19474.5 19525.4 19551.0 52217.0 55176.7 60225.7 63002.4 63228.0 72176.6 75649.9 84117.2 86261.6 88504.0 7970.5 8080.6 11570.5 11776.6 12512.9 13742.2 13789.6 13795.9 14340.9 14466.5 9979.8 11197.1 11326.7 11573.9 11799.8 9917.4 11248.9 11714.0 11795.3 11824.0 10608.3 12017.2 12118.4 12176.5 12241.8 12228.4 12466.2 12526.2 12550.0 12573.6 11740.6 11773.6 12095.7 12129.0 12180.5 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | [#3] mootools.html Cycles:180 Average:6521.60 Median:3424.53 stddev:4074.52 (119.0%) stddev-sans-first:4068.62 05:09:20 INFO - PID 1653 | Values: 1519.6 1560.4 1576.1 1597.9 1599.0 10296.3 10446.4 10953.5 10958.0 10962.1 10153.2 10914.9 10921.0 10927.6 10931.5 2322.3 2368.1 2369.3 2369.3 2369.6 2311.8 2339.3 2340.4 2344.3 2345.9 2306.5 2314.9 2327.2 2333.2 2341.0 2226.4 2231.7 2233.7 2245.3 2245.5 2263.4 2322.7 2325.8 2326.5 2328.4 11028.5 11103.3 11109.2 11126.6 11132.2 5633.0 5865.3 5871.6 5880.0 5886.2 3270.8 3381.0 3552.8 3589.2 3612.4 1011.4 1098.8 1145.6 1149.7 1225.4 14761.6 14835.0 14955.0 14971.4 15012.2 4206.0 4229.2 4280.3 4515.9 4595.4 11205.7 11215.8 11216.0 11218.6 11229.4 5496.8 5498.6 5498.9 5499.7 5505.1 4989.5 5090.6 5107.0 5150.6 5234.0 3022.5 3468.1 3565.4 3593.3 3605.3 1013.3 1014.8 1027.0 1027.8 1030.6 903.4 927.3 933.6 942.5 974.9 5617.2 6513.3 6531.5 6557.7 6576.8 925.4 945.0 968.1 973.8 987.4 3486.0 3944.4 4015.3 4107.5 4170.7 13087.5 13568.3 13593.3 13620.4 13743.5 11844.9 12292.8 12641.9 13098.7 13189.9 7330.9 9023.0 9030.7 9034.5 9041.8 5087.6 6578.8 6663.3 6767.7 6820.9 11165.0 11405.9 11729.9 11737.6 11756.5 10208.3 11900.3 11967.3 12218.6 12283.4 8857.1 9216.7 9241.9 9249.5 9249.6 9824.2 9933.0 10040.3 10060.0 10070.0 8522.4 8683.1 8894.6 8991.6 9089.5 8681.0 8692.6 8719.8 8777.1 8824.0 7733.8 7754.9 7835.7 7839.9 7847.9 5720.0 5735.7 5931.0 5952.3 5997.5 5777.7 5792.7 6008.1 6014.0 6164.0 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | [#4] prototype.html Cycles:180 Average:5082.40 Median:5499.10 stddev:1392.40 (25.3%) stddev-sans-first:1350.59 05:09:20 INFO - PID 1653 | Values: 367.8 373.6 381.6 384.8 387.2 5194.9 5667.1 5798.9 5807.4 5845.9 5158.6 5540.0 5578.4 5628.0 5638.5 4680.0 4699.4 4703.2 4715.5 4719.0 3956.6 4566.7 4729.5 4733.1 4739.3 3943.5 4617.3 4618.4 4650.9 4671.2 4607.3 4616.6 4629.6 4632.9 4654.9 4252.2 4276.4 4284.0 4285.3 4319.3 4962.4 5322.3 5535.0 5548.2 5647.3 5408.0 5423.9 5437.7 5441.2 5441.3 5135.8 5844.0 5998.4 6051.6 6081.3 4376.7 4550.3 4753.4 4764.2 4770.5 5255.9 6087.9 6295.1 6344.7 6516.3 4292.8 4763.2 4897.0 4915.9 4957.4 4981.9 5036.3 5041.0 5051.7 5058.6 4706.8 5269.5 5321.8 5405.7 5463.2 5905.3 6002.5 6081.8 6095.7 6110.9 1582.0 1620.5 1742.8 1744.7 1744.8 1323.5 1376.2 1460.9 1482.6 1543.4 5007.2 5331.8 5556.3 5608.8 5645.9 4804.3 5866.3 6147.3 6186.3 6216.1 5384.5 5640.0 5647.5 5692.2 5712.7 3262.9 3281.9 3286.4 3295.2 3300.2 6145.3 6451.9 6462.5 6485.3 6504.3 5730.8 6305.5 6356.2 6391.4 6436.5 5921.7 6417.2 6452.9 6467.5 6516.5 5233.5 5292.7 5667.4 5903.1 6106.1 5244.4 5758.9 6000.1 6159.7 6203.3 5549.8 5927.5 6230.3 6257.5 6433.8 4780.3 4845.7 4854.6 4856.8 4867.3 5256.3 5860.9 6173.0 6275.0 6297.5 5645.7 5975.7 6048.0 6048.6 6072.5 5058.8 5248.2 5788.7 6022.9 6077.4 5924.7 6010.9 6024.3 6051.5 6054.2 5861.8 6065.4 6078.0 6093.4 6135.4 5841.7 5910.9 5916.0 5943.3 5951.6 05:09:20 INFO - PID 1653 | 05:09:20 INFO - PID 1653 | [#5] yui.html Cycles:180 Average:20348.05 Median:24014.58 stddev:28982.40 (120.7%) stddev-sans-first:29026.36 05:09:20 INFO - PID 1653 | Values: 754.5 773.1 799.1 808.7 820.3 15720.8 15933.6 15940.8 16034.8 16041.9 11877.3 14348.6 14373.1 14377.1 14398.6 918.6 932.3 949.3 951.5 954.4 948.3 968.3 969.2 970.2 970.4 1003.8 1008.9 1010.9 1010.9 1011.0 1004.1 1004.6 1005.1 1007.1 1007.3 743.4 743.6 745.1 745.2 745.9 15837.0 16183.7 16245.2 16323.3 16417.7 12425.6 12530.3 12767.5 12841.0 12895.8 9807.7 11764.5 11779.4 11921.6 12078.1 3648.2 3813.8 3857.0 3892.1 3899.7 108142.4 110186.0 110906.0 110924.0 114463.4 9071.5 10542.6 10917.2 11076.3 11322.5 23523.1 23837.4 23889.0 24140.2 24141.5 7713.5 7790.8 7887.1 7892.4 8099.2 4157.8 4214.9 4235.5 4310.5 4469.9 3426.6 3455.4 3526.3 3526.5 3534.0 602.4 607.8 614.8 621.0 643.6 633.4 664.0 682.0 686.2 697.9 8920.5 10254.4 10265.7 10401.3 10544.5 659.5 682.3 690.6 701.6 735.9 4850.2 4904.8 4912.8 4938.4 4951.2 84588.0 94587.5 98435.0 100406.5 103233.5 85683.0 87030.6 88287.6 89633.2 90512.5 59722.0 78348.0 78821.6 79810.0 80399.8 8643.7 10587.4 10608.8 10788.5 11031.6 45665.0 46442.4 47951.5 48851.3 49333.3 56036.0 66606.7 67235.0 68341.0 68846.7 13426.0 13581.9 13667.7 13772.2 13800.6 14507.5 14631.7 14753.9 14773.6 15096.7 13651.0 13753.3 13777.4 13811.7 14356.0 13231.5 13419.7 13743.7 14071.0 14307.6 13198.6 13418.3 13892.2 13905.7 14021.7 10771.8 10994.8 11225.2 11418.2 11487.0 10432.9 10655.2 10719.3 11162.7 11292.9 05:09:20 INFO - PID 1653 | -------- Summary: end -------- 05:09:20 INFO - PID 1653 | 05:09:20 INFO - TEST-INFO | 1653: exit 0 05:09:20 INFO - TEST-OK | dromaeo_css | took 1120451ms 05:09:20 INFO - TEST-START | kraken 05:09:20 INFO - operating with platform_type : mac_ 05:09:20 INFO - Initialising browser for kraken test... 05:09:20 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpMhgla3/profile http://localhost:49240/getInfo.html) 05:09:23 INFO - PID 1660 | __metrics Screen width/height:1600/1200 05:09:23 INFO - PID 1660 | colorDepth:24 05:09:23 INFO - PID 1660 | Browser inner width/height: 1024/689 05:09:23 INFO - PID 1660 | __metrics 05:09:23 INFO - PID 1660 | 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" 05:09:23 INFO - PID 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:09:24 INFO - TEST-INFO | 1660: exit 0 05:09:24 INFO - Browser initialized. 05:09:24 INFO - Running cycle 1/1 for kraken test... 05:09:24 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpMhgla3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:36 INFO - PID 1661 | RSS: Main: 234934272 05:09:36 INFO - PID 1661 | 05:09:39 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 05:09:40 INFO - PID 1661 | RSS: Main: 284921856 05:09:40 INFO - PID 1661 | 05:09:42 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 05:09:43 INFO - PID 1661 | RSS: Main: 287518720 05:09:43 INFO - PID 1661 | 05:09:46 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 05:09:47 INFO - PID 1661 | RSS: Main: 378576896 05:09:47 INFO - PID 1661 | 05:09:49 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 05:09:50 INFO - PID 1661 | RSS: Main: 281677824 05:09:50 INFO - PID 1661 | 05:09:52 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:09:52 INFO - PID 1661 | RSS: Main: 286253056 05:09:52 INFO - PID 1661 | 05:09:55 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 05:09:56 INFO - PID 1661 | RSS: Main: 328503296 05:09:56 INFO - PID 1661 | 05:09:59 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 05:09:59 INFO - PID 1661 | RSS: Main: 341925888 05:09:59 INFO - PID 1661 | 05:10:02 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 05:10:03 INFO - PID 1661 | RSS: Main: 350355456 05:10:03 INFO - PID 1661 | 05:10:04 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:10:05 INFO - PID 1661 | RSS: Main: 302415872 05:10:05 INFO - PID 1661 | 05:10:07 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:10:08 INFO - PID 1661 | RSS: Main: 307617792 05:10:08 INFO - PID 1661 | 05:10:10 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:10:10 INFO - PID 1661 | RSS: Main: 308707328 05:10:10 INFO - PID 1661 | 05:10:13 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:10:14 INFO - PID 1661 | RSS: Main: 325337088 05:10:14 INFO - PID 1661 | 05:10:17 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:10:18 INFO - PID 1661 | RSS: Main: 319803392 05:10:18 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 05:10:20 INFO - PID 1661 | RSS: Main: 381476864 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | __start_tp_report 05:10:20 INFO - PID 1661 | _x_x_mozilla_page_load 05:10:20 INFO - PID 1661 | _x_x_mozilla_page_load_details 05:10:20 INFO - PID 1661 | |i|pagename|runs| 05:10:20 INFO - PID 1661 | |0;ai-astar;115;117;115;116;117;114;116;117;117;116 05:10:20 INFO - PID 1661 | |1;audio-beat-detection;139;109;110;163;137;137;121;113;133;113 05:10:20 INFO - PID 1661 | |2;audio-dft;185;198;258;207;187;185;183;185;185;184 05:10:20 INFO - PID 1661 | |3;audio-fft;77;60;60;89;64;59;59;60;74;77 05:10:20 INFO - PID 1661 | |4;audio-oscillator;86;72;85;69;70;69;84;69;68;84 05:10:20 INFO - PID 1661 | |5;imaging-gaussian-blur;87;88;87;87;87;87;87;87;87;86 05:10:20 INFO - PID 1661 | |6;imaging-darkroom;94;95;94;96;95;95;95;94;94;95 05:10:20 INFO - PID 1661 | |7;imaging-desaturate;86;84;84;84;86;84;83;86;84;87 05:10:20 INFO - PID 1661 | |8;json-parse-financial;65;64;64;64;62;62;63;62;62;60 05:10:20 INFO - PID 1661 | |9;json-stringify-tinderbox;53;54;64;67;67;61;57;59;59;62 05:10:20 INFO - PID 1661 | |10;stanford-crypto-aes;84;85;82;83;84;82;82;84;81;99 05:10:20 INFO - PID 1661 | |11;stanford-crypto-ccm;145;140;138;164;155;145;139;135;179;139 05:10:20 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;231;191;186;190;220;200;193;214;206;184 05:10:20 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;68;68;67;67;67;69;66;67;69;67 05:10:20 INFO - PID 1661 | __end_tp_report 05:10:20 INFO - PID 1661 | __start_cc_report 05:10:20 INFO - PID 1661 | _x_x_mozilla_cycle_collect,2187 05:10:20 INFO - PID 1661 | __end_cc_report 05:10:20 INFO - PID 1661 | __startTimestamp1490011820500__endTimestamp 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | ------- Summary: start ------- 05:10:20 INFO - PID 1661 | Number of tests: 14 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.50 stddev:1.05 (0.9%) stddev-sans-first:1.05 05:10:20 INFO - PID 1661 | Values: 115.0 117.0 115.0 116.0 117.0 114.0 116.0 117.0 117.0 116.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:135.00 stddev:17.37 (12.9%) stddev-sans-first:17.91 05:10:20 INFO - PID 1661 | Values: 139.0 109.0 110.0 163.0 137.0 137.0 121.0 113.0 133.0 113.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:195.70 Median:186.00 stddev:23.20 (12.5%) stddev-sans-first:24.29 05:10:20 INFO - PID 1661 | Values: 185.0 198.0 258.0 207.0 187.0 185.0 183.0 185.0 185.0 184.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:67.90 Median:69.00 stddev:10.59 (15.3%) stddev-sans-first:10.71 05:10:20 INFO - PID 1661 | Values: 77.0 60.0 60.0 89.0 64.0 59.0 59.0 60.0 74.0 77.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:78.00 stddev:7.96 (10.2%) stddev-sans-first:7.50 05:10:20 INFO - PID 1661 | Values: 86.0 72.0 85.0 69.0 70.0 69.0 84.0 69.0 68.0 84.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 05:10:20 INFO - PID 1661 | Values: 87.0 88.0 87.0 87.0 87.0 87.0 87.0 87.0 87.0 86.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 05:10:20 INFO - PID 1661 | Values: 94.0 95.0 94.0 96.0 95.0 95.0 95.0 94.0 94.0 95.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.32 (1.5%) stddev-sans-first:1.32 05:10:20 INFO - PID 1661 | Values: 86.0 84.0 84.0 84.0 86.0 84.0 83.0 86.0 84.0 87.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.50 stddev:1.48 (2.3%) stddev-sans-first:1.33 05:10:20 INFO - PID 1661 | Values: 65.0 64.0 64.0 64.0 62.0 62.0 63.0 62.0 62.0 60.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:61.50 stddev:4.88 (7.9%) stddev-sans-first:4.40 05:10:20 INFO - PID 1661 | Values: 53.0 54.0 64.0 67.0 67.0 61.0 57.0 59.0 59.0 62.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:84.00 stddev:5.21 (6.2%) stddev-sans-first:5.52 05:10:20 INFO - PID 1661 | Values: 84.0 85.0 82.0 83.0 84.0 82.0 82.0 84.0 81.0 99.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:145.00 stddev:14.06 (9.7%) stddev-sans-first:14.87 05:10:20 INFO - PID 1661 | Values: 145.0 140.0 138.0 164.0 155.0 145.0 139.0 135.0 179.0 139.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.50 Median:203.00 stddev:15.82 (7.8%) stddev-sans-first:12.68 05:10:20 INFO - PID 1661 | Values: 231.0 191.0 186.0 190.0 220.0 200.0 193.0 214.0 206.0 184.0 05:10:20 INFO - PID 1661 | 05:10:20 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:0.97 (1.4%) stddev-sans-first:1.01 05:10:20 INFO - PID 1661 | Values: 68.0 68.0 67.0 67.0 67.0 69.0 66.0 67.0 69.0 67.0 05:10:20 INFO - PID 1661 | -------- Summary: end -------- 05:10:20 INFO - PID 1661 | 05:10:21 INFO - TEST-INFO | 1661: exit 0 05:10:21 INFO - TEST-OK | kraken | took 60726ms 05:10:21 INFO - SUITE-END | took 1181s 05:10:21 INFO - Completed test suite (00:19:41) 05:10:21 INFO - javascript benchmark 05:10:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7724.542721967073, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3382.8477718502663, 3397.303552771682, 3404.13194635368, 3447.1726785571464, 3475.3396854597627, 5705.201271821938, 5814.7092645367775, 5974.044152935531, 5980.745549090877, 6073.453389194971, 4876.853694389174, 5950.464458198761, 5981.551383646222, 5985.281766188053, 6009.789657361993, 4516.277395616702, 5093.974530127344, 5130.820421285256, 5207.505286997729, 5225.790968361261, 5324.467553244684, 5358.2766326546, 5367.141257398817, 5375.489328513804, 5423.647462914898, 5213.556847667953, 5216.295800067019, 5261.263423120766, 5266.710330931797, 5329.813456529022, 5294.92057619136, 5320.600954928396, 5332.066888294572, 5341.866453338659, 5366.651167674088, 5249.553787928031, 5257.342832145981, 5257.7108259045735, 5266.052110620087, 5295.788168473295, 5865.266841644794, 5868.767558812655, 5899.91150132744, 5913.763449462016, 5939.970300148515, 6166.167567378437, 6221.564490485668, 6233.937660623381, 6237.688115594202, 6281.497480201573, 6216.595921265149, 6242.188515492957, 6273.12176295319, 6281.089242059876, 6291.842703932392, 5719.48524632785, 5865.090910908773, 5867.76528938842, 5870.178175055496, 5910.0248459004215, 6227.408396202353, 6253.7185826637915, 6289.119523266746, 6290.056491526216, 6297.874042519124, 6175.135481032659, 6177.1969643945995, 6211.2857021443115, 6313.463355614732, 6330.873382532415, 6286.748530058747, 6301.117843501914, 6302.4958003359625, 6307.653079080695, 6313.558050936482, 6138.232720909887, 6252.874942501216, 6269.153664255267, 6292.874142517124, 6308.022256550242, 5351.464853514695, 6210.441060304594, 6235.688215589203, 6241.687915604201, 6279.811605651837, 5057.974710126426, 5058.241263810384, 5073.061483625541, 5093.643444958891, 5110.565601923878, 4475.037866858641, 4496.640268778555, 4508.436445444319, 4516.728996260234, 4556.84051058218, 5802.448767367094, 5869.7945571904775, 5951.613145145551, 5980.252468441445, 6012.488938440194, 6260.060990851409, 6260.186175797117, 6294.559380843389, 6301.212348456443, 6308.8422789429715, 5852.975729247365, 5861.267341582302, 5862.179294898718, 5868.589198756132, 5877.353491115974, 5472.398036216013, 5478.808241711601, 5479.04116779562, 5482.451754824486, 5547.972260138674, 5511.972440137773, 6270.372962703693, 6347.777827776098, 6348.492120630339, 6357.268914074941, 6241.344658810941, 6279.183706118358, 6294.590851594632, 6300.9684951574945, 6301.117843502005, 6365.61806291624, 6402.263739669997, 6412.583182093335, 6450.0969864218105, 6474.384933431316, 6308.905366419415, 6319.2732835722645, 6325.0, 6336.0, 6340.017297318923, 5558.194061861089, 6275.3724627536885, 6289.9056514153235, 6291.150694656163, 6306.747730090745, 6109.556495504338, 6205.596636218631, 6226.59527130735, 6264.091706702594, 6288.968555157195, 6103.328633850362, 6244.656543889948, 6247.875042499216, 6253.155823963889, 6261.592996455216, 6141.631502109888, 6145.661988590492, 6146.354632763674, 6147.293061298008, 6181.938180618136, 6102.420270074336, 6121.209103132385, 6172.444479996821, 6246.843828904132, 6274.623522588659, 6131.662758548323, 6142.007694998287, 6153.6923153841235, 6167.044108163242, 6175.320714721287], "value": 5781.840578223675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.2846325211535, 772.6870617399952, 791.2522666080555, 862.3316929379733, 870.3994243971663, 28609.42781144376, 29025.56461653077, 29037.709622903763, 29049.273768155806, 29162.125136245893, 28049.719502804965, 37631.0, 38019.61980380195, 38088.80955595218, 38128.809355953184, 8803.691870784523, 9016.549172541347, 9116.8632470513, 9120.726378208665, 9121.817563648756, 8972.820543589125, 9149.67976120836, 9154.908450915505, 9160.541972901328, 9161.725148245563, 7145.214026457085, 7314.305141011595, 7316.451266155033, 7316.890246646304, 7318.231585683507, 7285.526440781358, 10555.47222638865, 10768.138548916146, 10784.460776961121, 10797.676069717843, 10461.476926153662, 10527.73680657982, 10569.894301056967, 10572.735681607945, 10576.52405641748, 36989.815050924844, 37346.25307493862, 37365.62634373648, 37392.626073739186, 37432.877013689635, 2852.7432531072304, 3743.2139250757373, 3758.586555478895, 3791.33651610967, 3932.3118454270675, 27095.9540786938, 27525.724742752518, 28012.439751205067, 28020.719792802014, 28936.27659308513, 3104.611923509561, 3112.2063873712286, 3124.156477751017, 3220.677932206784, 3237.255431250802, 336630.31684841664, 338011.0, 338107.0, 338194.0, 338754.0, 50866.745666272174, 50906.49093509017, 50918.49081509212, 51007.724806879385, 51041.0, 53390.7330463353, 53486.66283342947, 53490.197647035326, 53520.73239633777, 53549.73225133849, 14651.633709157144, 14816.40734370613, 14830.184339861195, 14835.777463338058, 14853.777193342108, 12015.639530814089, 12058.638240852788, 12060.638180854588, 12065.215760975509, 12080.154389192849, 11156.832647510293, 12310.692232694255, 12323.50705971751, 12383.504659813507, 12447.564335248222, 4448.199324121689, 4455.977720111444, 4503.099380123988, 4956.306117143603, 5199.246109314204, 721.4300702445114, 787.6494959742975, 849.2229609906866, 863.9524826134623, 879.0857508191409, 24886.626700599507, 25485.23544293674, 25914.74085259161, 25987.22038338853, 26037.869810650827, 908.5593487158723, 916.863089768682, 932.2029664636774, 936.281922671891, 953.9856902146473, 13327.533536326182, 13451.865481345258, 13587.45650173982, 13651.976101792405, 13734.587962361145, 343078.0, 345723.0, 346155.0, 346291.0, 361980.0, 55959.72020139955, 56979.43020569824, 57048.0, 57068.714656426455, 57233.0, 1385.8544852790515, 1427.9428822847178, 1434.684369438722, 1435.1532595768128, 1453.8255409350734, 26368.8681556591, 28121.0, 28194.577081343385, 28822.567661484674, 28972.420551588428, 44794.0, 44861.551384485734, 44869.0, 44948.65154045384, 44962.10075798531, 372987.0, 374318.0, 374897.0, 375017.1249143737, 376147.0, 30471.0, 31376.372472550876, 31430.0, 31431.685683142874, 31475.0, 39787.0, 40140.79929600333, 40255.99360016023, 40505.78482645525, 41132.76601701954, 33610.0, 33644.49533256954, 33700.662993369755, 34579.4813077799, 34898.82550587231, 33407.33185336231, 33438.0, 34236.82881585576, 34465.31069378649, 34934.825325874226, 33754.662453376135, 34414.0, 34463.82768086143, 34489.48265776066, 34512.0, 25132.874335628207, 29335.26661833386, 29738.702612973593, 30890.53664195084, 31211.063668089995, 19531.51171220674, 22959.196428125604, 23013.29776645316, 23130.53738925246, 23810.88094559516], "value": 17606.28746588616, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.1975790960029, 386.70610336144523, 398.3785294939892, 419.26837668268865, 429.6112018623147, 12740.0, 14973.625659358522, 17368.826311736862, 17482.300707971666, 21120.683189752166, 21100.472488187763, 21729.0, 21750.456238594044, 22284.88857555714, 22963.311100666964, 6489.35106489352, 6634.104395906539, 6645.202575690924, 6753.223379311393, 6877.449804015691, 4192.392103145042, 6546.672666366662, 6695.196576410814, 6703.0, 6720.899186512182, 4433.157700036999, 4529.524399938008, 7881.17247688993, 7892.092409372909, 7913.485623434458, 7625.428092892999, 7780.377569794403, 7903.841923161504, 7976.202379762007, 7992.720254791084, 7245.311695388929, 7637.0, 7652.617369131521, 7665.233476652346, 7669.846603067907, 27381.589276160874, 27782.861085694443, 28113.85943070292, 28258.0, 28695.71304286972, 2818.6053952446673, 2867.856607169633, 2881.106856874355, 2910.7962442629023, 2942.7498662613702, 13817.378217980215, 16175.595610109724, 16562.089085100313, 16679.166041697867, 16689.24898379576, 1984.2063174730079, 2018.7072874433125, 2027.320847516069, 2045.089934978935, 2045.263705066175, 157561.0, 160024.199879001, 161022.0, 163793.36206638018, 165011.0, 17134.742978855076, 21377.731571265846, 21656.133754649953, 21771.455713607287, 21877.062128816964, 36363.0, 36433.81783091067, 36579.634203657624, 36607.0, 36614.45078323827, 10398.220133489867, 10467.162626989822, 10657.946710266398, 10700.678979630624, 10831.0, 8378.874316885252, 8395.25550345705, 8419.0, 8585.399022068521, 8722.28384144218, 10245.964452574623, 10537.89462105369, 10685.946570267099, 10702.571897124028, 10735.194860385502, 4037.2038514825636, 4196.496420429568, 4221.282381995068, 4247.17180149874, 4252.787360631956, 982.5873133284038, 999.8250306196387, 1010.5750891243312, 1017.4056156800277, 1022.2537547590152, 22227.66658500124, 22605.43486412853, 22619.88690056539, 22662.66006009911, 22690.0, 1014.8731408573929, 1015.669907280137, 1039.812833689943, 1042.6663467690266, 1053.1627356251797, 9439.910520298696, 10833.187510936712, 10901.400422976812, 11111.83332250017, 11665.650030499099, 667362.253340872, 675011.0, 675595.9727184131, 679427.0, 684273.0, 112445.0, 146853.0, 151923.0, 152213.0, 153395.2330238364, 1270.4092596942282, 1320.0891384944357, 1350.216874212954, 1352.0738294268654, 1356.816829727974, 19078.904605476884, 19278.421647350602, 19474.513137171685, 19525.414237572328, 19551.0, 52217.0, 55176.72411637916, 60225.69887150536, 63002.36997629965, 63228.0, 72176.63911680409, 75649.86525202016, 84117.15882841093, 86261.56869215865, 88504.0, 7970.521768693897, 8080.636371363185, 11570.537178512765, 11776.646700598996, 12512.937435312766, 13742.244176570384, 13789.586312410644, 13795.862041379458, 14340.928295358457, 14466.493672721404, 9979.800403992025, 11197.104231661611, 11326.660200194008, 11573.942130289295, 11799.76400472003, 9917.355371900803, 11248.887511124783, 11714.0, 11795.29228246308, 11823.994960428116, 10608.310459820086, 12017.158798883995, 12118.394080296126, 12176.45205965716, 12241.816372754594, 12228.388580570758, 12466.189697669623, 12526.248425094524, 12549.988002879425, 12573.622791316275, 11740.647780566596, 11773.587924422603, 12095.697607559881, 12129.029677625593, 12180.451879665614], "value": 11717.697078380423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.5973067137206, 1560.3850015234227, 1576.1016220754175, 1597.9201039948, 1599.032585285902, 10296.330738501993, 10446.425446600433, 10953.452327383618, 10958.0, 10962.149841041337, 10153.238507111959, 10914.945425272863, 10921.0, 10927.617533386334, 10931.45342732864, 2322.3497420722224, 2368.08828600989, 2369.2536850891915, 2369.2892132360307, 2369.5853225685523, 2311.7572654871246, 2339.251439539348, 2340.438294809242, 2344.347235199189, 2345.94135146621, 2306.5041016181517, 2314.8611083335054, 2327.208749025331, 2333.195047708546, 2340.96488552671, 2226.4100013496272, 2231.6987206727044, 2233.653783663535, 2245.2702871566644, 2245.5396643688086, 2263.3775711679255, 2322.7096612923383, 2325.837191396603, 2326.4532834783813, 2328.4178955261186, 11028.50371733274, 11103.333799972028, 11109.166812489015, 11126.61056863006, 11132.220744547885, 5632.971835140798, 5865.266841644794, 5871.5596330274975, 5880.029795083806, 5886.175935369051, 3270.771046026779, 3380.9830950845335, 3552.8223588820456, 3589.174489867319, 3612.3678356287546, 1011.3931641015405, 1098.8022156011914, 1145.604766355606, 1149.6953307373553, 1225.4485481533345, 14761.55715328542, 14834.961552691317, 14955.0, 14971.401143954228, 15012.249387530579, 4205.96953746328, 4229.196452673982, 4280.315149576115, 4515.887102822456, 4595.4025976622825, 11205.66383008511, 11215.775684486265, 11216.0, 11218.607348742918, 11229.38238396896, 5496.835094947158, 5498.5876059295715, 5498.945010549924, 5499.697516636624, 5505.146702261156, 4989.525995030466, 5090.643654944119, 5107.029664363833, 5150.587952963754, 5234.0, 3022.5189657067876, 3468.0809585459874, 3565.4295312750355, 3593.299306635181, 3605.2694706541006, 1013.2856336282903, 1014.7868947521104, 1026.9897301027042, 1027.814993301198, 1030.5568605499707, 903.3721563513295, 927.3091546797679, 933.6498812945146, 942.528735632184, 974.9470571782458, 5617.185508101385, 6513.316101809241, 6531.477481801445, 6557.737690492327, 6576.769813056616, 925.3707478914403, 944.9669261575949, 968.0948313327061, 973.7662960889336, 987.3511236235504, 3486.0, 3944.36890097583, 4015.297322968469, 4107.466029416157, 4170.749755014648, 13087.541936032194, 13568.253746043865, 13593.320333983262, 13620.387082581308, 13743.450261989608, 11844.940775296069, 12292.754144917051, 12641.936790316173, 13098.738025239441, 13189.868101318863, 7330.853382932418, 9023.0, 9030.652030472125, 9034.503102329434, 9041.81916361669, 5087.64386492942, 6578.8355291118105, 6663.267040625624, 6767.661616919233, 6820.897686534805, 11165.0, 11405.916437938382, 11729.941350293195, 11737.647870563897, 11756.470958807045, 10208.285420020527, 11900.345480998629, 11967.281963082243, 12218.572349967708, 12283.447244874182, 8857.11428857109, 9216.677416290398, 9241.937177224436, 9249.491277979776, 9249.583768730557, 9824.164945979386, 9933.00669933001, 10040.347377420445, 10060.0, 10070.0, 8522.360822938428, 8683.131686831266, 8894.599743011451, 8991.640334386551, 9089.545522723705, 8680.956595216983, 8692.608832602675, 8719.78200544966, 8777.122287771428, 8823.955880220814, 7733.7679869604, 7754.883676744967, 7835.725749598737, 7839.882401763864, 7847.882281765664, 5719.971400142973, 5735.71321433935, 5930.970345148246, 5952.315483719428, 5997.460228579274, 5777.711114444345, 5792.710364481674, 6008.128821320972, 6013.9699301503215, 6164.0], "value": 4977.079150739169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.82344474652166, 373.55173791450255, 381.5535823086988, 384.84221469197615, 387.2081593142024, 5194.935038317145, 5667.0932650775885, 5798.855028624282, 5807.390224026469, 5845.912311315334, 5158.638895277332, 5539.972300138494, 5578.41426650201, 5627.971860140704, 5638.520725738317, 4679.976600116995, 4699.415321573129, 4703.247480403136, 4715.457722361921, 4718.97640511797, 3956.604339566049, 4566.703164294327, 4729.52704729526, 4733.148033353995, 4739.317542272478, 3943.507061617298, 4617.30740388941, 4618.35343051973, 4650.930236046445, 4671.182543054969, 4607.262837945946, 4616.561426664462, 4629.560191781776, 4632.907341853178, 4654.930176047362, 4252.213340531981, 4276.444062271916, 4283.993261583557, 4285.271503844354, 4319.287317592625, 4962.379702537183, 5322.33470816148, 5534.972325138388, 5548.167774833767, 5647.29408823897, 5408.0, 5423.891522169534, 5437.700926449043, 5441.238226648273, 5441.347038355383, 5135.845924622267, 5844.0, 5998.3701711320555, 6051.576389652726, 6081.3310535841465, 4376.715513491613, 4550.340200670884, 4753.35829662998, 4764.166270902613, 4770.499097594738, 5255.86860328491, 6087.878242435127, 6295.118683384376, 6344.6827658616885, 6516.2506311773805, 4292.785360732013, 4763.166445871959, 4896.951030489649, 4915.852524424273, 4957.380327459067, 4981.875453113628, 5036.345275114211, 5040.974795126001, 5051.696898186121, 5058.620603454756, 4706.764661766898, 5269.496988930304, 5321.760520776575, 5405.702686352289, 5463.235147079412, 5905.257948905083, 6002.459778619946, 6081.817545473643, 6095.725692343855, 6110.883449440728, 1581.9841801582068, 1620.4814459372888, 1742.7734394528884, 1744.7208446648729, 1744.842964133234, 1323.530146797873, 1376.1742954227384, 1460.889723809891, 1482.629342664334, 1543.4135028689027, 5007.198848184273, 5331.760070796746, 5556.333240011144, 5608.803691870765, 5645.915311270334, 4804.25534042224, 5866.266716660418, 6147.323794382614, 6186.319504854462, 6216.129741836146, 5384.488473595002, 5639.971800140973, 5647.548196144298, 5692.203091567184, 5712.714364281769, 3262.857999700086, 3281.8667865887364, 3286.375588638151, 3295.2091498040777, 3300.1584595927884, 6145.26256849172, 6451.935480645134, 6462.547621666438, 6485.319041500669, 6504.317046710122, 5730.776335957914, 6305.495560355161, 6356.237251529754, 6391.360863913665, 6436.549441539048, 5921.73352199152, 6417.1657684500715, 6452.903206452001, 6467.482601391877, 6516.478681705452, 5233.540236089032, 5292.708901010481, 5667.4332566743, 5903.144044113666, 6106.1451396803595, 5244.423113457593, 5758.913616295843, 6000.0699541800195, 6159.692015399301, 6203.348648391854, 5549.805756798492, 5927.4665280125, 6230.345813689497, 6257.468115210076, 6433.839154021187, 4780.306855505863, 4845.6608037438455, 4854.563089321978, 4856.757162141949, 4867.269909513601, 5256.316678831728, 5860.853478663067, 6172.975242131233, 6274.968625157027, 6297.496200303873, 5645.68948707825, 5975.715221227457, 6047.969760151347, 6048.60684055535, 6072.453479186895, 5058.797648094035, 5248.212768084818, 5788.652680839163, 6022.879542409215, 6077.361877003028, 5924.703764811656, 6010.87978240424, 6024.252966639626, 6051.485623721856, 6054.152418661303, 5861.79833134215, 6065.420607441353, 6077.969610151921, 6093.36019717941, 6135.417135372132, 5841.744025034638, 5910.881782364415, 5916.04770425767, 5943.316518600243, 5951.553633477593], "value": 4671.398685385377, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.5020286610838, 773.0993392697504, 799.1289494451049, 808.696738722979, 820.2859594221303, 15720.764188537181, 15933.601659958507, 15940.760888586681, 16034.759478607815, 16041.919790401033, 11877.287362758241, 14348.569542913729, 14373.065750726222, 14377.137371757703, 14398.640033999156, 918.6371383303591, 932.2914584915462, 949.3307218411004, 951.4671783801059, 954.3987288008545, 948.3314263444255, 968.2737946540095, 969.2004096620282, 970.1559643110504, 970.4225985538593, 1003.8343673293898, 1008.9344192627456, 1010.9191264698843, 1010.9292349535535, 1011.0092109732467, 1004.0912973758724, 1004.6182450668699, 1005.0602686488083, 1007.1338648762058, 1007.2848277722862, 743.3978477433242, 743.5575832379725, 745.0649434959146, 745.2100773180446, 745.8769303064988, 15836.970596911164, 16183.676326473522, 16245.18774061304, 16323.34706611746, 16417.67164656712, 12425.62723118308, 12530.310832904186, 12767.463656848835, 12841.0, 12895.806562901564, 9807.656732014417, 11764.470598823074, 11779.411029448578, 11921.582744603897, 12078.094142939315, 3648.1973965727498, 3813.771173729585, 3857.0, 3892.0853599404118, 3899.7075219358376, 108142.37786433131, 110186.0, 110905.9999999992, 110924.0, 114463.42768286105, 9071.501067441353, 10542.631007914686, 10917.235793494538, 11076.335419874673, 11322.547098115983, 23523.059077636703, 23837.404064898168, 23889.0, 24140.155094571604, 24141.51716965651, 7713.460057796013, 7790.8052298691855, 7887.053553573608, 7892.368610511146, 8099.230573095547, 4157.833686652561, 4214.931767692767, 4235.53409124996, 4310.504292006397, 4469.865904022885, 3426.6059403168456, 3455.3780319542725, 3526.2947410518, 3526.488659144461, 3534.0281422608987, 602.3825578781783, 607.7601692547154, 614.827848202504, 620.9658468784261, 643.5655932245716, 633.3539789414771, 663.9800805975732, 681.9659017049128, 686.193722376216, 697.9286794770105, 8920.506774309612, 10254.384736915808, 10265.743356416, 10401.27191096616, 10544.525496352682, 659.5416185750953, 682.3279070115932, 690.6477696374784, 701.5930760159096, 735.88961655751, 4850.216830871926, 4904.754762261872, 4912.803487860517, 4938.407391113089, 4951.232558953368, 84588.0, 94587.52706236424, 98435.0, 100406.49390259152, 103233.48383258036, 85683.0, 87030.56484717535, 88287.55856220677, 89633.20733585423, 90512.54743726371, 59722.0, 78348.0, 78821.60589197018, 79810.0, 80399.79400308883, 8643.740687779376, 10587.417692027013, 10608.787824243625, 10788.460576971276, 11031.558737650725, 45665.0, 46442.37394769188, 47951.5204847961, 48851.31317777556, 49333.26000110073, 56036.0, 66606.6768649321, 67235.0, 68341.0, 68846.65576672084, 13426.0, 13581.932090339486, 13667.658308542366, 13772.173669579857, 13800.585982420544, 14507.51360328795, 14631.707365852837, 14753.926230369208, 14773.556793296219, 15096.698066038838, 13651.0, 13753.312334383441, 13777.448902043807, 13811.654708631962, 14356.0, 13231.536955969626, 13419.664508387368, 13743.725125497635, 14071.0, 14307.57077287683, 13198.550638077773, 13418.261995590336, 13892.23592702411, 13905.72188556203, 14021.719565608426, 10771.838422423512, 10994.780104397707, 11225.158113141384, 11418.200725949104, 11487.0, 10432.947835261079, 10655.20085993569, 10719.303245289031, 11162.665120046411, 11292.943535282271], "value": 7659.538465821149, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1473.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 115.0, 116.0, 117.0, 114.0, 116.0, 117.0, 117.0, 116.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 110.0, 163.0, 137.0, 137.0, 121.0, 113.0, 133.0, 113.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 198.0, 258.0, 207.0, 187.0, 185.0, 183.0, 185.0, 185.0, 184.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 60.0, 60.0, 89.0, 64.0, 59.0, 59.0, 60.0, 74.0, 77.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 85.0, 69.0, 70.0, 69.0, 84.0, 69.0, 68.0, 84.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 87.0, 87.0, 87.0, 87.0, 87.0, 87.0, 86.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 84.0, 86.0, 84.0, 83.0, 86.0, 84.0, 87.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 62.0, 62.0, 63.0, 62.0, 62.0, 60.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 64.0, 67.0, 67.0, 61.0, 57.0, 59.0, 59.0, 62.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 82.0, 83.0, 84.0, 82.0, 82.0, 84.0, 81.0, 99.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 140.0, 138.0, 164.0, 155.0, 145.0, 139.0, 135.0, 179.0, 139.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [231.0, 191.0, 186.0, 190.0, 220.0, 200.0, 193.0, 214.0, 206.0, 184.0], "value": 201.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 67.0, 69.0, 66.0, 67.0, 69.0, 67.0], "value": 67.5, "unit": "score"}]}]} 05:10:21 INFO - Return code: 0 05:10:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:10:21 ERROR - # TBPL SUCCESS # 05:10:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:21 INFO - 2017-03-20 05:10:21.837 screenresolution[1665:9883] starting screenresolution argv=screenresolution get 05:10:21 INFO - 2017-03-20 05:10:21.839 screenresolution[1665:9883] Display 0: 1600x1200x32@60 05:10:21 INFO - 2017-03-20 05:10:21.845 screenresolution[1666:9886] starting screenresolution argv=screenresolution list 05:10:21 INFO - Available Modes on Display 0 05:10:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:22 INFO - Intel Iris: 05:10:22 INFO - Chipset Model: Intel Iris 05:10:22 INFO - Type: GPU 05:10:22 INFO - Bus: Built-In 05:10:22 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:22 INFO - Vendor: Intel (0x8086) 05:10:22 INFO - Device ID: 0x0a2e 05:10:22 INFO - Revision ID: 0x0009 05:10:22 INFO - Displays: 05:10:22 INFO - Display: 05:10:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:22 INFO - Display Serial Number: 1600x1200 60 05:10:22 INFO - Main Display: Yes 05:10:22 INFO - Mirror: Off 05:10:22 INFO - Online: Yes 05:10:22 INFO - Rotation: Supported 05:10:22 INFO - Return code: 0 05:10:22 INFO - Running post-action listener: _resource_record_post_action 05:10:22 INFO - [mozharness: 2017-03-20 12:10:22.089717Z] Finished run-tests step (success) 05:10:22 INFO - Running post-run listener: _resource_record_post_run 05:10:22 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 39875584; Write bytes: 514425856; Read time: 391; Write time: 1792 05:10:22 INFO - TinderboxPrint: CPU usage
53.6% 05:10:22 INFO - TinderboxPrint: I/O read bytes / time
39,875,584 / 391 05:10:22 INFO - TinderboxPrint: I/O write bytes / time
514,425,856 / 1,792 05:10:22 INFO - TinderboxPrint: CPU idle
2,232.4 (46.4%) 05:10:22 INFO - TinderboxPrint: CPU system
956.9 (19.9%) 05:10:22 INFO - TinderboxPrint: CPU user
1,624.4 (33.7%) 05:10:22 INFO - TinderboxPrint: Swap in / out
248,410,112 / 0 05:10:22 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 216916480; Write bytes: 211333120; Read time: 16890; Write time: 410 05:10:22 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 29830144; Write bytes: 301749248; Read time: 291; Write time: 1377 05:10:22 INFO - Running post-run listener: _upload_blobber_files 05:10:22 INFO - Blob upload gear active. 05:10:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:10:22 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 05:10:22 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:10:22 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:10:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:10:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:10:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:10:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:23 INFO - (blobuploader) - INFO - Done attempting. 05:10:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:10:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:10:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:24 INFO - (blobuploader) - INFO - Done attempting. 05:10:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:10:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:10:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:25 INFO - (blobuploader) - INFO - Done attempting. 05:10:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:10:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:10:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:27 INFO - (blobuploader) - INFO - Done attempting. 05:10:27 INFO - (blobuploader) - INFO - Iteration through files over. 05:10:27 INFO - Return code: 0 05:10:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:10:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:10:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2986c546779c2fdf583e043b24b23b3245ca7b2f3dd35c9798f96b2badeac261897dc925d8a036a4cdaa9a9a1ec7c752b28c9e7c185b2056d7b930035cb15f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/78fbfd9f88c419ad726b40aaf9ddb8eb3865d9c3123946f4b20a3fee6ccd4b221b49e6b9c006d3fc33d531a8587a73da79ebb4244235f54198d3cda5c5016f47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ccc71863fab55d194af204f644e04aa92dbdd19abcd3285e2e28cd59cc5228a4ba63914f1554fd13ad428b6f7ae3079877f99c4b6e8f9d53a3642431ca4da902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/826b2bc8badc705db22a8c46dc62afff9b0c919cd33a11e239c4e859ce4c318fdf295503b78e28c31b27dc26ccfa895b57b5efd4398a9e24740ff8174b98d251"} 05:10:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:10:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:10:27 INFO - Contents: 05:10:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2986c546779c2fdf583e043b24b23b3245ca7b2f3dd35c9798f96b2badeac261897dc925d8a036a4cdaa9a9a1ec7c752b28c9e7c185b2056d7b930035cb15f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/78fbfd9f88c419ad726b40aaf9ddb8eb3865d9c3123946f4b20a3fee6ccd4b221b49e6b9c006d3fc33d531a8587a73da79ebb4244235f54198d3cda5c5016f47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ccc71863fab55d194af204f644e04aa92dbdd19abcd3285e2e28cd59cc5228a4ba63914f1554fd13ad428b6f7ae3079877f99c4b6e8f9d53a3642431ca4da902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/826b2bc8badc705db22a8c46dc62afff9b0c919cd33a11e239c4e859ce4c318fdf295503b78e28c31b27dc26ccfa895b57b5efd4398a9e24740ff8174b98d251"} 05:10:27 INFO - Running post-run listener: copy_logs_to_upload_dir 05:10:27 INFO - Copying logs to upload dir... 05:10:27 INFO - mkdir: /builds/slave/test/build/upload/logs 05:10:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.901495 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-20 05:10:27.880822) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 05:10:27.884241) ========= 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.K3vMzXUH09/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.uRYpDpUFK2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/Pine/sha512/2986c546779c2fdf583e043b24b23b3245ca7b2f3dd35c9798f96b2badeac261897dc925d8a036a4cdaa9a9a1ec7c752b28c9e7c185b2056d7b930035cb15f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/78fbfd9f88c419ad726b40aaf9ddb8eb3865d9c3123946f4b20a3fee6ccd4b221b49e6b9c006d3fc33d531a8587a73da79ebb4244235f54198d3cda5c5016f47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ccc71863fab55d194af204f644e04aa92dbdd19abcd3285e2e28cd59cc5228a4ba63914f1554fd13ad428b6f7ae3079877f99c4b6e8f9d53a3642431ca4da902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/826b2bc8badc705db22a8c46dc62afff9b0c919cd33a11e239c4e859ce4c318fdf295503b78e28c31b27dc26ccfa895b57b5efd4398a9e24740ff8174b98d251"} build_url:https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011123 build_url: 'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2986c546779c2fdf583e043b24b23b3245ca7b2f3dd35c9798f96b2badeac261897dc925d8a036a4cdaa9a9a1ec7c752b28c9e7c185b2056d7b930035cb15f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/78fbfd9f88c419ad726b40aaf9ddb8eb3865d9c3123946f4b20a3fee6ccd4b221b49e6b9c006d3fc33d531a8587a73da79ebb4244235f54198d3cda5c5016f47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ccc71863fab55d194af204f644e04aa92dbdd19abcd3285e2e28cd59cc5228a4ba63914f1554fd13ad428b6f7ae3079877f99c4b6e8f9d53a3642431ca4da902", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/826b2bc8badc705db22a8c46dc62afff9b0c919cd33a11e239c4e859ce4c318fdf295503b78e28c31b27dc26ccfa895b57b5efd4398a9e24740ff8174b98d251"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 05:10:27.919330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:10:27.919696) ========= 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.K3vMzXUH09/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.uRYpDpUFK2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:10:27.968969) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 05:10:27.969286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 05:10:27.969603) ========= ========= Total master_lag: 0.19 =========