builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0329 starttime: 1490010474.26 results: success (0) buildid: 20170320031743 builduid: e966bdfeb2f3478c91904d09a5e578ca revision: 1c9cf05afab07f505e360d404dfbaba81a1b4721 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:54.262863) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:54.263284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:54.263557) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:55.224946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 04:47:55.225266) ========= 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.NeUtApGlgC/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.tqk7usVTCp/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:47:55-- 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% 10.8M=0.001s 2017-03-20 04:47:56 (10.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.770733 ========= master_lag: 0.81 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 04:47:56.807782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:56.808114) ========= 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.NeUtApGlgC/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.tqk7usVTCp/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.032095 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:56.863292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-20 04:47:56.863640) ========= 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.NeUtApGlgC/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.tqk7usVTCp/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:47:57,006 truncating revision to first 12 chars 2017-03-20 04:47:57,006 Setting DEBUG logging. 2017-03-20 04:47:57,006 attempt 1/10 2017-03-20 04:47:57,006 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:47:58,963 unpacking tar archive at: pine-1c9cf05afab0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.229280 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-20 04:47:59.116780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:59.117123) ========= script_repo_revision: 1c9cf05afab07f505e360d404dfbaba81a1b4721 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:59.117514) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:59.117876) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 04:47:59.137783) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-20 04:47:59.138150) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.NeUtApGlgC/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.tqk7usVTCp/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:47:59 INFO - MultiFileLogger online at 20170320 04:47:59 in /builds/slave/test 04:47:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 04:47:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:59 INFO - {'append_to_log': False, 04:47:59 INFO - 'base_work_dir': '/builds/slave/test', 04:47:59 INFO - 'blob_upload_branch': 'Pine', 04:47:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:59 INFO - 'branch': 'Pine', 04:47:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:59 INFO - 'config_files': ('talos/mac_config.py',), 04:47:59 INFO - 'default_actions': ('clobber', 04:47:59 INFO - 'read-buildbot-config', 04:47:59 INFO - 'download-and-extract', 04:47:59 INFO - 'populate-webroot', 04:47:59 INFO - 'create-virtualenv', 04:47:59 INFO - 'install', 04:47:59 INFO - 'run-tests'), 04:47:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:59 INFO - 'download_minidump_stackwalk': True, 04:47:59 INFO - 'download_symbols': 'ondemand', 04:47:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:59 INFO - 'gecko_profile': False, 04:47:59 INFO - 'gecko_profile_interval': 0, 04:47:59 INFO - 'installer_path': 'installer.exe', 04:47:59 INFO - 'log_level': 'info', 04:47:59 INFO - 'log_name': 'talos', 04:47:59 INFO - 'log_to_console': True, 04:47:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:47:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:47:59 INFO - 'opt_config_files': (), 04:47:59 INFO - 'pip_index': False, 04:47:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:59 INFO - 'cmd': ('bash', 04:47:59 INFO - '-c', 04:47:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:59 INFO - 'enabled': True, 04:47:59 INFO - 'halt_on_failure': False, 04:47:59 INFO - 'name': 'check_screen_resolution'},), 04:47:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:59 INFO - 'cmd': ('bash', 04:47:59 INFO - '-c', 04:47:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:59 INFO - 'enabled': True, 04:47:59 INFO - 'halt_on_failure': False, 04:47:59 INFO - 'name': 'check_screen_resolution'},), 04:47:59 INFO - 'run_cmd_checks_enabled': True, 04:47:59 INFO - 'suite': 'dromaeojs-e10s', 04:47:59 INFO - 'system_bits': '32', 04:47:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:59 INFO - 'title': 't-yosemite-r7-0329', 04:47:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:59 INFO - 'use_talos_json': True, 04:47:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:59 INFO - 'work_dir': 'build'} 04:47:59 INFO - [mozharness: 2017-03-20 11:47:59.387949Z] Running clobber step. 04:47:59 INFO - Running pre-action listener: _resource_record_pre_action 04:47:59 INFO - Running main action method: clobber 04:47:59 INFO - rmtree: /builds/slave/test/build 04:47:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:48:01 INFO - Running post-action listener: _resource_record_post_action 04:48:01 INFO - [mozharness: 2017-03-20 11:48:01.762758Z] Finished clobber step (success) 04:48:01 INFO - [mozharness: 2017-03-20 11:48:01.762886Z] Running read-buildbot-config step. 04:48:01 INFO - Running pre-action listener: _resource_record_pre_action 04:48:01 INFO - Running main action method: read_buildbot_config 04:48:01 INFO - Using buildbot properties: 04:48:01 INFO - { 04:48:01 INFO - "project": "", 04:48:01 INFO - "product": "firefox", 04:48:01 INFO - "script_repo_revision": "default", 04:48:01 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 04:48:01 INFO - "repository": "", 04:48:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 04:48:01 INFO - "buildid": "20170320031743", 04:48:01 INFO - "pgo_build": "False", 04:48:01 INFO - "basedir": "/builds/slave/test", 04:48:01 INFO - "buildnumber": 3, 04:48:01 INFO - "slavename": "t-yosemite-r7-0329", 04:48:01 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:48:01 INFO - "platform": "yosemite_r7", 04:48:01 INFO - "scheduler": "tests-pine-macosx64-talos", 04:48:01 INFO - "branch": "pine", 04:48:01 INFO - "repo_path": "projects/pine", 04:48:01 INFO - "revision": "1c9cf05afab07f505e360d404dfbaba81a1b4721", 04:48:01 INFO - "stage_platform": "macosx64", 04:48:01 INFO - "builduid": "e966bdfeb2f3478c91904d09a5e578ca", 04:48:01 INFO - "slavebuilddir": "test" 04:48:01 INFO - } 04:48:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:48:01 INFO - Running post-action listener: _resource_record_post_action 04:48:01 INFO - [mozharness: 2017-03-20 11:48:01.766088Z] Finished read-buildbot-config step (success) 04:48:01 INFO - [mozharness: 2017-03-20 11:48:01.766228Z] Running download-and-extract step. 04:48:01 INFO - Running pre-action listener: _resource_record_pre_action 04:48:01 INFO - Running main action method: download_and_extract 04:48:01 INFO - mkdir: /builds/slave/test/build/tests 04:48:01 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:48:01 INFO - trying https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:01 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:48:01 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:48:03 INFO - Downloaded 1325 bytes. 04:48:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:03 INFO - Using the following test package requirements: 04:48:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:48:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:48:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:48:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:48:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'jsshell-mac.zip'], 04:48:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:48:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:48:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:48:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:48:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:48:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:48:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:48:03 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:48:03 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:48:03 INFO - Fetch https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:48:05 INFO - Expected file size: 52473358 04:48:05 INFO - Obtained file size: 52473358 04:48:22 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:48:22 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:48:22 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:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:48:24 INFO - Expected file size: 52473358 04:48:24 INFO - Obtained file size: 52473358 04:48:39 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:48:39 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:48:39 INFO - Fetch https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:48:40 INFO - Expected file size: 14404171 04:48:40 INFO - Obtained file size: 14404171 04:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:41 INFO - trying https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:48:41 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:48:41 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:48:43 INFO - Downloaded 75189884 bytes. 04:48:43 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:48:43 INFO - mkdir: /builds/slave/test/properties 04:48:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:48:43 INFO - Writing to file /builds/slave/test/properties/build_url 04:48:43 INFO - Contents: 04:48:43 INFO - build_url:https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:48:44 INFO - Running post-action listener: _resource_record_post_action 04:48:44 INFO - Running post-action listener: set_extra_try_arguments 04:48:44 INFO - [mozharness: 2017-03-20 11:48:44.180634Z] Finished download-and-extract step (success) 04:48:44 INFO - [mozharness: 2017-03-20 11:48:44.180782Z] Running populate-webroot step. 04:48:44 INFO - Running pre-action listener: _resource_record_pre_action 04:48:44 INFO - Running main action method: populate_webroot 04:48:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:48:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:48:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:48:44 INFO - '--tppagecycles', 04:48:44 INFO - '7'], 04:48:44 INFO - 'tests': ['tsvgm']}}, 04:48:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:48:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:48:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:48:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:48:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['damp', 'tps']}, 04:48:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'tests': ['damp', 'tps']}, 04:48:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['dromaeo_dom']}, 04:48:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:48:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['basic_compositor_video']}, 04:48:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:48:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['a11yr', 04:48:44 INFO - 'ts_paint', 04:48:44 INFO - 'tpaint', 04:48:44 INFO - 'sessionrestore', 04:48:44 INFO - 'sessionrestore_no_auto_restore', 04:48:44 INFO - 'tabpaint']}, 04:48:44 INFO - 'other-e10s': {'tests': ['a11yr', 04:48:44 INFO - 'ts_paint', 04:48:44 INFO - 'tpaint', 04:48:44 INFO - 'sessionrestore', 04:48:44 INFO - 'sessionrestore_no_auto_restore', 04:48:44 INFO - 'tabpaint']}, 04:48:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:48:44 INFO - 'ts_paint', 04:48:44 INFO - 'tpaint', 04:48:44 INFO - 'sessionrestore', 04:48:44 INFO - 'sessionrestore_no_auto_restore', 04:48:44 INFO - 'tabpaint']}, 04:48:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:48:44 INFO - 'ts_paint', 04:48:44 INFO - 'tpaint', 04:48:44 INFO - 'sessionrestore', 04:48:44 INFO - 'sessionrestore_no_auto_restore', 04:48:44 INFO - 'tabpaint']}, 04:48:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['a11yr', 04:48:44 INFO - 'ts_paint', 04:48:44 INFO - 'tpaint', 04:48:44 INFO - 'sessionrestore', 04:48:44 INFO - 'sessionrestore_no_auto_restore', 04:48:44 INFO - 'tabpaint']}, 04:48:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['a11yr', 04:48:44 INFO - 'ts_paint', 04:48:44 INFO - 'tpaint', 04:48:44 INFO - 'sessionrestore', 04:48:44 INFO - 'sessionrestore_no_auto_restore', 04:48:44 INFO - 'tabpaint']}, 04:48:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['tsvgx', 04:48:44 INFO - 'tsvgr_opacity', 04:48:44 INFO - 'tart', 04:48:44 INFO - 'tscrollx', 04:48:44 INFO - 'cart', 04:48:44 INFO - 'tsvg_static']}, 04:48:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:48:44 INFO - 'tsvgr_opacity', 04:48:44 INFO - 'tart', 04:48:44 INFO - 'tscrollx', 04:48:44 INFO - 'cart', 04:48:44 INFO - 'tsvg_static']}, 04:48:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'talos_options': ['--disable-e10s'], 04:48:44 INFO - 'tests': ['tp5o']}, 04:48:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:48:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'talos_options': ['--disable-e10s', 04:48:44 INFO - '--xperf_path', 04:48:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:44 INFO - 'tests': ['tp5n']}, 04:48:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:48:44 INFO - 'talos_options': ['--xperf_path', 04:48:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:44 INFO - 'tests': ['tp5n']}}, 04:48:44 INFO - 'talos.zip': {'path': '', 04:48:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:48:44 INFO - Running post-action listener: _resource_record_post_action 04:48:44 INFO - [mozharness: 2017-03-20 11:48:44.198979Z] Finished populate-webroot step (success) 04:48:44 INFO - [mozharness: 2017-03-20 11:48:44.199092Z] Running create-virtualenv step. 04:48:44 INFO - Running pre-action listener: _resource_record_pre_action 04:48:44 INFO - Running main action method: create_virtualenv 04:48:44 INFO - Creating virtualenv /builds/slave/test/build/venv 04:48:44 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:48:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:48:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:48:44 INFO - Using real prefix '/tools/python27' 04:48:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:48:45 INFO - Installing distribute.............................................................................................................................................................................................done. 04:48:47 INFO - Installing pip.................done. 04:48:47 INFO - Return code: 0 04:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:48:48 INFO - Reading from file tmpfile_stdout 04:48:48 INFO - Output received: 04:48:48 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:48:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:48:48 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:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:48 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:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:48 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:48:48 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:48:48 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:48:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:48:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:48 INFO - 'HOME': '/Users/cltbld', 04:48:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:48 INFO - 'LOGNAME': 'cltbld', 04:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:48 INFO - 'MOZ_NO_REMOTE': '1', 04:48:48 INFO - 'NO_EM_RESTART': '1', 04:48:48 INFO - 'PAGER': '/bin/cat', 04:48:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:48 INFO - 'PWD': '/builds/slave/test', 04:48:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:48 INFO - 'SHELL': '/bin/bash', 04:48:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:48:48 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:48:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:48 INFO - 'USER': 'cltbld', 04:48:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:48 INFO - 'XPC_FLAGS': '0x0', 04:48:48 INFO - 'XPC_SERVICE_NAME': '0', 04:48:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48: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:48:49 INFO - Cleaning up... 04:48:49 INFO - Return code: 0 04:48:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:48: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:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:48: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:48: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:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:49 INFO - 'HOME': '/Users/cltbld', 04:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:49 INFO - 'LOGNAME': 'cltbld', 04:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:49 INFO - 'MOZ_NO_REMOTE': '1', 04:48:49 INFO - 'NO_EM_RESTART': '1', 04:48:49 INFO - 'PAGER': '/bin/cat', 04:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:49 INFO - 'PWD': '/builds/slave/test', 04:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:49 INFO - 'SHELL': '/bin/bash', 04:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:48:49 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:49 INFO - 'USER': 'cltbld', 04:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:49 INFO - 'XPC_FLAGS': '0x0', 04:48:49 INFO - 'XPC_SERVICE_NAME': '0', 04:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:49 INFO - Downloading/unpacking psutil>=3.1.1 04:48: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:48: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:48: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:48: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:48: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:48: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:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:53 INFO - Installing collected packages: psutil 04:48:53 INFO - Running setup.py install for psutil 04:48:53 INFO - building 'psutil._psutil_osx' extension 04:48: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:48:54 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:48:54 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:48:54 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:48:54 INFO - building 'psutil._psutil_posix' extension 04:48: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:48:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:48:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:48:54 INFO - ^ 04:48:54 INFO - 1 warning generated. 04:48: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:48:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:54 INFO - Successfully installed psutil 04:48:54 INFO - Cleaning up... 04:48:54 INFO - Return code: 0 04:48:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:48: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:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:54 INFO - 'HOME': '/Users/cltbld', 04:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:54 INFO - 'LOGNAME': 'cltbld', 04:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:54 INFO - 'MOZ_NO_REMOTE': '1', 04:48:54 INFO - 'NO_EM_RESTART': '1', 04:48:54 INFO - 'PAGER': '/bin/cat', 04:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:54 INFO - 'PWD': '/builds/slave/test', 04:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:54 INFO - 'SHELL': '/bin/bash', 04:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:48:54 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:54 INFO - 'USER': 'cltbld', 04:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:54 INFO - 'XPC_FLAGS': '0x0', 04:48:54 INFO - 'XPC_SERVICE_NAME': '0', 04:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:48: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:48: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:48: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:48: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:48: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:48: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:48:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:48:57 INFO - Installing collected packages: mozsystemmonitor 04:48:57 INFO - Running setup.py install for mozsystemmonitor 04:48:57 INFO - Successfully installed mozsystemmonitor 04:48:57 INFO - Cleaning up... 04:48:58 INFO - Return code: 0 04:48:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:48: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:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:48: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:48: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:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:58 INFO - 'HOME': '/Users/cltbld', 04:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:58 INFO - 'LOGNAME': 'cltbld', 04:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:58 INFO - 'MOZ_NO_REMOTE': '1', 04:48:58 INFO - 'NO_EM_RESTART': '1', 04:48:58 INFO - 'PAGER': '/bin/cat', 04:48:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:58 INFO - 'PWD': '/builds/slave/test', 04:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:58 INFO - 'SHELL': '/bin/bash', 04:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:48:58 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:58 INFO - 'USER': 'cltbld', 04:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:58 INFO - 'XPC_FLAGS': '0x0', 04:48:58 INFO - 'XPC_SERVICE_NAME': '0', 04:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:58 INFO - Downloading/unpacking jsonschema==2.5.1 04:48: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:48: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:48: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:48: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:48: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:48: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:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:49:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:01 INFO - Downloading functools32-3.2.3-2.tar.gz 04:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:49:01 INFO - warning: no files found matching '*.txt' 04:49:01 INFO - no previously-included directories found matching 'build' 04:49:01 INFO - no previously-included directories found matching 'dist' 04:49:01 INFO - no previously-included directories found matching '.git*' 04:49:01 INFO - Installing collected packages: jsonschema, functools32 04:49:01 INFO - Running setup.py install for functools32 04:49:01 INFO - warning: no files found matching '*.txt' 04:49:01 INFO - no previously-included directories found matching 'build' 04:49:01 INFO - no previously-included directories found matching 'dist' 04:49:01 INFO - no previously-included directories found matching '.git*' 04:49:01 INFO - Successfully installed jsonschema functools32 04:49:01 INFO - Cleaning up... 04:49:01 INFO - Return code: 0 04:49:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:49:01 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:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:01 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:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:01 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:01 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:49:01 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:49:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:01 INFO - 'HOME': '/Users/cltbld', 04:49:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:01 INFO - 'LOGNAME': 'cltbld', 04:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:01 INFO - 'MOZ_NO_REMOTE': '1', 04:49:01 INFO - 'NO_EM_RESTART': '1', 04:49:01 INFO - 'PAGER': '/bin/cat', 04:49:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:01 INFO - 'PWD': '/builds/slave/test', 04:49:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:01 INFO - 'SHELL': '/bin/bash', 04:49:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:01 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:01 INFO - 'USER': 'cltbld', 04:49:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:01 INFO - 'XPC_FLAGS': '0x0', 04:49:01 INFO - 'XPC_SERVICE_NAME': '0', 04:49:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:49:01 INFO - Cleaning up... 04:49:01 INFO - Return code: 0 04:49:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:49:01 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:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:01 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:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:01 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:01 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:49:01 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:49:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:01 INFO - 'HOME': '/Users/cltbld', 04:49:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:01 INFO - 'LOGNAME': 'cltbld', 04:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:01 INFO - 'MOZ_NO_REMOTE': '1', 04:49:01 INFO - 'NO_EM_RESTART': '1', 04:49:01 INFO - 'PAGER': '/bin/cat', 04:49:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:01 INFO - 'PWD': '/builds/slave/test', 04:49:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:01 INFO - 'SHELL': '/bin/bash', 04:49:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:01 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:01 INFO - 'USER': 'cltbld', 04:49:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:01 INFO - 'XPC_FLAGS': '0x0', 04:49:01 INFO - 'XPC_SERVICE_NAME': '0', 04:49:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:02 INFO - Downloading/unpacking blobuploader==1.2.4 04:49: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:49: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:49: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:49: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:49: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:49: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:49:04 INFO - Downloading blobuploader-1.2.4.tar.gz 04:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:49:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:49:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:49: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:49: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:49: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:49: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:49: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:49: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:49:05 INFO - Downloading docopt-0.6.1.tar.gz 04:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:49:06 INFO - Installing collected packages: blobuploader, requests, docopt 04:49:06 INFO - Running setup.py install for blobuploader 04:49:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:49:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:49:06 INFO - Running setup.py install for requests 04:49:06 INFO - Running setup.py install for docopt 04:49:06 INFO - Successfully installed blobuploader requests docopt 04:49:06 INFO - Cleaning up... 04:49:06 INFO - Return code: 0 04:49:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:06 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:06 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:49:06 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:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:06 INFO - 'HOME': '/Users/cltbld', 04:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:06 INFO - 'LOGNAME': 'cltbld', 04:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:06 INFO - 'MOZ_NO_REMOTE': '1', 04:49:06 INFO - 'NO_EM_RESTART': '1', 04:49:06 INFO - 'PAGER': '/bin/cat', 04:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:06 INFO - 'PWD': '/builds/slave/test', 04:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:06 INFO - 'SHELL': '/bin/bash', 04:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:06 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:06 INFO - 'USER': 'cltbld', 04:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:06 INFO - 'XPC_FLAGS': '0x0', 04:49:06 INFO - 'XPC_SERVICE_NAME': '0', 04:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-qIGgq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-OMmMjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-3W7WK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-_wQ1tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Md8qdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-YlriM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-tG_4Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:07 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-jNVS4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-5zbYiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Bn94Gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-dJoqdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-uS2jgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Q6YlfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Lan4Uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-rlFX3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-i2CLUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:08 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-JXAPcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:49:08 INFO - Running setup.py install for manifestparser 04:49:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:49:09 INFO - Running setup.py install for mozcrash 04:49:09 INFO - Running setup.py install for mozdebug 04:49:09 INFO - Running setup.py install for mozdevice 04:49:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:49:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:49:09 INFO - Running setup.py install for mozfile 04:49:09 INFO - Running setup.py install for mozhttpd 04:49:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:49:09 INFO - Running setup.py install for mozinfo 04:49:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Running setup.py install for mozInstall 04:49:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Running setup.py install for mozleak 04:49:10 INFO - Running setup.py install for mozlog 04:49:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Running setup.py install for moznetwork 04:49:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Running setup.py install for mozprocess 04:49:10 INFO - Running setup.py install for mozprofile 04:49:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Running setup.py install for mozrunner 04:49:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Running setup.py install for mozscreenshot 04:49:11 INFO - Running setup.py install for moztest 04:49:11 INFO - Running setup.py install for mozversion 04:49:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:49:11 INFO - Cleaning up... 04:49:11 INFO - Return code: 0 04:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:11 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:11 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:49:11 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:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:11 INFO - 'HOME': '/Users/cltbld', 04:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:11 INFO - 'LOGNAME': 'cltbld', 04:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:11 INFO - 'MOZ_NO_REMOTE': '1', 04:49:11 INFO - 'NO_EM_RESTART': '1', 04:49:11 INFO - 'PAGER': '/bin/cat', 04:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:11 INFO - 'PWD': '/builds/slave/test', 04:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:11 INFO - 'SHELL': '/bin/bash', 04:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:11 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:11 INFO - 'USER': 'cltbld', 04:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:11 INFO - 'XPC_FLAGS': '0x0', 04:49:11 INFO - 'XPC_SERVICE_NAME': '0', 04:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-XWXGwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:11 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:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:11 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-ppOVG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-QY4Svn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-A8l9hE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-KfVQ96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-GkaY3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-pNoeMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-v8EHTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-ATFTrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-7_Of0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:12 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-AyVub1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-w7HfF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Clihg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-LWMJR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-HZ2Z9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-HOMi4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:13 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-i532QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:13 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:49:13 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:49:13 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:49:13 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:49:13 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:49:13 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:49:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:16 INFO - Downloading blessings-1.6.tar.gz 04:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:49:16 INFO - Installing collected packages: blessings 04:49:16 INFO - Running setup.py install for blessings 04:49:16 INFO - Successfully installed blessings 04:49:16 INFO - Cleaning up... 04:49:16 INFO - Return code: 0 04:49:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:17 INFO - Reading from file tmpfile_stdout 04:49:17 INFO - Current package versions: 04:49:17 INFO - blessings == 1.6 04:49:17 INFO - blobuploader == 1.2.4 04:49:17 INFO - docopt == 0.6.1 04:49:17 INFO - functools32 == 3.2.3-2 04:49:17 INFO - jsonschema == 2.5.1 04:49:17 INFO - manifestparser == 1.1 04:49:17 INFO - mozInstall == 1.12 04:49:17 INFO - mozcrash == 1.0 04:49:17 INFO - mozdebug == 0.1 04:49:17 INFO - mozdevice == 0.49 04:49:17 INFO - mozfile == 1.2 04:49:17 INFO - mozhttpd == 0.7 04:49:17 INFO - mozinfo == 0.9 04:49:17 INFO - mozleak == 0.1 04:49:17 INFO - mozlog == 3.4 04:49:17 INFO - moznetwork == 0.27 04:49:17 INFO - mozprocess == 0.25 04:49:17 INFO - mozprofile == 0.28 04:49:17 INFO - mozrunner == 6.13 04:49:17 INFO - mozscreenshot == 0.1 04:49:17 INFO - mozsystemmonitor == 0.3 04:49:17 INFO - moztest == 0.8 04:49:17 INFO - mozversion == 1.4 04:49:17 INFO - psutil == 3.1.1 04:49:17 INFO - requests == 1.2.3 04:49:17 INFO - wsgiref == 0.1.2 04:49:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:17 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:17 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:49:17 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:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:17 INFO - 'HOME': '/Users/cltbld', 04:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:17 INFO - 'LOGNAME': 'cltbld', 04:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:17 INFO - 'MOZ_NO_REMOTE': '1', 04:49:17 INFO - 'NO_EM_RESTART': '1', 04:49:17 INFO - 'PAGER': '/bin/cat', 04:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:17 INFO - 'PWD': '/builds/slave/test', 04:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:17 INFO - 'SHELL': '/bin/bash', 04:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:17 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:17 INFO - 'USER': 'cltbld', 04:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:17 INFO - 'XPC_FLAGS': '0x0', 04:49:17 INFO - 'XPC_SERVICE_NAME': '0', 04:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 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:49:17 INFO - Cleaning up... 04:49:17 INFO - Return code: 0 04:49:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:17 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 0x105bfcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83fbc751f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, '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.NeUtApGlgC/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.tqk7usVTCp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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:17 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:49:17 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:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:17 INFO - 'HOME': '/Users/cltbld', 04:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:17 INFO - 'LOGNAME': 'cltbld', 04:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:17 INFO - 'MOZ_NO_REMOTE': '1', 04:49:17 INFO - 'NO_EM_RESTART': '1', 04:49:17 INFO - 'PAGER': '/bin/cat', 04:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:17 INFO - 'PWD': '/builds/slave/test', 04:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:17 INFO - 'SHELL': '/bin/bash', 04:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:17 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:17 INFO - 'USER': 'cltbld', 04:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:17 INFO - 'XPC_FLAGS': '0x0', 04:49:17 INFO - 'XPC_SERVICE_NAME': '0', 04:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:49:17 INFO - Cleaning up... 04:49:17 INFO - Return code: 0 04:49:17 INFO - Running post-action listener: _resource_record_post_action 04:49:17 INFO - Running post-action listener: _start_resource_monitoring 04:49:17 INFO - Starting resource monitoring. 04:49:17 INFO - [mozharness: 2017-03-20 11:49:17.615022Z] Finished create-virtualenv step (success) 04:49:17 INFO - [mozharness: 2017-03-20 11:49:17.616029Z] Running install step. 04:49:17 INFO - Running pre-action listener: _resource_record_pre_action 04:49:17 INFO - Running main action method: install 04:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:17 INFO - Reading from file tmpfile_stdout 04:49:17 INFO - Detecting whether we're running mozinstall >=1.0... 04:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:49:17 INFO - Reading from file tmpfile_stdout 04:49:17 INFO - Output received: 04:49:17 INFO - Usage: mozinstall [options] installer 04:49:17 INFO - Options: 04:49:17 INFO - -h, --help show this help message and exit 04:49:17 INFO - -d DEST, --destination=DEST 04:49:17 INFO - Directory to install application into. [default: 04:49:17 INFO - "/builds/slave/test"] 04:49:17 INFO - --app=APP Application being installed. [default: firefox] 04:49:17 INFO - mkdir: /builds/slave/test/build/application 04:49:17 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:49:17 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:49:38 INFO - Reading from file tmpfile_stdout 04:49:38 INFO - Output received: 04:49:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:49:38 INFO - Running post-action listener: _resource_record_post_action 04:49:38 INFO - [mozharness: 2017-03-20 11:49:38.353340Z] Finished install step (success) 04:49:38 INFO - [mozharness: 2017-03-20 11:49:38.353489Z] Running run-tests step. 04:49:38 INFO - Running pre-action listener: _resource_record_pre_action 04:49:38 INFO - Running main action method: run_tests 04:49:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:38 INFO - 2017-03-20 04:49:38.367 screenresolution[1624:7416] starting screenresolution argv=screenresolution get 04:49:38 INFO - 2017-03-20 04:49:38.370 screenresolution[1624:7416] Display 0: 1600x1200x32@60 04:49:38 INFO - 2017-03-20 04:49:38.375 screenresolution[1625:7418] starting screenresolution argv=screenresolution list 04:49:38 INFO - Available Modes on Display 0 04:49:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:38 INFO - Intel Iris: 04:49:38 INFO - Chipset Model: Intel Iris 04:49:38 INFO - Type: GPU 04:49:38 INFO - Bus: Built-In 04:49:38 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:38 INFO - Vendor: Intel (0x8086) 04:49:38 INFO - Device ID: 0x0a2e 04:49:38 INFO - Revision ID: 0x0009 04:49:38 INFO - Displays: 04:49:38 INFO - Display: 04:49:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:38 INFO - Display Serial Number: 1600x1200 60 04:49:38 INFO - Main Display: Yes 04:49:38 INFO - Mirror: Off 04:49:38 INFO - Online: Yes 04:49:38 INFO - Rotation: Supported 04:49:38 INFO - Return code: 0 04:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:49:38 INFO - Python 2.7.3 04:49:38 INFO - Return code: 0 04:49:38 INFO - grabbing minidump binary from tooltool 04:49:38 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:38 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 0x1059dad30>, '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 0x105901db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059cfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:49:38 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:49:38 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:49:38 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:49:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:49:38 INFO - Return code: 0 04:49:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:49:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:49:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:49:38 INFO - ENV: RUST_BACKTRACE is now 1 04:49:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:49:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--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-0329', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --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-0329 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NeUtApGlgC/Render', 04:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:38 INFO - 'HOME': '/Users/cltbld', 04:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:38 INFO - 'LOGNAME': 'cltbld', 04:49:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:49:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:38 INFO - 'MOZ_NO_REMOTE': '1', 04:49:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:49:38 INFO - 'NO_EM_RESTART': '1', 04:49:38 INFO - 'PAGER': '/bin/cat', 04:49:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:38 INFO - 'PWD': '/builds/slave/test', 04:49:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:38 INFO - 'RUST_BACKTRACE': '1', 04:49:38 INFO - 'SHELL': '/bin/bash', 04:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tqk7usVTCp/Listeners', 04:49:38 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 04:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:38 INFO - 'USER': 'cltbld', 04:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:38 INFO - 'XPC_FLAGS': '0x0', 04:49:38 INFO - 'XPC_SERVICE_NAME': '0', 04:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--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-0329', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:49:38 INFO - mozversion application_buildid: 20170320031743 04:49:38 INFO - mozversion application_changeset: 1c9cf05afab07f505e360d404dfbaba81a1b4721 04:49:38 INFO - mozversion application_display_name: Nightly 04:49:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:49:38 INFO - mozversion application_name: Firefox 04:49:38 INFO - mozversion application_remotingname: firefox 04:49:38 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 04:49:38 INFO - mozversion application_vendor: Mozilla 04:49:38 INFO - mozversion application_version: 55.0a1 04:49:38 INFO - mozversion platform_buildid: 20170320031743 04:49:38 INFO - mozversion platform_changeset: 1c9cf05afab07f505e360d404dfbaba81a1b4721 04:49:38 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 04:49:38 INFO - mozversion platform_version: 55.0a1 04:49:38 INFO - using testdate: 1490010578 04:49:38 INFO - actual date: 1490010578 04:49:38 INFO - starting webserver on 'localhost:49246' 04:49:38 INFO - SUITE-START | Running 2 tests 04:49:38 INFO - TEST-START | dromaeo_css 04:49:38 INFO - operating with platform_type : mac_ 04:49:38 INFO - Initialising browser for dromaeo_css test... 04:49:38 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpUrnM4Q/profile http://localhost:49246/getInfo.html) 04:49:41 INFO - PID 1635 | 2017-03-20 04:49:41.963 plugin-container[1637:7584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 04:49:41 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:49:41 INFO - PID 1635 | 2017-03-20 04:49:41.966 plugin-container[1637:7584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b13, name = 'com.apple.CFPasteboardClient' 04:49:41 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:49:41 INFO - PID 1635 | 2017-03-20 04:49:41.966 plugin-container[1637:7584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:49:42 INFO - PID 1635 | __metrics Screen width/height:1600/1200 04:49:42 INFO - PID 1635 | colorDepth:24 04:49:42 INFO - PID 1635 | Browser inner width/height: 1024/689 04:49:42 INFO - PID 1635 | __metrics 04:49:42 ERROR - PID 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 04:49:42 INFO - TEST-INFO | 1635: exit 0 04:49:42 INFO - Browser initialized. 04:49:42 INFO - Running cycle 1/1 for dromaeo_css test... 04:49:42 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpUrnM4Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:45 INFO - PID 1638 | 2017-03-20 04:49:45.415 plugin-container[1639:7720] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 04:49:45 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:49:45 INFO - PID 1638 | 2017-03-20 04:49:45.415 plugin-container[1639:7720] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 04:49:45 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:49:45 INFO - PID 1638 | 2017-03-20 04:49:45.416 plugin-container[1639:7720] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:49:55 INFO - PID 1638 | RSS: Main: 227786752 04:49:55 INFO - PID 1638 | 04:52:58 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 04:52:59 INFO - PID 1638 | RSS: Main: 251117568 04:52:59 INFO - PID 1638 | 04:54:43 INFO - PID 1638 | ************************* 04:54:43 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 04:54:43 INFO - PID 1638 | 04:54:43 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:54:43 INFO - PID 1638 | Full stack: 04:54:43 INFO - PID 1638 | ************************* 04:56:01 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 04:56:02 INFO - PID 1638 | RSS: Main: 259592192 04:56:02 INFO - PID 1638 | 04:59:04 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 04:59:05 INFO - PID 1638 | RSS: Main: 270462976 04:59:05 INFO - PID 1638 | 05:02:07 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 05:02:07 INFO - PID 1638 | RSS: Main: 264040448 05:02:07 INFO - PID 1638 | 05:05:10 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 05:05:11 INFO - PID 1638 | RSS: Main: 273649664 05:05:11 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 05:08:14 INFO - PID 1638 | RSS: Main: 267526144 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | __start_tp_report 05:08:14 INFO - PID 1638 | _x_x_mozilla_page_load 05:08:14 INFO - PID 1638 | _x_x_mozilla_page_load_details 05:08:14 INFO - PID 1638 | |i|pagename|runs| 05:08:14 INFO - PID 1638 | |0;dojo.html;2977.6426828780536;3017.894373696921;3312.734981201504;3502.054445299769;3513.595936467307;5600;5969.761209551623;5983.132445795357;6009.609375390596;6043.728032238549;4529.501754806968;4954.810845397105;6020.909686354708;6104.2674879014585;6133;5269.2886460327745;5324.068288049595;5369.382521010075;5392.595555333346;5419.620626556123;5409.648372855771;5441.129419292914;5459.727013649302;5478.616496845222;5481.75332110054;5333.986542556921;5356.9732151339385;5379.031774280628;5402.270693456372;5466.808661696861;4546.590806827401;5258.447862974409;5373.462653734634;5394.000915586873;5405.864853378657;5278.996990571817;5394.703291318976;5399.974004939049;5417.26866872971;5425.403205647375;4292.7424354538825;5816.709164541756;5846.239988801429;5926.229590153295;6019.307779605358;6364.586301890408;6399.8080057598345;6419.582727122722;6422.100905873182;6422.7109780059545;6500.05749166373;6505.804825855232;6509.414152726277;6520.608763474207;6527.184101987252;5934.465898069108;6112.419320164578;6171.845703857394;6184.350643182446;6242.188515493048;6424.13274207976;6450.161479007699;6460.870782584416;6623.4038936495945;6650.467962562983;4958.330625365529;5778;6141.056449558148;6281.842953926189;6366.84082897922;6414.358564143548;6484.578502397423;6495.090687303781;6497.512686548528;6497.805065848032;6351.237851457855;6354.618722876642;6362.77730279438;6367.235931688135;6385.329540398284;6208.627482351073;6268.968655156695;6285.811425657238;6322.810315690444;6341.778037768656;5154.61340399464;5163.715995620277;5168.405633352213;5179.22311653255;5198.948010519922;4598.425196850394;4612.907741845144;4626.028534007896;4629.143608432383;4643.257078867365;6045.21412216409;6102.883130681719;6113.537117903944;6139.29398119222;6234.781782637677;5263;6423.614583125028;6457.741690332334;6470.45001174896;6475.676216189172;6119.418655227746;6160;6200.875982480326;6230.06549017651;6250.343713910065;4585.633149348044;5632.605717599728;5694.829155125353;5714.685692286964;5767.596268261182;6466.256380516207;6588.802335929834;6631.535792494479;6640.634765087966;6675.532712710064;6457.4511166551365;6469.676516174079;6474.676266186766;6483.124778155077;6532.444742196966;6403.359664033559;6585.5719378240265;6656.267810540739;6696.799096027127;6698.899516507159;5322;6174.969125154345;6306.211723534559;6401.711922963571;6406.583572067801;5101.387833460009;5710.428957104256;6134.4172303631085;6432.292447830641;6435.549511534147;5508.807191748269;6146.969265153645;6162.815115546541;6209.720562574605;6264.561480696308;6312.873742525216;6351.523635727246;6355.491560675043;6371.267304259884;6424.421802037839;5020.447750747342;6137.897974673567;6152.98475751496;6199.442050215503;6215.875682486416;6267.2479302484;6274.529410294155;6309.1167236587835;6309.305976342691;6312.779052733316;6257.311695713559;6264.185655864709;6281.654509002049;6286.088517165077;6287.46556542699 05:08:14 INFO - PID 1638 | |1;ext.html;632.8702615963728;747.2863415438258;787.2796391301958;814.381070386506;935.4948327902937;29205.853970730142;38326.808365958204;38333.42499862497;38350.616493835056;38390.424143637865;37673.05817354567;38124.42813357795;38158.23683526335;38244.85265442034;38245.80877095617;7784.805379865506;10855.565777368896;11154.163437742161;11155.995960363565;11163.939425754541;11042.061424778904;11138.27601205923;11156.995870371666;11157.832632510519;11200.495977681025;11217;11307.151963602762;11315.603953861617;11316;11333.489992950295;8739.781505462352;9740.610375585004;11176.608818691388;11238.381888996142;11303.604373846916;9148.908510914873;10911.83632245517;10997.450127493674;11000.724981875437;11040.951109644571;31690.524642130385;38896.22207555833;39081.60918390837;39099;39163.8041809792;3785.6592906638534;3808.5620153682403;3813.218290250492;3842;3869.4582758413844;28009.29976750577;28159.296017599725;28382.432351352858;28759.424811503653;29233.97681081173;3167.9683203167906;3172.6510083890753;3198.712115909556;3268.7548433867555;3296.8516416761304;226993.86503067543;236706;239788;322583;325101;37593.060173495884;45973.310400344024;46234;46259.76870115628;46713.5328646716;47503.762481187376;47505.5249447508;47578.145311281434;47719.522804772205;47826.891252068504;14350.49773257931;14474;14478.782818257734;14503;14508.274586270643;11873.168878178445;11899.226550274205;11911.987481064207;11916.52333906634;11957.402129893471;13615.910727141805;13639.249841258628;13709.657258568615;13729.107608005448;13823.308834558433;5170.741462926914;6089.756409743649;6096.72564734588;6121.387861213932;6153.4154255345675;1090.816464136405;1095.687728997232;1105.4362275239685;1122.8708698499613;1176.088531388181;21720;25442.74557254441;25625;25847.870760646078;26019;1029.994850025745;1212.8241404996402;1223.1560223445972;1224.7672942140964;1261.6152073617452;10627.628033018807;13084.08411411192;13164.670883227996;13279.26964016969;13415.798763018562;291827;341057;342282;345115;411500;50083.74958125259;50264.74867625711;55849.720751396795;55936;55993.440065599636;1257.6730050187018;1275.8150068240054;1411.4142630808099;1457.8396376398587;1466.1349803615917;26928.32679182958;27363.042293519633;28507.28731781722;28665.14004579866;29007.54962251921;52448.73775631098;52515.47484525259;53062.734686327865;53090;53208.467915320354;436044;436998;437135.1008144099;437356;438231;32592.674073259914;33432.16419589432;33546.161345966546;33563;34188;40702.389464157466;41268.96827579215;41510.1697966045;42175.57824421716;43254.351184732885;35268.47097293491;35281.47077793886;35425.46861797023;36425;36443.906682799556;34873.47689784604;35083;36079.27841443209;36093;36098;29283.121506354844;30130;34524.964251071506;34663.960081197605;35956.4606530897;31073.8446307767;32495;32497.02508924736;32621;32755;22109.025469269287;24341.26976190717;24380.6342904853;24843.378915527257;24940.50118997646 05:08:14 INFO - PID 1638 | |2;jquery.html;405.8660641988144;407.9877603671891;413.79723935271716;420.844287613583;435.43828461284943;20291.49271268219;20351.898240508795;21046.789532104693;22483;22781.54436911265;21971;22553.661695074545;22811.657825132595;22899.542009159806;23039.654405183937;6169.845753856167;6721.126253587051;6726.663666816664;6727.730890764388;6732.01405585351;6314.968425157867;6627.602343859384;6652.201735791712;6687.899681504757;6709.295523969986;6466.127072845153;6650.401463868251;6669.599824010575;6694.497912656546;6742.898856517156;6697.832554186136;6738;6766.966165169168;6780.762673306411;6786.694598743068;6340.714667839959;6481.64350960697;6554.5739526931075;6576.1122248496795;6576.704048317837;27543.586846197322;27716.673999120034;28157;28304.575431368547;28732.281692957844;2881.106856874376;2932.325565120013;2937.9265518362;2940.426616809723;2974.464596372652;14427.78358324626;15501.922490387477;16486.258118384583;16610.83389166105;17784.55538611545;1996.6006798640185;2000.4898750818447;2000.9599808003904;2028.1684509351244;2029.553498230387;127451.36274318662;132256;157060;171657;172271;22239.555208896054;23273.650895236584;23289.18487852917;23503.294901152993;23576.41058973505;36335.091622709646;36408;36519.63480365162;36577.81711091428;36702.89891303265;10432.530536125892;10716;10807.459627018618;10822.971817677308;10984.505697243643;8657.350698697497;8676.089010653917;8691.869621955675;8836.116388361112;8841.955790221007;9112.362134650512;10661.566134315139;11056.391898445663;11102.389368584805;11175.105991520659;3935.9569714025806;4386.956130438719;4501.3248012797685;4521.525239849834;4536.750478723636;947.303731757158;982.8181786369545;1005.3314545826943;1006.6879267426974;1021.6219998600566;21536.907987482642;21983.780162198495;22150.33548993533;22164.667529987062;22219.888900555394;935.265816334188;992.6277645882794;1022.3048327137469;1023.8617786598862;1038.7039693687263;11230.494627741607;11567.710807229718;11577.073834093253;11598.826017609743;11678.182527223013;652421;668887;674717;676249;685311;151899.24050379678;154750;157457.2127139357;159501.70713296696;160369.19815400848;1224.3082658298033;1296.5915736542959;1321.035643979881;1321.5770953295046;1322.4379638653534;20557;20576.485587859825;20932.686009709563;20974.58050838944;20994.790052099284;48273.75863120662;58281;62384.68807655932;62804.685976569825;64890.37774055686;83812.58093709736;84060.15939840523;84719.72920406076;85067.72398413905;86030.2793944105;11616.244944078608;11636.36000020007;11652.126090543074;11887.405629718653;11927.642170734893;10882.401467919024;12946.870531294566;13004.8049279259;13027.47890084386;13127.540536081191;9118.544072796467;9374.859377109213;10452.216083793897;10487.842682359618;10619.1701351984;9637.566309516229;10613.832478427212;11095.27880687785;11109.777804443704;11429.314241145557;9516.238700903772;11441.599544015919;11503.884961150281;11612.245204061708;11676.532938682698;10174.898251017396;11067.72330691739;11762.353070580857;11819.936205741524;11892.405379731152;11584.304941703525;11586.652400428;11799.056075513767;11828.940855295668;11854.288742675468 05:08:14 INFO - PID 1638 | |3;mootools.html;1372.9656758581034;1496.2144873941172;1543.3055125193666;1559.8440155984404;1582.050769538277;10406.42764647944;10740.409277489734;10836.67489975301;10861;11096.500657470413;10879.401632910187;10936.890631093687;10943.507542160602;11010.119190464764;11017.559297628106;2236.630955892276;2285.737140228878;2286.4283929017747;2290.931272061841;2293.9541209175804;2195.4730864592543;2229.643257078868;2244.3491387497606;2245.1805091141696;2248.8313376496744;2254.256095488485;2261.378121016717;2266.7506574276817;2268.6710426988075;2269.1944359752297;2210.8784016879067;2226;2234.955300893981;2236.474428509299;2245.303955773707;2261.6155253606926;2320.7679232076825;2325.837191396603;2327.057541695616;2329.2546385156757;10888;10909.945450272777;10958;10966.616168434066;10970.725731856688;4714.222153344694;5584.134459158837;5824.301083869963;5828.737706803204;5861.3552509223955;2774.3064233941514;3147.35479226758;3249.3014001989686;3284.6204594070546;3462.8961131166106;1078.1159449251618;1095.8904109588977;1116.7598966222301;1139.6694958462035;1145.513156908319;12404.999999999909;13561;13686.931565342209;15049.097054176675;15060.774088388684;4380.8685739427865;4456.9108617828115;4635.768211589407;4638.512956139557;4644.373009643722;11411.258268212705;11417.200795944203;11544.076473882069;11601.709957251136;11611.419429028514;5591.888162236733;5635.633683810539;5650.830475085754;5653.038983372837;5691.9146212806845;5018.347614810052;5093.414257360451;5173.896522069537;5198.2722418861385;5211.557017653542;3810.3141434541494;3839.3856982882603;3892.0464486200494;3950.4469374287046;3952.881413557598;862.3618522293458;1028.3521381529588;1036.7822757221068;1047.8480620310165;1052.484282701486;706.8480276740526;927.2767241551601;934.7990182110879;938.9014153513783;944.7071407863584;5715.885402346503;6260.593061450991;6482.157319548429;6753.527253092237;6772.864542709118;768.6809973860896;906.759708677201;935.8455854784021;942.4675058591873;945.6359301668907;3885.8834234972996;3885.9805700971315;4194.832206711697;4202.973858821643;4204.789760511962;12396.628101156979;12715.55495557651;13051.086423950423;13497.32513374347;13515.932420337835;13283.800742988862;13301.600951971457;13442.596722098353;13480.123791953492;13606.31968401576;9914.008599140172;9978.052085051908;10061.295709300279;10093.444860532594;10104.74738131538;6315.273743519554;6421.518386120966;6475.316417731329;6526.151600292122;6590.110335104891;11836.349000805038;11856.940715296369;11893.108016898594;11980.64058078259;12043.638690839289;10617.203709721647;12098.939505302416;12297.692557686129;12310.446029928495;12442.688932776753;8209.671613135646;8373.49759014461;8405.87391189145;8424.915750842412;8461.323094152576;8550.315974722133;9919.355241909252;10139.949300253451;10248.846267306146;10297.485125743533;8819.470831750115;8963.282937364866;9002.279817614444;9048.230900373543;9195.724128276162;7450.627468626655;8748.081451447506;8797.824043518965;8825.69110081144;8974.86537701922;5990.520758339409;7750.031246094239;7762.456628035983;7898.644560994884;7940.126586075412;5668.801591944262;5810.186573879576;5825.155352473952;5920.200772895776;5920.763169473173;5935.465808077121;5980.415189974573;6013.098035294741;6024.367441418588;6038.6678732670125 05:08:14 INFO - PID 1638 | |4;prototype.html;386.6442872557247;387.0941995729991;388.8833349995001;391.7081774078312;397.3384315115332;4693.671442998992;5131.435542090367;5576.888462230758;5691.943080569193;6118.1434599156155;5741.043362668229;5861.325947516037;5874.6181498202695;5878.441548052928;5890.234269544964;4015.07653239755;4719.6224302055925;4850.296706977486;4885.658003939726;4888.291197776338;4060.7969601519953;4840.910795071099;4870.245112007627;4875.463698993108;4910.558049775519;4873.926891096637;4885.2427873679635;4901.995091006335;4907.858970259037;4910.060526092191;4622.8382006629945;4656.813727450898;4806.783694733728;4841.225403935388;4845.878853028667;3776.188119554309;4356.542563030899;4417.425734654506;4439.933400998988;4451.332300154971;4769.35613692154;5077.695338279715;5623.803166889139;5723.914141287884;5769.394213607595;4391.5827996340295;4710.8351207707565;4996.200607902718;5681.147827825818;5706.258186435737;5272.340957380327;5466.945330546723;6287.811365659038;6321.178246827929;6358.682065896686;3909.6090390960962;4403.691741578091;4814.927776083362;5032.207125045598;5056.30613744396;5357.43746906577;6164.907526387108;6383.138276332682;6658.434033107192;6780.423663988567;4151.356539736345;5201.555269023553;5216.947830521722;5225.268462415265;5247.527722504993;4489.7979590918485;5243.082460569385;5300.787968481295;5305.602079844027;5319.494648008433;4665.4168228971375;4959.727215003138;5721.113227449752;5725.026745453283;5955.255593050869;6341.619502829845;6395.4418232707285;6429.7428102875365;6438.3883531064475;6438.549301548846;1903.9333623323116;2013.919443222284;2033.7152798608206;2035.2673037706709;2036.562139140092;1461.9035723207594;1570.0971941133894;1590.9124998124983;1625.7642641817106;1661.7008938390964;5634.690092044977;5647.463490968352;5980.594560278331;6006.819795406145;6020.488258498077;6456.644884531302;6475.32009139043;6534.771283005167;6540.672966351759;6571.868562628816;4947.925781113287;5214.983078299695;5765.57845704805;5819.53443724501;5902.852428689317;2922.5177845655653;3391.2878295558207;3417.829108544563;3442.5524681791208;3517.542719446507;5250.897311564614;5627.803026894039;6522.673866306665;6547.134066790942;6739.123913891261;5215.973920130375;5883.970580147072;6591.340865913467;6639.037339585733;6665.333466653296;6370.362963703593;6471.967640161769;6607;6725.630090345078;6749.08887300218;5897.882042359043;6008.519318454601;6417.711202995783;6496.187976502937;6522.184726909137;5268;6232.5325600581045;6366.808995730135;6433.806985790434;6454.515911306765;6341.587796793378;6830.624315662686;6838.111045564045;6866.519343646097;6873.381395674413;4971.204607262821;5413.648112872651;5683.204351390726;5686.573506987075;5695.914561281667;5146.970605878764;6377.7767778127545;6543.378379053982;6546.887029204951;6633.502487313374;5271.023366237661;5542.916856247079;6362.331955144643;6385.233771947396;6411.6153030818305;6087.878242435215;6145.385461453998;6336.9049464259;6403.199600049994;6428.55000149985;5209.843704688866;6227.906581401373;6241.719122639583;6248.562600617913;6277.686115694289;5039;5443.237946687388;6096.878062438637;6422.614643121427;6422.921444882627;6156.5998210116195;6215.663632319073;6232.25212974446;6240.687965601793;6281.120643109877 05:08:14 INFO - PID 1638 | |5;yui.html;655.8196495963613;716.9354758071772;757.776455945496;817.9591020448978;828.2918105020208;14078.451370349267;14554.563363099123;14844.77732834007;14904.776428353583;15924.203789810506;14483.782743258833;14608.415663373453;14715.705685886276;14727.852721472782;15080.54758357251;914.1270087066869;941.7692665297012;951.6764300137952;953.1088432315772;953.5232383808096;670.2191946382459;670.4301343857707;923.4136323434634;952.0622187145656;956.8469044952809;992.9553170107363;994.462989985407;998.0518507417981;1000.6097621927454;1000.774825664227;992.5235886774352;994.5574219472302;994.9005099490065;994.9801003979952;996.1084829077971;738.3428748413916;739.5008369350667;739.7743688175104;740.2597402597403;740.3336996702957;15575.688486230325;15856;16019.599510012227;16127;16457.09485978271;11435.59975400857;13237.271950042743;13702.588922332247;14302.356393962296;14319.036606745976;11259.380734059623;11518.712032199179;11558.595449159238;11565.132615053904;12031.759364812655;3613.5184127536154;3657.4513822926506;3667.9266414671556;3727.869524566627;3749.3813520769313;87398;91857.54071229669;92020;92557.07442925552;108664;11383.260088094248;11512.30926144434;11675;11959.222650527689;12082.456289466994;23928.521429571658;24019.759802402103;24566.017359305784;24586.877065614557;24626.014959401422;7727.922720772833;7801.609919504002;7867.803304917308;7985.72049978248;8002.149029205821;4142.171565686815;4143.81352839123;4147.91704165915;4183.1215444756335;4186.079062606222;3470.201853573692;3505.544279243733;3516.226430185355;3541.38025845476;3554;639.6322114784018;640.2285246278224;647.3591144766647;654.8853950558729;656.409231691472;643.8165122939939;648.5687018132983;688.6246995387526;702.2836706559281;717.2576383443215;9823.312368134304;9929.50352482373;10158.492075396201;10375.221858360654;10541.0513053824;476.7854465490543;520.3157847430616;570.8572856785804;574.7269301328975;594.4055944055945;4486.371907932892;4891.682040667345;4905.828296009622;4972.975135124374;4987.326710894055;77000.84498732524;88449;88625;92568;96743.51628241813;64764.73323433657;70559;72348;81485.59257203677;84873.57563212268;70182;70424;73134.2686573138;74068.5186296271;74369;10820.206301965489;10873.401962892116;10967.616133435291;11004.944975275072;11096.112311014938;39697.206055878145;43437.565624343355;46640.833979149436;49260;49376.75311623419;39298;57842.7107864458;62764.68617656882;63066.738665227356;63223.36776632359;12949.741005180033;13448.99132565042;13615.727685446038;13711.725765484434;13762.380692868644;10993.780124397308;12507.061970352079;12619.49522019109;14123.717525649636;15139.924300378427;11665.80701475952;13659.521916732867;13825.239611821446;13920.095193812369;14130.434782608581;11322.490487928228;12928.288944108164;13651.795223071464;13658.931705341409;14584.343704533108;11968.401579921143;13040.347982601023;13261;13697.589072327846;13917.0953888001;10767.838482422927;11034.455394473947;11198.776024479628;11217.326960382403;11846.644600661994;7750.571432839888;9373.390729602554;9666.275029372684;9748.432349369054;10556.84164737545 05:08:14 INFO - PID 1638 | __end_tp_report 05:08:14 INFO - PID 1638 | __start_cc_report 05:08:14 INFO - PID 1638 | _x_x_mozilla_cycle_collect,431 05:08:14 INFO - PID 1638 | __end_cc_report 05:08:14 INFO - PID 1638 | __startTimestamp1490011694504__endTimestamp 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | ------- Summary: start ------- 05:08:14 INFO - PID 1638 | Number of tests: 6 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:5885.41 Median:6171.85 stddev:730.08 (11.8%) stddev-sans-first:695.77 05:08:14 INFO - PID 1638 | Values: 2977.6 3017.9 3312.7 3502.1 3513.6 5600.0 5969.8 5983.1 6009.6 6043.7 4529.5 4954.8 6020.9 6104.3 6133.0 5269.3 5324.1 5369.4 5392.6 5419.6 5409.6 5441.1 5459.7 5478.6 5481.8 5334.0 5357.0 5379.0 5402.3 5466.8 4546.6 5258.4 5373.5 5394.0 5405.9 5279.0 5394.7 5400.0 5417.3 5425.4 4292.7 5816.7 5846.2 5926.2 6019.3 6364.6 6399.8 6419.6 6422.1 6422.7 6500.1 6505.8 6509.4 6520.6 6527.2 5934.5 6112.4 6171.8 6184.4 6242.2 6424.1 6450.2 6460.9 6623.4 6650.5 4958.3 5778.0 6141.1 6281.8 6366.8 6414.4 6484.6 6495.1 6497.5 6497.8 6351.2 6354.6 6362.8 6367.2 6385.3 6208.6 6269.0 6285.8 6322.8 6341.8 5154.6 5163.7 5168.4 5179.2 5198.9 4598.4 4612.9 4626.0 4629.1 4643.3 6045.2 6102.9 6113.5 6139.3 6234.8 5263.0 6423.6 6457.7 6470.5 6475.7 6119.4 6160.0 6200.9 6230.1 6250.3 4585.6 5632.6 5694.8 5714.7 5767.6 6466.3 6588.8 6631.5 6640.6 6675.5 6457.5 6469.7 6474.7 6483.1 6532.4 6403.4 6585.6 6656.3 6696.8 6698.9 5322.0 6175.0 6306.2 6401.7 6406.6 5101.4 5710.4 6134.4 6432.3 6435.5 5508.8 6147.0 6162.8 6209.7 6264.6 6312.9 6351.5 6355.5 6371.3 6424.4 5020.4 6137.9 6153.0 6199.4 6215.9 6267.2 6274.5 6309.1 6309.3 6312.8 6257.3 6264.2 6281.7 6286.1 6287.5 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:50174.43 Median:178799.01 stddev:94945.87 (53.1%) stddev-sans-first:95139.36 05:08:14 INFO - PID 1638 | Values: 632.9 747.3 787.3 814.4 935.5 29205.9 38326.8 38333.4 38350.6 38390.4 37673.1 38124.4 38158.2 38244.9 38245.8 7784.8 10855.6 11154.2 11156.0 11163.9 11042.1 11138.3 11157.0 11157.8 11200.5 11217.0 11307.2 11315.6 11316.0 11333.5 8739.8 9740.6 11176.6 11238.4 11303.6 9148.9 10911.8 10997.5 11000.7 11041.0 31690.5 38896.2 39081.6 39099.0 39163.8 3785.7 3808.6 3813.2 3842.0 3869.5 28009.3 28159.3 28382.4 28759.4 29234.0 3168.0 3172.7 3198.7 3268.8 3296.9 226993.9 236706.0 239788.0 322583.0 325101.0 37593.1 45973.3 46234.0 46259.8 46713.5 47503.8 47505.5 47578.1 47719.5 47826.9 14350.5 14474.0 14478.8 14503.0 14508.3 11873.2 11899.2 11912.0 11916.5 11957.4 13615.9 13639.2 13709.7 13729.1 13823.3 5170.7 6089.8 6096.7 6121.4 6153.4 1090.8 1095.7 1105.4 1122.9 1176.1 21720.0 25442.7 25625.0 25847.9 26019.0 1030.0 1212.8 1223.2 1224.8 1261.6 10627.6 13084.1 13164.7 13279.3 13415.8 291827.0 341057.0 342282.0 345115.0 411500.0 50083.7 50264.7 55849.7 55936.0 55993.4 1257.7 1275.8 1411.4 1457.8 1466.1 26928.3 27363.0 28507.3 28665.1 29007.5 52448.7 52515.5 53062.7 53090.0 53208.5 436044.0 436998.0 437135.1 437356.0 438231.0 32592.7 33432.2 33546.2 33563.0 34188.0 40702.4 41269.0 41510.2 42175.6 43254.4 35268.5 35281.5 35425.5 36425.0 36443.9 34873.5 35083.0 36079.3 36093.0 36098.0 29283.1 30130.0 34525.0 34664.0 35956.5 31073.8 32495.0 32497.0 32621.0 32755.0 22109.0 24341.3 24380.6 24843.4 24940.5 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:41179.63 Median:22361.28 stddev:112768.36 (504.3%) stddev-sans-first:113043.15 05:08:14 INFO - PID 1638 | Values: 405.9 408.0 413.8 420.8 435.4 20291.5 20351.9 21046.8 22483.0 22781.5 21971.0 22553.7 22811.7 22899.5 23039.7 6169.8 6721.1 6726.7 6727.7 6732.0 6315.0 6627.6 6652.2 6687.9 6709.3 6466.1 6650.4 6669.6 6694.5 6742.9 6697.8 6738.0 6767.0 6780.8 6786.7 6340.7 6481.6 6554.6 6576.1 6576.7 27543.6 27716.7 28157.0 28304.6 28732.3 2881.1 2932.3 2937.9 2940.4 2974.5 14427.8 15501.9 16486.3 16610.8 17784.6 1996.6 2000.5 2001.0 2028.2 2029.6 127451.4 132256.0 157060.0 171657.0 172271.0 22239.6 23273.7 23289.2 23503.3 23576.4 36335.1 36408.0 36519.6 36577.8 36702.9 10432.5 10716.0 10807.5 10823.0 10984.5 8657.4 8676.1 8691.9 8836.1 8842.0 9112.4 10661.6 11056.4 11102.4 11175.1 3936.0 4387.0 4501.3 4521.5 4536.8 947.3 982.8 1005.3 1006.7 1021.6 21536.9 21983.8 22150.3 22164.7 22219.9 935.3 992.6 1022.3 1023.9 1038.7 11230.5 11567.7 11577.1 11598.8 11678.2 652421.0 668887.0 674717.0 676249.0 685311.0 151899.2 154750.0 157457.2 159501.7 160369.2 1224.3 1296.6 1321.0 1321.6 1322.4 20557.0 20576.5 20932.7 20974.6 20994.8 48273.8 58281.0 62384.7 62804.7 64890.4 83812.6 84060.2 84719.7 85067.7 86030.3 11616.2 11636.4 11652.1 11887.4 11927.6 10882.4 12946.9 13004.8 13027.5 13127.5 9118.5 9374.9 10452.2 10487.8 10619.2 9637.6 10613.8 11095.3 11109.8 11429.3 9516.2 11441.6 11503.9 11612.2 11676.5 10174.9 11067.7 11762.4 11819.9 11892.4 11584.3 11586.7 11799.1 11828.9 11854.3 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6534.29 Median:2960.83 stddev:4089.74 (138.1%) stddev-sans-first:4082.82 05:08:14 INFO - PID 1638 | Values: 1373.0 1496.2 1543.3 1559.8 1582.1 10406.4 10740.4 10836.7 10861.0 11096.5 10879.4 10936.9 10943.5 11010.1 11017.6 2236.6 2285.7 2286.4 2290.9 2294.0 2195.5 2229.6 2244.3 2245.2 2248.8 2254.3 2261.4 2266.8 2268.7 2269.2 2210.9 2226.0 2235.0 2236.5 2245.3 2261.6 2320.8 2325.8 2327.1 2329.3 10888.0 10909.9 10958.0 10966.6 10970.7 4714.2 5584.1 5824.3 5828.7 5861.4 2774.3 3147.4 3249.3 3284.6 3462.9 1078.1 1095.9 1116.8 1139.7 1145.5 12405.0 13561.0 13686.9 15049.1 15060.8 4380.9 4456.9 4635.8 4638.5 4644.4 11411.3 11417.2 11544.1 11601.7 11611.4 5591.9 5635.6 5650.8 5653.0 5691.9 5018.3 5093.4 5173.9 5198.3 5211.6 3810.3 3839.4 3892.0 3950.4 3952.9 862.4 1028.4 1036.8 1047.8 1052.5 706.8 927.3 934.8 938.9 944.7 5715.9 6260.6 6482.2 6753.5 6772.9 768.7 906.8 935.8 942.5 945.6 3885.9 3886.0 4194.8 4203.0 4204.8 12396.6 12715.6 13051.1 13497.3 13515.9 13283.8 13301.6 13442.6 13480.1 13606.3 9914.0 9978.1 10061.3 10093.4 10104.7 6315.3 6421.5 6475.3 6526.2 6590.1 11836.3 11856.9 11893.1 11980.6 12043.6 10617.2 12098.9 12297.7 12310.4 12442.7 8209.7 8373.5 8405.9 8424.9 8461.3 8550.3 9919.4 10139.9 10248.8 10297.5 8819.5 8963.3 9002.3 9048.2 9195.7 7450.6 8748.1 8797.8 8825.7 8974.9 5990.5 7750.0 7762.5 7898.6 7940.1 5668.8 5810.2 5825.2 5920.2 5920.8 5935.5 5980.4 6013.1 6024.4 6038.7 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5260.79 Median:5682.18 stddev:1454.54 (25.6%) stddev-sans-first:1411.87 05:08:14 INFO - PID 1638 | Values: 386.6 387.1 388.9 391.7 397.3 4693.7 5131.4 5576.9 5691.9 6118.1 5741.0 5861.3 5874.6 5878.4 5890.2 4015.1 4719.6 4850.3 4885.7 4888.3 4060.8 4840.9 4870.2 4875.5 4910.6 4873.9 4885.2 4902.0 4907.9 4910.1 4622.8 4656.8 4806.8 4841.2 4845.9 3776.2 4356.5 4417.4 4439.9 4451.3 4769.4 5077.7 5623.8 5723.9 5769.4 4391.6 4710.8 4996.2 5681.1 5706.3 5272.3 5466.9 6287.8 6321.2 6358.7 3909.6 4403.7 4814.9 5032.2 5056.3 5357.4 6164.9 6383.1 6658.4 6780.4 4151.4 5201.6 5216.9 5225.3 5247.5 4489.8 5243.1 5300.8 5305.6 5319.5 4665.4 4959.7 5721.1 5725.0 5955.3 6341.6 6395.4 6429.7 6438.4 6438.5 1903.9 2013.9 2033.7 2035.3 2036.6 1461.9 1570.1 1590.9 1625.8 1661.7 5634.7 5647.5 5980.6 6006.8 6020.5 6456.6 6475.3 6534.8 6540.7 6571.9 4947.9 5215.0 5765.6 5819.5 5902.9 2922.5 3391.3 3417.8 3442.6 3517.5 5250.9 5627.8 6522.7 6547.1 6739.1 5216.0 5884.0 6591.3 6639.0 6665.3 6370.4 6472.0 6607.0 6725.6 6749.1 5897.9 6008.5 6417.7 6496.2 6522.2 5268.0 6232.5 6366.8 6433.8 6454.5 6341.6 6830.6 6838.1 6866.5 6873.4 4971.2 5413.6 5683.2 5686.6 5695.9 5147.0 6377.8 6543.4 6546.9 6633.5 5271.0 5542.9 6362.3 6385.2 6411.6 6087.9 6145.4 6336.9 6403.2 6428.6 5209.8 6227.9 6241.7 6248.6 6277.7 5039.0 5443.2 6096.9 6422.6 6422.9 6156.6 6215.7 6232.3 6240.7 6281.1 05:08:14 INFO - PID 1638 | 05:08:14 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:18871.76 Median:4145.87 stddev:25692.30 (619.7%) stddev-sans-first:25727.97 05:08:14 INFO - PID 1638 | Values: 655.8 716.9 757.8 818.0 828.3 14078.5 14554.6 14844.8 14904.8 15924.2 14483.8 14608.4 14715.7 14727.9 15080.5 914.1 941.8 951.7 953.1 953.5 670.2 670.4 923.4 952.1 956.8 993.0 994.5 998.1 1000.6 1000.8 992.5 994.6 994.9 995.0 996.1 738.3 739.5 739.8 740.3 740.3 15575.7 15856.0 16019.6 16127.0 16457.1 11435.6 13237.3 13702.6 14302.4 14319.0 11259.4 11518.7 11558.6 11565.1 12031.8 3613.5 3657.5 3667.9 3727.9 3749.4 87398.0 91857.5 92020.0 92557.1 108664.0 11383.3 11512.3 11675.0 11959.2 12082.5 23928.5 24019.8 24566.0 24586.9 24626.0 7727.9 7801.6 7867.8 7985.7 8002.1 4142.2 4143.8 4147.9 4183.1 4186.1 3470.2 3505.5 3516.2 3541.4 3554.0 639.6 640.2 647.4 654.9 656.4 643.8 648.6 688.6 702.3 717.3 9823.3 9929.5 10158.5 10375.2 10541.1 476.8 520.3 570.9 574.7 594.4 4486.4 4891.7 4905.8 4973.0 4987.3 77000.8 88449.0 88625.0 92568.0 96743.5 64764.7 70559.0 72348.0 81485.6 84873.6 70182.0 70424.0 73134.3 74068.5 74369.0 10820.2 10873.4 10967.6 11004.9 11096.1 39697.2 43437.6 46640.8 49260.0 49376.8 39298.0 57842.7 62764.7 63066.7 63223.4 12949.7 13449.0 13615.7 13711.7 13762.4 10993.8 12507.1 12619.5 14123.7 15139.9 11665.8 13659.5 13825.2 13920.1 14130.4 11322.5 12928.3 13651.8 13658.9 14584.3 11968.4 13040.3 13261.0 13697.6 13917.1 10767.8 11034.5 11198.8 11217.3 11846.6 7750.6 9373.4 9666.3 9748.4 10556.8 05:08:14 INFO - PID 1638 | -------- Summary: end -------- 05:08:14 INFO - PID 1638 | 05:08:15 INFO - TEST-INFO | 1638: exit 0 05:08:15 INFO - TEST-OK | dromaeo_css | took 1116210ms 05:08:15 INFO - TEST-START | kraken 05:08:15 INFO - operating with platform_type : mac_ 05:08:15 INFO - Initialising browser for kraken test... 05:08:15 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpH2qgrx/profile http://localhost:49246/getInfo.html) 05:08:18 INFO - PID 1647 | 2017-03-20 05:08:18.030 plugin-container[1648:9997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:08:18 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:18 INFO - PID 1647 | 2017-03-20 05:08:18.033 plugin-container[1648:9997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 05:08:18 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:18 INFO - PID 1647 | 2017-03-20 05:08:18.033 plugin-container[1648:9997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:08:18 INFO - PID 1647 | __metrics Screen width/height:1600/1200 05:08:18 INFO - PID 1647 | colorDepth:24 05:08:18 INFO - PID 1647 | Browser inner width/height: 1024/689 05:08:18 INFO - PID 1647 | __metrics 05:08:18 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 05:08:19 INFO - TEST-INFO | 1647: exit 0 05:08:19 INFO - Browser initialized. 05:08:19 INFO - Running cycle 1/1 for kraken test... 05:08:19 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpH2qgrx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:21 INFO - PID 1649 | 2017-03-20 05:08:21.564 plugin-container[1650:10122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:08:21 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:21 INFO - PID 1649 | 2017-03-20 05:08:21.565 plugin-container[1650:10122] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:08:21 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:08:21 INFO - PID 1649 | 2017-03-20 05:08:21.565 plugin-container[1650:10122] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:08:31 INFO - PID 1649 | RSS: Main: 227827712 05:08:31 INFO - PID 1649 | 05:08:34 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 05:08:35 INFO - PID 1649 | RSS: Main: 241754112 05:08:35 INFO - PID 1649 | 05:08:37 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 05:08:38 INFO - PID 1649 | RSS: Main: 249942016 05:08:38 INFO - PID 1649 | 05:08:41 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 05:08:42 INFO - PID 1649 | RSS: Main: 252477440 05:08:42 INFO - PID 1649 | 05:08:44 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 05:08:44 INFO - PID 1649 | RSS: Main: 254799872 05:08:44 INFO - PID 1649 | 05:08:47 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:08:47 INFO - PID 1649 | RSS: Main: 255504384 05:08:47 INFO - PID 1649 | 05:08:50 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 05:08:51 INFO - PID 1649 | RSS: Main: 260841472 05:08:51 INFO - PID 1649 | 05:08:54 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:55 INFO - PID 1649 | RSS: Main: 258846720 05:08:55 INFO - PID 1649 | 05:08:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 05:08:59 INFO - PID 1649 | RSS: Main: 260108288 05:08:59 INFO - PID 1649 | 05:09:01 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:09:01 INFO - PID 1649 | RSS: Main: 260083712 05:09:01 INFO - PID 1649 | 05:09:03 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:09:04 INFO - PID 1649 | RSS: Main: 260149248 05:09:04 INFO - PID 1649 | 05:09:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:09:07 INFO - PID 1649 | RSS: Main: 260210688 05:09:07 INFO - PID 1649 | 05:09:10 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:09:11 INFO - PID 1649 | RSS: Main: 260608000 05:09:11 INFO - PID 1649 | 05:09:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:09:14 INFO - PID 1649 | RSS: Main: 260284416 05:09:14 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 05:09:16 INFO - PID 1649 | RSS: Main: 263401472 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | __start_tp_report 05:09:16 INFO - PID 1649 | _x_x_mozilla_page_load 05:09:16 INFO - PID 1649 | _x_x_mozilla_page_load_details 05:09:16 INFO - PID 1649 | |i|pagename|runs| 05:09:16 INFO - PID 1649 | |0;ai-astar;109;121;120;132;186;129;120;121;120;120 05:09:16 INFO - PID 1649 | |1;audio-beat-detection;139;112;115;165;114;133;148;122;136;115 05:09:16 INFO - PID 1649 | |2;audio-dft;195;204;205;262;283;199;217;216;280;190 05:09:16 INFO - PID 1649 | |3;audio-fft;80;60;60;78;58;59;60;59;69;61 05:09:16 INFO - PID 1649 | |4;audio-oscillator;97;88;108;69;71;70;114;102;108;118 05:09:16 INFO - PID 1649 | |5;imaging-gaussian-blur;139;88;90;88;106;77;125;130;125;88 05:09:16 INFO - PID 1649 | |6;imaging-darkroom;96;147;155;95;146;91;153;136;96;95 05:09:16 INFO - PID 1649 | |7;imaging-desaturate;141;90;91;93;109;89;95;144;89;92 05:09:16 INFO - PID 1649 | |8;json-parse-financial;67;68;67;66;80;106;109;108;72;92 05:09:16 INFO - PID 1649 | |9;json-stringify-tinderbox;69;77;85;93;96;60;60;69;87;87 05:09:16 INFO - PID 1649 | |10;stanford-crypto-aes;85;84;96;84;84;82;88;77;91;110 05:09:16 INFO - PID 1649 | |11;stanford-crypto-ccm;142;144;144;162;156;158;140;156;260;135 05:09:16 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;184;191;186;184;188;188;188;158;175;234 05:09:16 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;69;71;69;67;71;71;69;74;96;72 05:09:16 INFO - PID 1649 | __end_tp_report 05:09:16 INFO - PID 1649 | __start_cc_report 05:09:16 INFO - PID 1649 | _x_x_mozilla_cycle_collect,994 05:09:16 INFO - PID 1649 | __end_cc_report 05:09:16 INFO - PID 1649 | __startTimestamp1490011756836__endTimestamp 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | ------- Summary: start ------- 05:09:16 INFO - PID 1649 | Number of tests: 14 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:127.80 Median:121.00 stddev:21.33 (17.6%) stddev-sans-first:21.51 05:09:16 INFO - PID 1649 | Values: 109.0 121.0 120.0 132.0 186.0 129.0 120.0 121.0 120.0 120.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:134.50 stddev:17.54 (13.0%) stddev-sans-first:18.29 05:09:16 INFO - PID 1649 | Values: 139.0 112.0 115.0 165.0 114.0 133.0 148.0 122.0 136.0 115.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:225.10 Median:216.50 stddev:35.82 (16.5%) stddev-sans-first:36.29 05:09:16 INFO - PID 1649 | Values: 195.0 204.0 205.0 262.0 283.0 199.0 217.0 216.0 280.0 190.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:64.40 Median:60.50 stddev:8.29 (13.7%) stddev-sans-first:6.60 05:09:16 INFO - PID 1649 | Values: 80.0 60.0 60.0 78.0 58.0 59.0 60.0 59.0 69.0 61.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:69.50 stddev:18.87 (27.2%) stddev-sans-first:19.99 05:09:16 INFO - PID 1649 | Values: 97.0 88.0 108.0 69.0 71.0 70.0 114.0 102.0 108.0 118.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:105.60 Median:82.50 stddev:22.25 (27.0%) stddev-sans-first:20.05 05:09:16 INFO - PID 1649 | Values: 139.0 88.0 90.0 88.0 106.0 77.0 125.0 130.0 125.0 88.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:121.00 Median:93.00 stddev:28.30 (30.4%) stddev-sans-first:28.53 05:09:16 INFO - PID 1649 | Values: 96.0 147.0 155.0 95.0 146.0 91.0 153.0 136.0 96.0 95.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:90.50 stddev:21.47 (23.7%) stddev-sans-first:17.93 05:09:16 INFO - PID 1649 | Values: 141.0 90.0 91.0 93.0 109.0 89.0 95.0 144.0 89.0 92.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:83.50 Median:67.50 stddev:18.45 (27.3%) stddev-sans-first:18.58 05:09:16 INFO - PID 1649 | Values: 67.0 68.0 67.0 66.0 80.0 106.0 109.0 108.0 72.0 92.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:86.00 stddev:13.21 (15.4%) stddev-sans-first:13.57 05:09:16 INFO - PID 1649 | Values: 69.0 77.0 85.0 93.0 96.0 60.0 60.0 69.0 87.0 87.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:84.50 stddev:9.26 (11.0%) stddev-sans-first:9.75 05:09:16 INFO - PID 1649 | Values: 85.0 84.0 96.0 84.0 84.0 82.0 88.0 77.0 91.0 110.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:159.70 Median:156.00 stddev:36.36 (23.3%) stddev-sans-first:38.00 05:09:16 INFO - PID 1649 | Values: 142.0 144.0 144.0 162.0 156.0 158.0 140.0 156.0 260.0 135.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:188.00 stddev:18.94 (10.1%) stddev-sans-first:20.04 05:09:16 INFO - PID 1649 | Values: 184.0 191.0 186.0 184.0 188.0 188.0 188.0 158.0 175.0 234.0 05:09:16 INFO - PID 1649 | 05:09:16 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:71.00 stddev:8.35 (11.8%) stddev-sans-first:8.73 05:09:16 INFO - PID 1649 | Values: 69.0 71.0 69.0 67.0 71.0 71.0 69.0 74.0 96.0 72.0 05:09:16 INFO - PID 1649 | -------- Summary: end -------- 05:09:16 INFO - PID 1649 | 05:09:17 INFO - TEST-INFO | 1649: exit 0 05:09:17 INFO - TEST-OK | kraken | took 62537ms 05:09:17 INFO - SUITE-END | took 1178s 05:09:18 INFO - Completed test suite (00:19:39) 05:09:18 INFO - javascript benchmark 05:09:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7812.815118399615, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2977.6426828780536, 3017.894373696921, 3312.734981201504, 3502.054445299769, 3513.595936467307, 5600.0, 5969.761209551623, 5983.132445795357, 6009.609375390596, 6043.728032238549, 4529.501754806968, 4954.810845397105, 6020.909686354708, 6104.2674879014585, 6133.0, 5269.2886460327745, 5324.068288049595, 5369.382521010075, 5392.595555333346, 5419.620626556123, 5409.648372855771, 5441.129419292914, 5459.727013649302, 5478.616496845222, 5481.75332110054, 5333.986542556921, 5356.9732151339385, 5379.031774280628, 5402.270693456372, 5466.808661696861, 4546.590806827401, 5258.447862974409, 5373.462653734634, 5394.000915586873, 5405.864853378657, 5278.996990571817, 5394.703291318976, 5399.974004939049, 5417.26866872971, 5425.403205647375, 4292.7424354538825, 5816.709164541756, 5846.239988801429, 5926.229590153295, 6019.307779605358, 6364.586301890408, 6399.8080057598345, 6419.582727122722, 6422.100905873182, 6422.7109780059545, 6500.05749166373, 6505.804825855232, 6509.414152726277, 6520.608763474207, 6527.184101987252, 5934.465898069108, 6112.419320164578, 6171.845703857394, 6184.350643182446, 6242.188515493048, 6424.13274207976, 6450.161479007699, 6460.870782584416, 6623.4038936495945, 6650.467962562983, 4958.330625365529, 5778.0, 6141.056449558148, 6281.842953926189, 6366.84082897922, 6414.358564143548, 6484.578502397423, 6495.090687303781, 6497.512686548528, 6497.805065848032, 6351.237851457855, 6354.618722876642, 6362.77730279438, 6367.235931688135, 6385.329540398284, 6208.627482351073, 6268.968655156695, 6285.811425657238, 6322.810315690444, 6341.778037768656, 5154.61340399464, 5163.715995620277, 5168.405633352213, 5179.22311653255, 5198.948010519922, 4598.425196850394, 4612.907741845144, 4626.028534007896, 4629.143608432383, 4643.257078867365, 6045.21412216409, 6102.883130681719, 6113.537117903944, 6139.29398119222, 6234.781782637677, 5263.0, 6423.614583125028, 6457.741690332334, 6470.45001174896, 6475.676216189172, 6119.418655227746, 6160.0, 6200.875982480326, 6230.06549017651, 6250.343713910065, 4585.633149348044, 5632.605717599728, 5694.829155125353, 5714.685692286964, 5767.596268261182, 6466.256380516207, 6588.802335929834, 6631.535792494479, 6640.634765087966, 6675.532712710064, 6457.4511166551365, 6469.676516174079, 6474.676266186766, 6483.124778155077, 6532.444742196966, 6403.359664033559, 6585.5719378240265, 6656.267810540739, 6696.799096027127, 6698.899516507159, 5322.0, 6174.969125154345, 6306.211723534559, 6401.711922963571, 6406.583572067801, 5101.387833460009, 5710.428957104256, 6134.4172303631085, 6432.292447830641, 6435.549511534147, 5508.807191748269, 6146.969265153645, 6162.815115546541, 6209.720562574605, 6264.561480696308, 6312.873742525216, 6351.523635727246, 6355.491560675043, 6371.267304259884, 6424.421802037839, 5020.447750747342, 6137.897974673567, 6152.98475751496, 6199.442050215503, 6215.875682486416, 6267.2479302484, 6274.529410294155, 6309.1167236587835, 6309.305976342691, 6312.779052733316, 6257.311695713559, 6264.185655864709, 6281.654509002049, 6286.088517165077, 6287.46556542699], "value": 5838.622979455494, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.8702615963728, 747.2863415438258, 787.2796391301958, 814.381070386506, 935.4948327902937, 29205.853970730142, 38326.808365958204, 38333.42499862497, 38350.616493835056, 38390.424143637865, 37673.05817354567, 38124.42813357795, 38158.23683526335, 38244.85265442034, 38245.80877095617, 7784.805379865506, 10855.565777368896, 11154.163437742161, 11155.995960363565, 11163.939425754541, 11042.061424778904, 11138.27601205923, 11156.995870371666, 11157.832632510519, 11200.495977681025, 11217.0, 11307.151963602762, 11315.603953861617, 11316.0, 11333.489992950295, 8739.781505462352, 9740.610375585004, 11176.608818691388, 11238.381888996142, 11303.604373846916, 9148.908510914873, 10911.83632245517, 10997.450127493674, 11000.724981875437, 11040.951109644571, 31690.524642130385, 38896.22207555833, 39081.60918390837, 39099.0, 39163.8041809792, 3785.6592906638534, 3808.5620153682403, 3813.218290250492, 3842.0, 3869.4582758413844, 28009.29976750577, 28159.296017599725, 28382.432351352858, 28759.424811503653, 29233.97681081173, 3167.9683203167906, 3172.6510083890753, 3198.712115909556, 3268.7548433867555, 3296.8516416761304, 226993.86503067543, 236706.0, 239788.0, 322583.0, 325101.0, 37593.060173495884, 45973.310400344024, 46234.0, 46259.76870115628, 46713.5328646716, 47503.762481187376, 47505.5249447508, 47578.145311281434, 47719.522804772205, 47826.891252068504, 14350.49773257931, 14474.0, 14478.782818257734, 14503.0, 14508.274586270643, 11873.168878178445, 11899.226550274205, 11911.987481064207, 11916.52333906634, 11957.402129893471, 13615.910727141805, 13639.249841258628, 13709.657258568615, 13729.107608005448, 13823.308834558433, 5170.741462926914, 6089.756409743649, 6096.72564734588, 6121.387861213932, 6153.4154255345675, 1090.816464136405, 1095.687728997232, 1105.4362275239685, 1122.8708698499613, 1176.088531388181, 21720.0, 25442.74557254441, 25625.0, 25847.870760646078, 26019.0, 1029.994850025745, 1212.8241404996402, 1223.1560223445972, 1224.7672942140964, 1261.6152073617452, 10627.628033018807, 13084.08411411192, 13164.670883227996, 13279.26964016969, 13415.798763018562, 291827.0, 341057.0, 342282.0, 345115.0, 411500.0, 50083.74958125259, 50264.74867625711, 55849.720751396795, 55936.0, 55993.440065599636, 1257.6730050187018, 1275.8150068240054, 1411.4142630808099, 1457.8396376398587, 1466.1349803615917, 26928.32679182958, 27363.042293519633, 28507.28731781722, 28665.14004579866, 29007.54962251921, 52448.73775631098, 52515.47484525259, 53062.734686327865, 53090.0, 53208.467915320354, 436044.0, 436998.0, 437135.1008144099, 437356.0, 438231.0, 32592.674073259914, 33432.16419589432, 33546.161345966546, 33563.0, 34188.0, 40702.389464157466, 41268.96827579215, 41510.1697966045, 42175.57824421716, 43254.351184732885, 35268.47097293491, 35281.47077793886, 35425.46861797023, 36425.0, 36443.906682799556, 34873.47689784604, 35083.0, 36079.27841443209, 36093.0, 36098.0, 29283.121506354844, 30130.0, 34524.964251071506, 34663.960081197605, 35956.4606530897, 31073.8446307767, 32495.0, 32497.02508924736, 32621.0, 32755.0, 22109.025469269287, 24341.26976190717, 24380.6342904853, 24843.378915527257, 24940.50118997646], "value": 18633.85905823647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.8660641988144, 407.9877603671891, 413.79723935271716, 420.844287613583, 435.43828461284943, 20291.49271268219, 20351.898240508795, 21046.789532104693, 22483.0, 22781.54436911265, 21971.0, 22553.661695074545, 22811.657825132595, 22899.542009159806, 23039.654405183937, 6169.845753856167, 6721.126253587051, 6726.663666816664, 6727.730890764388, 6732.01405585351, 6314.968425157867, 6627.602343859384, 6652.201735791712, 6687.899681504757, 6709.295523969986, 6466.127072845153, 6650.401463868251, 6669.599824010575, 6694.497912656546, 6742.898856517156, 6697.832554186136, 6738.0, 6766.966165169168, 6780.762673306411, 6786.694598743068, 6340.714667839959, 6481.64350960697, 6554.5739526931075, 6576.1122248496795, 6576.704048317837, 27543.586846197322, 27716.673999120034, 28157.0, 28304.575431368547, 28732.281692957844, 2881.106856874376, 2932.325565120013, 2937.9265518362, 2940.426616809723, 2974.464596372652, 14427.78358324626, 15501.922490387477, 16486.258118384583, 16610.83389166105, 17784.55538611545, 1996.6006798640185, 2000.4898750818447, 2000.9599808003904, 2028.1684509351244, 2029.553498230387, 127451.36274318662, 132256.0, 157060.0, 171657.0, 172271.0, 22239.555208896054, 23273.650895236584, 23289.18487852917, 23503.294901152993, 23576.41058973505, 36335.091622709646, 36408.0, 36519.63480365162, 36577.81711091428, 36702.89891303265, 10432.530536125892, 10716.0, 10807.459627018618, 10822.971817677308, 10984.505697243643, 8657.350698697497, 8676.089010653917, 8691.869621955675, 8836.116388361112, 8841.955790221007, 9112.362134650512, 10661.566134315139, 11056.391898445663, 11102.389368584805, 11175.105991520659, 3935.9569714025806, 4386.956130438719, 4501.3248012797685, 4521.525239849834, 4536.750478723636, 947.303731757158, 982.8181786369545, 1005.3314545826943, 1006.6879267426974, 1021.6219998600566, 21536.907987482642, 21983.780162198495, 22150.33548993533, 22164.667529987062, 22219.888900555394, 935.265816334188, 992.6277645882794, 1022.3048327137469, 1023.8617786598862, 1038.7039693687263, 11230.494627741607, 11567.710807229718, 11577.073834093253, 11598.826017609743, 11678.182527223013, 652421.0, 668887.0, 674717.0, 676249.0, 685311.0, 151899.24050379678, 154750.0, 157457.2127139357, 159501.70713296696, 160369.19815400848, 1224.3082658298033, 1296.5915736542959, 1321.035643979881, 1321.5770953295046, 1322.4379638653534, 20557.0, 20576.485587859825, 20932.686009709563, 20974.58050838944, 20994.790052099284, 48273.75863120662, 58281.0, 62384.68807655932, 62804.685976569825, 64890.37774055686, 83812.58093709736, 84060.15939840523, 84719.72920406076, 85067.72398413905, 86030.2793944105, 11616.244944078608, 11636.36000020007, 11652.126090543074, 11887.405629718653, 11927.642170734893, 10882.401467919024, 12946.870531294566, 13004.8049279259, 13027.47890084386, 13127.540536081191, 9118.544072796467, 9374.859377109213, 10452.216083793897, 10487.842682359618, 10619.1701351984, 9637.566309516229, 10613.832478427212, 11095.27880687785, 11109.777804443704, 11429.314241145557, 9516.238700903772, 11441.599544015919, 11503.884961150281, 11612.245204061708, 11676.532938682698, 10174.898251017396, 11067.72330691739, 11762.353070580857, 11819.936205741524, 11892.405379731152, 11584.304941703525, 11586.652400428, 11799.056075513767, 11828.940855295668, 11854.288742675468], "value": 11750.996445518027, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.9656758581034, 1496.2144873941172, 1543.3055125193666, 1559.8440155984404, 1582.050769538277, 10406.42764647944, 10740.409277489734, 10836.67489975301, 10861.0, 11096.500657470413, 10879.401632910187, 10936.890631093687, 10943.507542160602, 11010.119190464764, 11017.559297628106, 2236.630955892276, 2285.737140228878, 2286.4283929017747, 2290.931272061841, 2293.9541209175804, 2195.4730864592543, 2229.643257078868, 2244.3491387497606, 2245.1805091141696, 2248.8313376496744, 2254.256095488485, 2261.378121016717, 2266.7506574276817, 2268.6710426988075, 2269.1944359752297, 2210.8784016879067, 2226.0, 2234.955300893981, 2236.474428509299, 2245.303955773707, 2261.6155253606926, 2320.7679232076825, 2325.837191396603, 2327.057541695616, 2329.2546385156757, 10888.0, 10909.945450272777, 10958.0, 10966.616168434066, 10970.725731856688, 4714.222153344694, 5584.134459158837, 5824.301083869963, 5828.737706803204, 5861.3552509223955, 2774.3064233941514, 3147.35479226758, 3249.3014001989686, 3284.6204594070546, 3462.8961131166106, 1078.1159449251618, 1095.8904109588977, 1116.7598966222301, 1139.6694958462035, 1145.513156908319, 12404.999999999909, 13561.0, 13686.931565342209, 15049.097054176675, 15060.774088388684, 4380.8685739427865, 4456.9108617828115, 4635.768211589407, 4638.512956139557, 4644.373009643722, 11411.258268212705, 11417.200795944203, 11544.076473882069, 11601.709957251136, 11611.419429028514, 5591.888162236733, 5635.633683810539, 5650.830475085754, 5653.038983372837, 5691.9146212806845, 5018.347614810052, 5093.414257360451, 5173.896522069537, 5198.2722418861385, 5211.557017653542, 3810.3141434541494, 3839.3856982882603, 3892.0464486200494, 3950.4469374287046, 3952.881413557598, 862.3618522293458, 1028.3521381529588, 1036.7822757221068, 1047.8480620310165, 1052.484282701486, 706.8480276740526, 927.2767241551601, 934.7990182110879, 938.9014153513783, 944.7071407863584, 5715.885402346503, 6260.593061450991, 6482.157319548429, 6753.527253092237, 6772.864542709118, 768.6809973860896, 906.759708677201, 935.8455854784021, 942.4675058591873, 945.6359301668907, 3885.8834234972996, 3885.9805700971315, 4194.832206711697, 4202.973858821643, 4204.789760511962, 12396.628101156979, 12715.55495557651, 13051.086423950423, 13497.32513374347, 13515.932420337835, 13283.800742988862, 13301.600951971457, 13442.596722098353, 13480.123791953492, 13606.31968401576, 9914.008599140172, 9978.052085051908, 10061.295709300279, 10093.444860532594, 10104.74738131538, 6315.273743519554, 6421.518386120966, 6475.316417731329, 6526.151600292122, 6590.110335104891, 11836.349000805038, 11856.940715296369, 11893.108016898594, 11980.64058078259, 12043.638690839289, 10617.203709721647, 12098.939505302416, 12297.692557686129, 12310.446029928495, 12442.688932776753, 8209.671613135646, 8373.49759014461, 8405.87391189145, 8424.915750842412, 8461.323094152576, 8550.315974722133, 9919.355241909252, 10139.949300253451, 10248.846267306146, 10297.485125743533, 8819.470831750115, 8963.282937364866, 9002.279817614444, 9048.230900373543, 9195.724128276162, 7450.627468626655, 8748.081451447506, 8797.824043518965, 8825.69110081144, 8974.86537701922, 5990.520758339409, 7750.031246094239, 7762.456628035983, 7898.644560994884, 7940.126586075412, 5668.801591944262, 5810.186573879576, 5825.155352473952, 5920.200772895776, 5920.763169473173, 5935.465808077121, 5980.415189974573, 6013.098035294741, 6024.367441418588, 6038.6678732670125], "value": 4957.315841297708, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.6442872557247, 387.0941995729991, 388.8833349995001, 391.7081774078312, 397.3384315115332, 4693.671442998992, 5131.435542090367, 5576.888462230758, 5691.943080569193, 6118.1434599156155, 5741.043362668229, 5861.325947516037, 5874.6181498202695, 5878.441548052928, 5890.234269544964, 4015.07653239755, 4719.6224302055925, 4850.296706977486, 4885.658003939726, 4888.291197776338, 4060.7969601519953, 4840.910795071099, 4870.245112007627, 4875.463698993108, 4910.558049775519, 4873.926891096637, 4885.2427873679635, 4901.995091006335, 4907.858970259037, 4910.060526092191, 4622.8382006629945, 4656.813727450898, 4806.783694733728, 4841.225403935388, 4845.878853028667, 3776.188119554309, 4356.542563030899, 4417.425734654506, 4439.933400998988, 4451.332300154971, 4769.35613692154, 5077.695338279715, 5623.803166889139, 5723.914141287884, 5769.394213607595, 4391.5827996340295, 4710.8351207707565, 4996.200607902718, 5681.147827825818, 5706.258186435737, 5272.340957380327, 5466.945330546723, 6287.811365659038, 6321.178246827929, 6358.682065896686, 3909.6090390960962, 4403.691741578091, 4814.927776083362, 5032.207125045598, 5056.30613744396, 5357.43746906577, 6164.907526387108, 6383.138276332682, 6658.434033107192, 6780.423663988567, 4151.356539736345, 5201.555269023553, 5216.947830521722, 5225.268462415265, 5247.527722504993, 4489.7979590918485, 5243.082460569385, 5300.787968481295, 5305.602079844027, 5319.494648008433, 4665.4168228971375, 4959.727215003138, 5721.113227449752, 5725.026745453283, 5955.255593050869, 6341.619502829845, 6395.4418232707285, 6429.7428102875365, 6438.3883531064475, 6438.549301548846, 1903.9333623323116, 2013.919443222284, 2033.7152798608206, 2035.2673037706709, 2036.562139140092, 1461.9035723207594, 1570.0971941133894, 1590.9124998124983, 1625.7642641817106, 1661.7008938390964, 5634.690092044977, 5647.463490968352, 5980.594560278331, 6006.819795406145, 6020.488258498077, 6456.644884531302, 6475.32009139043, 6534.771283005167, 6540.672966351759, 6571.868562628816, 4947.925781113287, 5214.983078299695, 5765.57845704805, 5819.53443724501, 5902.852428689317, 2922.5177845655653, 3391.2878295558207, 3417.829108544563, 3442.5524681791208, 3517.542719446507, 5250.897311564614, 5627.803026894039, 6522.673866306665, 6547.134066790942, 6739.123913891261, 5215.973920130375, 5883.970580147072, 6591.340865913467, 6639.037339585733, 6665.333466653296, 6370.362963703593, 6471.967640161769, 6607.0, 6725.630090345078, 6749.08887300218, 5897.882042359043, 6008.519318454601, 6417.711202995783, 6496.187976502937, 6522.184726909137, 5268.0, 6232.5325600581045, 6366.808995730135, 6433.806985790434, 6454.515911306765, 6341.587796793378, 6830.624315662686, 6838.111045564045, 6866.519343646097, 6873.381395674413, 4971.204607262821, 5413.648112872651, 5683.204351390726, 5686.573506987075, 5695.914561281667, 5146.970605878764, 6377.7767778127545, 6543.378379053982, 6546.887029204951, 6633.502487313374, 5271.023366237661, 5542.916856247079, 6362.331955144643, 6385.233771947396, 6411.6153030818305, 6087.878242435215, 6145.385461453998, 6336.9049464259, 6403.199600049994, 6428.55000149985, 5209.843704688866, 6227.906581401373, 6241.719122639583, 6248.562600617913, 6277.686115694289, 5039.0, 5443.237946687388, 6096.878062438637, 6422.614643121427, 6422.921444882627, 6156.5998210116195, 6215.663632319073, 6232.25212974446, 6240.687965601793, 6281.120643109877], "value": 4849.031323316096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.8196495963613, 716.9354758071772, 757.776455945496, 817.9591020448978, 828.2918105020208, 14078.451370349267, 14554.563363099123, 14844.77732834007, 14904.776428353583, 15924.203789810506, 14483.782743258833, 14608.415663373453, 14715.705685886276, 14727.852721472782, 15080.54758357251, 914.1270087066869, 941.7692665297012, 951.6764300137952, 953.1088432315772, 953.5232383808096, 670.2191946382459, 670.4301343857707, 923.4136323434634, 952.0622187145656, 956.8469044952809, 992.9553170107363, 994.462989985407, 998.0518507417981, 1000.6097621927454, 1000.774825664227, 992.5235886774352, 994.5574219472302, 994.9005099490065, 994.9801003979952, 996.1084829077971, 738.3428748413916, 739.5008369350667, 739.7743688175104, 740.2597402597403, 740.3336996702957, 15575.688486230325, 15856.0, 16019.599510012227, 16127.0, 16457.09485978271, 11435.59975400857, 13237.271950042743, 13702.588922332247, 14302.356393962296, 14319.036606745976, 11259.380734059623, 11518.712032199179, 11558.595449159238, 11565.132615053904, 12031.759364812655, 3613.5184127536154, 3657.4513822926506, 3667.9266414671556, 3727.869524566627, 3749.3813520769313, 87398.0, 91857.54071229669, 92020.0, 92557.07442925552, 108664.0, 11383.260088094248, 11512.30926144434, 11675.0, 11959.222650527689, 12082.456289466994, 23928.521429571658, 24019.759802402103, 24566.017359305784, 24586.877065614557, 24626.014959401422, 7727.922720772833, 7801.609919504002, 7867.803304917308, 7985.72049978248, 8002.149029205821, 4142.171565686815, 4143.81352839123, 4147.91704165915, 4183.1215444756335, 4186.079062606222, 3470.201853573692, 3505.544279243733, 3516.226430185355, 3541.38025845476, 3554.0, 639.6322114784018, 640.2285246278224, 647.3591144766647, 654.8853950558729, 656.409231691472, 643.8165122939939, 648.5687018132983, 688.6246995387526, 702.2836706559281, 717.2576383443215, 9823.312368134304, 9929.50352482373, 10158.492075396201, 10375.221858360654, 10541.0513053824, 476.7854465490543, 520.3157847430616, 570.8572856785804, 574.7269301328975, 594.4055944055945, 4486.371907932892, 4891.682040667345, 4905.828296009622, 4972.975135124374, 4987.326710894055, 77000.84498732524, 88449.0, 88625.0, 92568.0, 96743.51628241813, 64764.73323433657, 70559.0, 72348.0, 81485.59257203677, 84873.57563212268, 70182.0, 70424.0, 73134.2686573138, 74068.5186296271, 74369.0, 10820.206301965489, 10873.401962892116, 10967.616133435291, 11004.944975275072, 11096.112311014938, 39697.206055878145, 43437.565624343355, 46640.833979149436, 49260.0, 49376.75311623419, 39298.0, 57842.7107864458, 62764.68617656882, 63066.738665227356, 63223.36776632359, 12949.741005180033, 13448.99132565042, 13615.727685446038, 13711.725765484434, 13762.380692868644, 10993.780124397308, 12507.061970352079, 12619.49522019109, 14123.717525649636, 15139.924300378427, 11665.80701475952, 13659.521916732867, 13825.239611821446, 13920.095193812369, 14130.434782608581, 11322.490487928228, 12928.288944108164, 13651.795223071464, 13658.931705341409, 14584.343704533108, 11968.401579921143, 13040.347982601023, 13261.0, 13697.589072327846, 13917.0953888001, 10767.838482422927, 11034.455394473947, 11198.776024479628, 11217.326960382403, 11846.644600661994, 7750.571432839888, 9373.390729602554, 9666.275029372684, 9748.432349369054, 10556.84164737545], "value": 7399.7798184622025, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1641.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 120.0, 132.0, 186.0, 129.0, 120.0, 121.0, 120.0, 120.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 115.0, 165.0, 114.0, 133.0, 148.0, 122.0, 136.0, 115.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 205.0, 262.0, 283.0, 199.0, 217.0, 216.0, 280.0, 190.0], "value": 225.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 78.0, 58.0, 59.0, 60.0, 59.0, 69.0, 61.0], "value": 64.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 88.0, 108.0, 69.0, 71.0, 70.0, 114.0, 102.0, 108.0, 118.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 88.0, 90.0, 88.0, 106.0, 77.0, 125.0, 130.0, 125.0, 88.0], "value": 105.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 147.0, 155.0, 95.0, 146.0, 91.0, 153.0, 136.0, 96.0, 95.0], "value": 121.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 90.0, 91.0, 93.0, 109.0, 89.0, 95.0, 144.0, 89.0, 92.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 80.0, 106.0, 109.0, 108.0, 72.0, 92.0], "value": 83.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 85.0, 93.0, 96.0, 60.0, 60.0, 69.0, 87.0, 87.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 96.0, 84.0, 84.0, 82.0, 88.0, 77.0, 91.0, 110.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 144.0, 144.0, 162.0, 156.0, 158.0, 140.0, 156.0, 260.0, 135.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 191.0, 186.0, 184.0, 188.0, 188.0, 188.0, 158.0, 175.0, 234.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 67.0, 71.0, 71.0, 69.0, 74.0, 96.0, 72.0], "value": 72.9, "unit": "score"}]}]} 05:09:18 INFO - Return code: 0 05:09:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:09:18 ERROR - # TBPL SUCCESS # 05:09:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:18 INFO - 2017-03-20 05:09:18.190 screenresolution[1654:10511] starting screenresolution argv=screenresolution get 05:09:18 INFO - 2017-03-20 05:09:18.193 screenresolution[1654:10511] Display 0: 1600x1200x32@60 05:09:18 INFO - 2017-03-20 05:09:18.199 screenresolution[1655:10514] starting screenresolution argv=screenresolution list 05:09:18 INFO - Available Modes on Display 0 05:09:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:18 INFO - Intel Iris: 05:09:18 INFO - Chipset Model: Intel Iris 05:09:18 INFO - Type: GPU 05:09:18 INFO - Bus: Built-In 05:09:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:18 INFO - Vendor: Intel (0x8086) 05:09:18 INFO - Device ID: 0x0a2e 05:09:18 INFO - Revision ID: 0x0009 05:09:18 INFO - Displays: 05:09:18 INFO - Display: 05:09:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:18 INFO - Display Serial Number: 1600x1200 60 05:09:18 INFO - Main Display: Yes 05:09:18 INFO - Mirror: Off 05:09:18 INFO - Online: Yes 05:09:18 INFO - Rotation: Supported 05:09:18 INFO - Return code: 0 05:09:18 INFO - Running post-action listener: _resource_record_post_action 05:09:18 INFO - [mozharness: 2017-03-20 12:09:18.434163Z] Finished run-tests step (success) 05:09:18 INFO - Running post-run listener: _resource_record_post_run 05:09:18 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38590976; Write bytes: 516195328; Read time: 244; Write time: 1662 05:09:18 INFO - TinderboxPrint: CPU usage
54.2% 05:09:18 INFO - TinderboxPrint: I/O read bytes / time
38,590,976 / 244 05:09:18 INFO - TinderboxPrint: I/O write bytes / time
516,195,328 / 1,662 05:09:18 INFO - TinderboxPrint: CPU idle
2,199.5 (45.8%) 05:09:18 INFO - TinderboxPrint: CPU system
967.4 (20.1%) 05:09:18 INFO - TinderboxPrint: CPU user
1,634.7 (34.0%) 05:09:18 INFO - TinderboxPrint: Swap in / out
248,819,712 / 0 05:09:18 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 207981056; Write bytes: 205279232; Read time: 15708; Write time: 498 05:09:18 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32440832; Write bytes: 300966912; Read time: 189; Write time: 1129 05:09:18 INFO - Running post-run listener: _upload_blobber_files 05:09:18 INFO - Blob upload gear active. 05:09:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:09:18 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:09:18 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:09:18 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:09:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:09:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:09:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:20 INFO - (blobuploader) - INFO - Done attempting. 05:09:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:09:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:09:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:21 INFO - (blobuploader) - INFO - Done attempting. 05:09:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:09:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:09:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:23 INFO - (blobuploader) - INFO - Done attempting. 05:09:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:09:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:09:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:25 INFO - (blobuploader) - INFO - Done attempting. 05:09:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:09:25 INFO - Return code: 0 05:09:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:09:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:09:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c15da9aadc9e2474099bd9720e1f82343f53242389baa068059486ece23fb903e40e6ba5a2dda9ba3b156da601c3de5aa85aad6372b269a3d2eea4dad5ac6260", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ef3e1b881d46a3dcdd8663bed95facc951fa46244d9a22d79e295f8df4a7bd367b0eaf79883c89aaad8bacfa67fdbf7b67d645d18cb70e991a8b8c23d4fe6bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa96db01e5b8cd3839ba7f7665e08f6057740889add3a00e98cfd21ef7aecca4285a14d4816eea869f25e8b7af9e59dcf3902b03fc4e7121e21ec50370748f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7166e18eb5d77509e0ac5f7c3dceda8ce432e95f5378a92f4157d6f2ced488f2d9f239b1d12d6764ea80e8a981276cb09450e2af993e01f6da21c2d6b10d23b0"} 05:09:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:09:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:09:25 INFO - Contents: 05:09:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c15da9aadc9e2474099bd9720e1f82343f53242389baa068059486ece23fb903e40e6ba5a2dda9ba3b156da601c3de5aa85aad6372b269a3d2eea4dad5ac6260", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ef3e1b881d46a3dcdd8663bed95facc951fa46244d9a22d79e295f8df4a7bd367b0eaf79883c89aaad8bacfa67fdbf7b67d645d18cb70e991a8b8c23d4fe6bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa96db01e5b8cd3839ba7f7665e08f6057740889add3a00e98cfd21ef7aecca4285a14d4816eea869f25e8b7af9e59dcf3902b03fc4e7121e21ec50370748f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7166e18eb5d77509e0ac5f7c3dceda8ce432e95f5378a92f4157d6f2ced488f2d9f239b1d12d6764ea80e8a981276cb09450e2af993e01f6da21c2d6b10d23b0"} 05:09:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:09:25 INFO - Copying logs to upload dir... 05:09:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:09:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.123193 ========= master_lag: 0.29 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-20 05:09:25.548411) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 05:09:25.551649) ========= 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.NeUtApGlgC/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.tqk7usVTCp/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c15da9aadc9e2474099bd9720e1f82343f53242389baa068059486ece23fb903e40e6ba5a2dda9ba3b156da601c3de5aa85aad6372b269a3d2eea4dad5ac6260", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ef3e1b881d46a3dcdd8663bed95facc951fa46244d9a22d79e295f8df4a7bd367b0eaf79883c89aaad8bacfa67fdbf7b67d645d18cb70e991a8b8c23d4fe6bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa96db01e5b8cd3839ba7f7665e08f6057740889add3a00e98cfd21ef7aecca4285a14d4816eea869f25e8b7af9e59dcf3902b03fc4e7121e21ec50370748f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7166e18eb5d77509e0ac5f7c3dceda8ce432e95f5378a92f4157d6f2ced488f2d9f239b1d12d6764ea80e8a981276cb09450e2af993e01f6da21c2d6b10d23b0"} 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.010909 build_url: 'https://queue.taskcluster.net/v1/task/MzNMbU32TgSANcdHTRqksQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c15da9aadc9e2474099bd9720e1f82343f53242389baa068059486ece23fb903e40e6ba5a2dda9ba3b156da601c3de5aa85aad6372b269a3d2eea4dad5ac6260", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ef3e1b881d46a3dcdd8663bed95facc951fa46244d9a22d79e295f8df4a7bd367b0eaf79883c89aaad8bacfa67fdbf7b67d645d18cb70e991a8b8c23d4fe6bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa96db01e5b8cd3839ba7f7665e08f6057740889add3a00e98cfd21ef7aecca4285a14d4816eea869f25e8b7af9e59dcf3902b03fc4e7121e21ec50370748f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7166e18eb5d77509e0ac5f7c3dceda8ce432e95f5378a92f4157d6f2ced488f2d9f239b1d12d6764ea80e8a981276cb09450e2af993e01f6da21c2d6b10d23b0"}' ========= master_lag: 0.97 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 05:09:26.528447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:09:26.528770) ========= 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.NeUtApGlgC/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.tqk7usVTCp/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.005183 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:09:26.556523) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 05:09:26.556851) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 05:09:26.557156) ========= ========= Total master_lag: 2.13 =========