builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0075 starttime: 1489065833.92 results: success (0) buildid: 20170309030823 builduid: db94d04f173a46998f61cb1d99af4a3e revision: 31f757e837abad20e44fff4700b1d3917ef5cd11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:53.923048) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:53.923494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:53.923783) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:53.951045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:53.951444) ========= 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.nXlFSKJmnI/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.oWchrrwtWN/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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-09 05:23:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.78M=0.001s 2017-03-09 05:23:54 (7.78 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087354 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:54.062187) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:54.062491) ========= 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.nXlFSKJmnI/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.oWchrrwtWN/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.020900 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:54.113552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 05:23:54.113935) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 31f757e837abad20e44fff4700b1d3917ef5cd11 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 31f757e837abad20e44fff4700b1d3917ef5cd11 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nXlFSKJmnI/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.oWchrrwtWN/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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-09 05:23:54,204 truncating revision to first 12 chars 2017-03-09 05:23:54,204 Setting DEBUG logging. 2017-03-09 05:23:54,204 attempt 1/10 2017-03-09 05:23:54,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/31f757e837ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-09 05:23:55,877 unpacking tar archive at: mozilla-beta-31f757e837ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.927462 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 05:23:56.068582) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:56.068968) ========= script_repo_revision: 31f757e837abad20e44fff4700b1d3917ef5cd11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:56.069398) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:56.069715) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 05:23:56.089532) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-09 05:23:56.089877) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nXlFSKJmnI/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.oWchrrwtWN/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:23:56 INFO - MultiFileLogger online at 20170309 05:23:56 in /builds/slave/test 05:23:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:23:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:56 INFO - {'append_to_log': False, 05:23:56 INFO - 'base_work_dir': '/builds/slave/test', 05:23:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:23:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:56 INFO - 'branch': 'Mozilla-Beta', 05:23:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:56 INFO - 'config_files': ('talos/mac_config.py',), 05:23:56 INFO - 'default_actions': ('clobber', 05:23:56 INFO - 'read-buildbot-config', 05:23:56 INFO - 'download-and-extract', 05:23:56 INFO - 'populate-webroot', 05:23:56 INFO - 'create-virtualenv', 05:23:56 INFO - 'install', 05:23:56 INFO - 'run-tests'), 05:23:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:56 INFO - 'download_minidump_stackwalk': True, 05:23:56 INFO - 'download_symbols': 'ondemand', 05:23:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:56 INFO - 'installer_path': 'installer.exe', 05:23:56 INFO - 'log_level': 'info', 05:23:56 INFO - 'log_name': 'talos', 05:23:56 INFO - 'log_to_console': True, 05:23:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:23:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:23:56 INFO - 'opt_config_files': (), 05:23:56 INFO - 'pip_index': False, 05:23:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:23:56 INFO - 'cmd': ('bash', 05:23:56 INFO - '-c', 05:23:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:23:56 INFO - 'enabled': True, 05:23:56 INFO - 'halt_on_failure': False, 05:23:56 INFO - 'name': 'check_screen_resolution'},), 05:23:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:23:56 INFO - 'cmd': ('bash', 05:23:56 INFO - '-c', 05:23:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:23:56 INFO - 'enabled': True, 05:23:56 INFO - 'halt_on_failure': False, 05:23:56 INFO - 'name': 'check_screen_resolution'},), 05:23:56 INFO - 'run_cmd_checks_enabled': True, 05:23:56 INFO - 'sps_profile': False, 05:23:56 INFO - 'sps_profile_interval': 0, 05:23:56 INFO - 'suite': 'dromaeojs-e10s', 05:23:56 INFO - 'system_bits': '32', 05:23:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:56 INFO - 'title': 't-yosemite-r7-0075', 05:23:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:56 INFO - 'use_talos_json': True, 05:23:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:56 INFO - 'work_dir': 'build'} 05:23:56 INFO - [mozharness: 2017-03-09 13:23:56.303888Z] Running clobber step. 05:23:56 INFO - Running pre-action listener: _resource_record_pre_action 05:23:56 INFO - Running main action method: clobber 05:23:56 INFO - rmtree: /builds/slave/test/build 05:23:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:57 INFO - Running post-action listener: _resource_record_post_action 05:23:57 INFO - [mozharness: 2017-03-09 13:23:57.842586Z] Finished clobber step (success) 05:23:57 INFO - [mozharness: 2017-03-09 13:23:57.842714Z] Running read-buildbot-config step. 05:23:57 INFO - Running pre-action listener: _resource_record_pre_action 05:23:57 INFO - Running main action method: read_buildbot_config 05:23:57 INFO - Using buildbot properties: 05:23:57 INFO - { 05:23:57 INFO - "project": "", 05:23:57 INFO - "product": "firefox", 05:23:57 INFO - "script_repo_revision": "production", 05:23:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:23:57 INFO - "repository": "", 05:23:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:23:57 INFO - "buildid": "20170309030823", 05:23:57 INFO - "pgo_build": "False", 05:23:57 INFO - "basedir": "/builds/slave/test", 05:23:57 INFO - "buildnumber": 7, 05:23:57 INFO - "slavename": "t-yosemite-r7-0075", 05:23:57 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:23:57 INFO - "platform": "yosemite_r7", 05:23:57 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:23:57 INFO - "branch": "mozilla-beta", 05:23:57 INFO - "repo_path": "releases/mozilla-beta", 05:23:57 INFO - "revision": "31f757e837abad20e44fff4700b1d3917ef5cd11", 05:23:57 INFO - "stage_platform": "macosx64", 05:23:57 INFO - "builduid": "db94d04f173a46998f61cb1d99af4a3e", 05:23:57 INFO - "slavebuilddir": "test" 05:23:57 INFO - } 05:23:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 05:23:57 INFO - Running post-action listener: _resource_record_post_action 05:23:57 INFO - [mozharness: 2017-03-09 13:23:57.845940Z] Finished read-buildbot-config step (success) 05:23:57 INFO - [mozharness: 2017-03-09 13:23:57.846049Z] Running download-and-extract step. 05:23:57 INFO - Running pre-action listener: _resource_record_pre_action 05:23:57 INFO - Running main action method: download_and_extract 05:23:57 INFO - mkdir: /builds/slave/test/build/tests 05:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:57 INFO - trying https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 05:23:57 INFO - Downloading https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:23:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 05:23:58 INFO - Downloaded 1165 bytes. 05:23:58 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:23:58 INFO - Using the following test package requirements: 05:23:58 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:23:58 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 05:23:58 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 05:23:58 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:23:58 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 05:23:58 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:23:58 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 05:23:58 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 05:23:58 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 05:23:58 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:23:58 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 05:23:58 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 05:23:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:23:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:23:58 INFO - Fetch https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:23:59 INFO - Expected file size: 16103545 05:23:59 INFO - Obtained file size: 16103545 05:24:06 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:24:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:24:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:24:06 INFO - Fetch https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:24:07 INFO - Expected file size: 16103545 05:24:07 INFO - Obtained file size: 16103545 05:24:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 05:24:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 05:24:13 INFO - Fetch https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 05:24:15 INFO - Expected file size: 14403650 05:24:15 INFO - Obtained file size: 14403650 05:24:16 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')]} 05:24:16 INFO - trying https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:24:16 INFO - Downloading https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 05:24:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 05:24:17 INFO - Downloaded 58690728 bytes. 05:24:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:24:17 INFO - mkdir: /builds/slave/test/properties 05:24:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:24:17 INFO - Writing to file /builds/slave/test/properties/build_url 05:24:17 INFO - Contents: 05:24:17 INFO - build_url:https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:24:18 INFO - Running post-action listener: _resource_record_post_action 05:24:18 INFO - Running post-action listener: set_extra_try_arguments 05:24:18 INFO - [mozharness: 2017-03-09 13:24:18.539428Z] Finished download-and-extract step (success) 05:24:18 INFO - [mozharness: 2017-03-09 13:24:18.539551Z] Running populate-webroot step. 05:24:18 INFO - Running pre-action listener: _resource_record_pre_action 05:24:18 INFO - Running main action method: populate_webroot 05:24:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:24:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:24:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:24:18 INFO - '--tppagecycles', 05:24:18 INFO - '7'], 05:24:18 INFO - 'tests': ['tsvgm']}}, 05:24:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:24:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:24:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:24:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:24:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['damp', 'tps']}, 05:24:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'tests': ['damp', 'tps']}, 05:24:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['dromaeo_dom']}, 05:24:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:24:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['basic_compositor_video']}, 05:24:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:24:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['a11yr', 05:24:18 INFO - 'ts_paint', 05:24:18 INFO - 'tpaint', 05:24:18 INFO - 'sessionrestore', 05:24:18 INFO - 'sessionrestore_no_auto_restore', 05:24:18 INFO - 'tabpaint']}, 05:24:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:24:18 INFO - 'ts_paint', 05:24:18 INFO - 'tpaint', 05:24:18 INFO - 'sessionrestore', 05:24:18 INFO - 'sessionrestore_no_auto_restore', 05:24:18 INFO - 'tabpaint']}, 05:24:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:24:18 INFO - 'ts_paint', 05:24:18 INFO - 'tpaint', 05:24:18 INFO - 'sessionrestore', 05:24:18 INFO - 'sessionrestore_no_auto_restore', 05:24:18 INFO - 'tabpaint']}, 05:24:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:24:18 INFO - 'ts_paint', 05:24:18 INFO - 'tpaint', 05:24:18 INFO - 'sessionrestore', 05:24:18 INFO - 'sessionrestore_no_auto_restore', 05:24:18 INFO - 'tabpaint']}, 05:24:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['a11yr', 05:24:18 INFO - 'ts_paint', 05:24:18 INFO - 'tpaint', 05:24:18 INFO - 'sessionrestore', 05:24:18 INFO - 'sessionrestore_no_auto_restore', 05:24:18 INFO - 'tabpaint']}, 05:24:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['a11yr', 05:24:18 INFO - 'ts_paint', 05:24:18 INFO - 'tpaint', 05:24:18 INFO - 'sessionrestore', 05:24:18 INFO - 'sessionrestore_no_auto_restore', 05:24:18 INFO - 'tabpaint']}, 05:24:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['tsvgx', 05:24:18 INFO - 'tsvgr_opacity', 05:24:18 INFO - 'tart', 05:24:18 INFO - 'tscrollx', 05:24:18 INFO - 'cart', 05:24:18 INFO - 'tsvg_static']}, 05:24:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:24:18 INFO - 'tsvgr_opacity', 05:24:18 INFO - 'tart', 05:24:18 INFO - 'tscrollx', 05:24:18 INFO - 'cart', 05:24:18 INFO - 'tsvg_static']}, 05:24:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'talos_options': ['--disable-e10s'], 05:24:18 INFO - 'tests': ['tp5o']}, 05:24:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:24:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'talos_options': ['--disable-e10s', 05:24:18 INFO - '--xperf_path', 05:24:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:18 INFO - 'tests': ['tp5n']}, 05:24:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:24:18 INFO - 'talos_options': ['--xperf_path', 05:24:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:18 INFO - 'tests': ['tp5n']}}, 05:24:18 INFO - 'talos.zip': {'path': '', 05:24:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:24:18 INFO - Running post-action listener: _resource_record_post_action 05:24:18 INFO - [mozharness: 2017-03-09 13:24:18.557929Z] Finished populate-webroot step (success) 05:24:18 INFO - [mozharness: 2017-03-09 13:24:18.558058Z] Running create-virtualenv step. 05:24:18 INFO - Running pre-action listener: _resource_record_pre_action 05:24:18 INFO - Running main action method: create_virtualenv 05:24:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:24:18 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 05:24:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:24:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:24:18 INFO - Using real prefix '/tools/python27' 05:24:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:24:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:24:22 INFO - Installing pip.................done. 05:24:22 INFO - Return code: 0 05:24:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:24:22 INFO - Reading from file tmpfile_stdout 05:24:22 INFO - Output received: 05:24:22 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) 05:24:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:22 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:22 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 05:24:22 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 05:24:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:22 INFO - 'HOME': '/Users/cltbld', 05:24:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:22 INFO - 'LOGNAME': 'cltbld', 05:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:22 INFO - 'MOZ_NO_REMOTE': '1', 05:24:22 INFO - 'NO_EM_RESTART': '1', 05:24:22 INFO - 'PAGER': '/bin/cat', 05:24:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:22 INFO - 'PWD': '/builds/slave/test', 05:24:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:22 INFO - 'SHELL': '/bin/bash', 05:24:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:22 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:22 INFO - 'USER': 'cltbld', 05:24:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:22 INFO - 'XPC_FLAGS': '0x0', 05:24:22 INFO - 'XPC_SERVICE_NAME': '0', 05:24:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:22 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 05:24:22 INFO - Cleaning up... 05:24:22 INFO - Return code: 0 05:24:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:22 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:22 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 05:24:22 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 05:24:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:22 INFO - 'HOME': '/Users/cltbld', 05:24:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:22 INFO - 'LOGNAME': 'cltbld', 05:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:22 INFO - 'MOZ_NO_REMOTE': '1', 05:24:22 INFO - 'NO_EM_RESTART': '1', 05:24:22 INFO - 'PAGER': '/bin/cat', 05:24:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:22 INFO - 'PWD': '/builds/slave/test', 05:24:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:22 INFO - 'SHELL': '/bin/bash', 05:24:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:22 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:22 INFO - 'USER': 'cltbld', 05:24:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:22 INFO - 'XPC_FLAGS': '0x0', 05:24:22 INFO - 'XPC_SERVICE_NAME': '0', 05:24:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:23 INFO - Downloading/unpacking psutil>=3.1.1 05:24:23 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 05:24:23 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 05:24:23 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 05:24:23 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 05:24:23 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 05:24:23 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 05:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:24:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:26 INFO - Installing collected packages: psutil 05:24:26 INFO - Running setup.py install for psutil 05:24:26 INFO - building 'psutil._psutil_osx' extension 05:24:26 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 05:24:26 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 05:24:26 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 05:24:27 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 05:24:27 INFO - building 'psutil._psutil_posix' extension 05:24:27 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 05:24:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:24:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:24:27 INFO - ^ 05:24:27 INFO - 1 warning generated. 05:24:27 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 05:24:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:27 INFO - Successfully installed psutil 05:24:27 INFO - Cleaning up... 05:24:27 INFO - Return code: 0 05:24:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:24:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:27 INFO - 'HOME': '/Users/cltbld', 05:24:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:27 INFO - 'LOGNAME': 'cltbld', 05:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:27 INFO - 'MOZ_NO_REMOTE': '1', 05:24:27 INFO - 'NO_EM_RESTART': '1', 05:24:27 INFO - 'PAGER': '/bin/cat', 05:24:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:27 INFO - 'PWD': '/builds/slave/test', 05:24:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:27 INFO - 'SHELL': '/bin/bash', 05:24:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:27 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:27 INFO - 'USER': 'cltbld', 05:24:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:27 INFO - 'XPC_FLAGS': '0x0', 05:24:27 INFO - 'XPC_SERVICE_NAME': '0', 05:24:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:24:30 INFO - Installing collected packages: mozsystemmonitor 05:24:30 INFO - Running setup.py install for mozsystemmonitor 05:24:30 INFO - Successfully installed mozsystemmonitor 05:24:30 INFO - Cleaning up... 05:24:30 INFO - Return code: 0 05:24:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:24:30 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')]} 05:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:30 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')]} 05:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:30 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:30 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 05:24:30 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 05:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:30 INFO - 'HOME': '/Users/cltbld', 05:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:30 INFO - 'LOGNAME': 'cltbld', 05:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:30 INFO - 'MOZ_NO_REMOTE': '1', 05:24:30 INFO - 'NO_EM_RESTART': '1', 05:24:30 INFO - 'PAGER': '/bin/cat', 05:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:30 INFO - 'PWD': '/builds/slave/test', 05:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:30 INFO - 'SHELL': '/bin/bash', 05:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:30 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:30 INFO - 'USER': 'cltbld', 05:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:30 INFO - 'XPC_FLAGS': '0x0', 05:24:30 INFO - 'XPC_SERVICE_NAME': '0', 05:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:30 INFO - Downloading/unpacking jsonschema==2.5.1 05:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:24:33 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 05:24:33 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 05:24:33 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 05:24:33 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 05:24:33 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 05:24:33 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 05:24:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:33 INFO - warning: no files found matching '*.txt' 05:24:33 INFO - no previously-included directories found matching 'build' 05:24:33 INFO - no previously-included directories found matching 'dist' 05:24:33 INFO - no previously-included directories found matching '.git*' 05:24:33 INFO - Installing collected packages: jsonschema, functools32 05:24:34 INFO - Running setup.py install for functools32 05:24:34 INFO - warning: no files found matching '*.txt' 05:24:34 INFO - no previously-included directories found matching 'build' 05:24:34 INFO - no previously-included directories found matching 'dist' 05:24:34 INFO - no previously-included directories found matching '.git*' 05:24:34 INFO - Successfully installed jsonschema functools32 05:24:34 INFO - Cleaning up... 05:24:34 INFO - Return code: 0 05:24:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:24:34 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')]} 05:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:34 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')]} 05:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:34 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:34 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 05:24:34 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 05:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:34 INFO - 'HOME': '/Users/cltbld', 05:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:34 INFO - 'LOGNAME': 'cltbld', 05:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:34 INFO - 'MOZ_NO_REMOTE': '1', 05:24:34 INFO - 'NO_EM_RESTART': '1', 05:24:34 INFO - 'PAGER': '/bin/cat', 05:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:34 INFO - 'PWD': '/builds/slave/test', 05:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:34 INFO - 'SHELL': '/bin/bash', 05:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:34 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:34 INFO - 'USER': 'cltbld', 05:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:34 INFO - 'XPC_FLAGS': '0x0', 05:24:34 INFO - 'XPC_SERVICE_NAME': '0', 05:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:24:34 INFO - Cleaning up... 05:24:34 INFO - Return code: 0 05:24:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:24:34 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')]} 05:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:34 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')]} 05:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:34 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:34 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 05:24:34 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 05:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:34 INFO - 'HOME': '/Users/cltbld', 05:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:34 INFO - 'LOGNAME': 'cltbld', 05:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:34 INFO - 'MOZ_NO_REMOTE': '1', 05:24:34 INFO - 'NO_EM_RESTART': '1', 05:24:34 INFO - 'PAGER': '/bin/cat', 05:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:34 INFO - 'PWD': '/builds/slave/test', 05:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:34 INFO - 'SHELL': '/bin/bash', 05:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:34 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:34 INFO - 'USER': 'cltbld', 05:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:34 INFO - 'XPC_FLAGS': '0x0', 05:24:34 INFO - 'XPC_SERVICE_NAME': '0', 05:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:37 INFO - Downloading blobuploader-1.2.4.tar.gz 05:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:24:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:24:37 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 05:24:37 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 05:24:37 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 05:24:37 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 05:24:37 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 05:24:37 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 05:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:24:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:24:38 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 05:24:38 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 05:24:38 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 05:24:38 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 05:24:38 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 05:24:38 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 05:24:38 INFO - Downloading docopt-0.6.1.tar.gz 05:24:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:24:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:24:38 INFO - Running setup.py install for blobuploader 05:24:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:24:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:24:38 INFO - Running setup.py install for requests 05:24:39 INFO - Running setup.py install for docopt 05:24:39 INFO - Successfully installed blobuploader requests docopt 05:24:39 INFO - Cleaning up... 05:24:39 INFO - Return code: 0 05:24:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:39 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:39 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 05:24:39 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 05:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:39 INFO - 'HOME': '/Users/cltbld', 05:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:39 INFO - 'LOGNAME': 'cltbld', 05:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:39 INFO - 'MOZ_NO_REMOTE': '1', 05:24:39 INFO - 'NO_EM_RESTART': '1', 05:24:39 INFO - 'PAGER': '/bin/cat', 05:24:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:39 INFO - 'PWD': '/builds/slave/test', 05:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:39 INFO - 'SHELL': '/bin/bash', 05:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:39 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:39 INFO - 'USER': 'cltbld', 05:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:39 INFO - 'XPC_FLAGS': '0x0', 05:24:39 INFO - 'XPC_SERVICE_NAME': '0', 05:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:39 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-DBqskZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:39 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-KeG3mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-kjgilF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-UmEwi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-dyRCNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Yb3cmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-goKjri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-oWdvAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Y3cRZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-FkgM43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-fX0olQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:40 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-916R1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-MgPVye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-O4waif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-uCCS07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-dcgUE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:41 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-xRMuWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:24:41 INFO - Running setup.py install for manifestparser 05:24:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:24:41 INFO - Running setup.py install for mozcrash 05:24:41 INFO - Running setup.py install for mozdebug 05:24:42 INFO - Running setup.py install for mozdevice 05:24:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozfile 05:24:42 INFO - Running setup.py install for mozhttpd 05:24:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozinfo 05:24:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozInstall 05:24:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozleak 05:24:42 INFO - Running setup.py install for mozlog 05:24:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Running setup.py install for moznetwork 05:24:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Running setup.py install for mozprocess 05:24:43 INFO - Running setup.py install for mozprofile 05:24:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Running setup.py install for mozrunner 05:24:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Running setup.py install for mozscreenshot 05:24:43 INFO - Running setup.py install for moztest 05:24:44 INFO - Running setup.py install for mozversion 05:24:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:24:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:24:44 INFO - Cleaning up... 05:24:44 INFO - Return code: 0 05:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:44 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:44 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 05:24:44 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 05:24:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:44 INFO - 'HOME': '/Users/cltbld', 05:24:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:44 INFO - 'LOGNAME': 'cltbld', 05:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:44 INFO - 'MOZ_NO_REMOTE': '1', 05:24:44 INFO - 'NO_EM_RESTART': '1', 05:24:44 INFO - 'PAGER': '/bin/cat', 05:24:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:44 INFO - 'PWD': '/builds/slave/test', 05:24:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:44 INFO - 'SHELL': '/bin/bash', 05:24:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:44 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:44 INFO - 'USER': 'cltbld', 05:24:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:44 INFO - 'XPC_FLAGS': '0x0', 05:24:44 INFO - 'XPC_SERVICE_NAME': '0', 05:24:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:44 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-8u5JxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:44 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)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:44 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-pt9Ola-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:44 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)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:44 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-_eaw61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:44 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)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:44 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-17ORuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:44 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)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:44 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-1HCKUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:44 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)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:44 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-qP2ZWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-nJ8Snk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-phweVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-0PNkUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ryORHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-_64JCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Cr7Z_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-HxxYRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Pmeeos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:45 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)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:45 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-sdnkff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:46 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)) 05:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:46 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-RVO4_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:46 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)) 05:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:46 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-jHJFoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:46 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)) 05:24:46 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)) 05:24:46 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)) 05:24:46 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)) 05:24:46 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)) 05:24:46 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)) 05:24:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:48 INFO - Downloading blessings-1.6.tar.gz 05:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:24:48 INFO - Installing collected packages: blessings 05:24:48 INFO - Running setup.py install for blessings 05:24:49 INFO - Successfully installed blessings 05:24:49 INFO - Cleaning up... 05:24:49 INFO - Return code: 0 05:24:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:24:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:49 INFO - Reading from file tmpfile_stdout 05:24:49 INFO - Current package versions: 05:24:49 INFO - blessings == 1.6 05:24:49 INFO - blobuploader == 1.2.4 05:24:49 INFO - docopt == 0.6.1 05:24:49 INFO - functools32 == 3.2.3-2 05:24:49 INFO - jsonschema == 2.5.1 05:24:49 INFO - manifestparser == 1.1 05:24:49 INFO - mozInstall == 1.12 05:24:49 INFO - mozcrash == 1.0 05:24:49 INFO - mozdebug == 0.1 05:24:49 INFO - mozdevice == 0.49 05:24:49 INFO - mozfile == 1.2 05:24:49 INFO - mozhttpd == 0.7 05:24:49 INFO - mozinfo == 0.9 05:24:49 INFO - mozleak == 0.1 05:24:49 INFO - mozlog == 3.4 05:24:49 INFO - moznetwork == 0.27 05:24:49 INFO - mozprocess == 0.24 05:24:49 INFO - mozprofile == 0.28 05:24:49 INFO - mozrunner == 6.13 05:24:49 INFO - mozscreenshot == 0.1 05:24:49 INFO - mozsystemmonitor == 0.3 05:24:49 INFO - moztest == 0.8 05:24:49 INFO - mozversion == 1.4 05:24:49 INFO - psutil == 3.1.1 05:24:49 INFO - requests == 1.2.3 05:24:49 INFO - wsgiref == 0.1.2 05:24:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:49 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:49 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 05:24:49 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 05:24:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:49 INFO - 'HOME': '/Users/cltbld', 05:24:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:49 INFO - 'LOGNAME': 'cltbld', 05:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:49 INFO - 'MOZ_NO_REMOTE': '1', 05:24:49 INFO - 'NO_EM_RESTART': '1', 05:24:49 INFO - 'PAGER': '/bin/cat', 05:24:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:49 INFO - 'PWD': '/builds/slave/test', 05:24:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:49 INFO - 'SHELL': '/bin/bash', 05:24:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:49 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:49 INFO - 'USER': 'cltbld', 05:24:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:49 INFO - 'XPC_FLAGS': '0x0', 05:24:49 INFO - 'XPC_SERVICE_NAME': '0', 05:24:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 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)) 05:24:50 INFO - Cleaning up... 05:24:50 INFO - Return code: 0 05:24:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:24:50 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')]} 05:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:50 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')]} 05:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:50 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 0x108ae1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ae4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe450555970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, '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.nXlFSKJmnI/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.oWchrrwtWN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:24:50 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 05:24:50 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 05:24:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:24:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:50 INFO - 'HOME': '/Users/cltbld', 05:24:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:50 INFO - 'LOGNAME': 'cltbld', 05:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:50 INFO - 'MOZ_NO_REMOTE': '1', 05:24:50 INFO - 'NO_EM_RESTART': '1', 05:24:50 INFO - 'PAGER': '/bin/cat', 05:24:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:50 INFO - 'PWD': '/builds/slave/test', 05:24:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:50 INFO - 'SHELL': '/bin/bash', 05:24:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:24:50 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:24:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:50 INFO - 'USER': 'cltbld', 05:24:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:50 INFO - 'XPC_FLAGS': '0x0', 05:24:50 INFO - 'XPC_SERVICE_NAME': '0', 05:24:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:24:50 INFO - Cleaning up... 05:24:50 INFO - Return code: 0 05:24:50 INFO - Running post-action listener: _resource_record_post_action 05:24:50 INFO - Running post-action listener: _start_resource_monitoring 05:24:50 INFO - Starting resource monitoring. 05:24:50 INFO - [mozharness: 2017-03-09 13:24:50.837830Z] Finished create-virtualenv step (success) 05:24:50 INFO - [mozharness: 2017-03-09 13:24:50.838771Z] Running install step. 05:24:50 INFO - Running pre-action listener: _resource_record_pre_action 05:24:50 INFO - Running main action method: install 05:24:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:51 INFO - Reading from file tmpfile_stdout 05:24:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:24:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:24:51 INFO - Reading from file tmpfile_stdout 05:24:51 INFO - Output received: 05:24:51 INFO - Usage: mozinstall [options] installer 05:24:51 INFO - Options: 05:24:51 INFO - -h, --help show this help message and exit 05:24:51 INFO - -d DEST, --destination=DEST 05:24:51 INFO - Directory to install application into. [default: 05:24:51 INFO - "/builds/slave/test"] 05:24:51 INFO - --app=APP Application being installed. [default: firefox] 05:24:51 INFO - mkdir: /builds/slave/test/build/application 05:24:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:25:05 INFO - Reading from file tmpfile_stdout 05:25:05 INFO - Output received: 05:25:05 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:25:05 INFO - Running post-action listener: _resource_record_post_action 05:25:05 INFO - [mozharness: 2017-03-09 13:25:05.779277Z] Finished install step (success) 05:25:05 INFO - [mozharness: 2017-03-09 13:25:05.779419Z] Running run-tests step. 05:25:05 INFO - Running pre-action listener: _resource_record_pre_action 05:25:05 INFO - Running main action method: run_tests 05:25:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:05 INFO - 2017-03-09 05:25:05.794 screenresolution[1904:15364] starting screenresolution argv=screenresolution get 05:25:05 INFO - 2017-03-09 05:25:05.796 screenresolution[1904:15364] Display 0: 1600x1200x32@60 05:25:05 INFO - 2017-03-09 05:25:05.802 screenresolution[1905:15367] starting screenresolution argv=screenresolution list 05:25:05 INFO - Available Modes on Display 0 05:25:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:06 INFO - Intel Iris: 05:25:06 INFO - Chipset Model: Intel Iris 05:25:06 INFO - Type: GPU 05:25:06 INFO - Bus: Built-In 05:25:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:06 INFO - Vendor: Intel (0x8086) 05:25:06 INFO - Device ID: 0x0a2e 05:25:06 INFO - Revision ID: 0x0009 05:25:06 INFO - Displays: 05:25:06 INFO - Display: 05:25:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:06 INFO - Display Serial Number: 1600x1200 60 05:25:06 INFO - Main Display: Yes 05:25:06 INFO - Mirror: Off 05:25:06 INFO - Online: Yes 05:25:06 INFO - Rotation: Supported 05:25:06 INFO - Return code: 0 05:25:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:25:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:25:06 INFO - Python 2.7.3 05:25:06 INFO - Return code: 0 05:25:06 INFO - grabbing minidump binary from tooltool 05:25: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')]} 05:25:06 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 0x1088bfd30>, '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 0x1087e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:25:06 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 05:25:06 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 05:25:06 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 05:25:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:25:06 INFO - Return code: 0 05:25:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:25:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:25:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:25:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:25:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:25:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 05:25:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0075 --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 05:25:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nXlFSKJmnI/Render', 05:25:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:06 INFO - 'HOME': '/Users/cltbld', 05:25:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:06 INFO - 'LOGNAME': 'cltbld', 05:25:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:25:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:06 INFO - 'MOZ_NO_REMOTE': '1', 05:25:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:25:06 INFO - 'NO_EM_RESTART': '1', 05:25:06 INFO - 'PAGER': '/bin/cat', 05:25:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:06 INFO - 'PWD': '/builds/slave/test', 05:25:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:25:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:06 INFO - 'SHELL': '/bin/bash', 05:25:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWchrrwtWN/Listeners', 05:25:06 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:25:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:06 INFO - 'USER': 'cltbld', 05:25:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:06 INFO - 'XPC_FLAGS': '0x0', 05:25:06 INFO - 'XPC_SERVICE_NAME': '0', 05:25:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 05:25:06 INFO - mozversion application_buildid: 20170309030823 05:25:06 INFO - mozversion application_changeset: 31f757e837abad20e44fff4700b1d3917ef5cd11 05:25:06 INFO - mozversion application_display_name: Firefox 05:25:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:25:06 INFO - mozversion application_name: Firefox 05:25:06 INFO - mozversion application_remotingname: firefox 05:25:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:25:06 INFO - mozversion application_vendor: Mozilla 05:25:06 INFO - mozversion application_version: 53.0 05:25:06 INFO - mozversion platform_buildid: 20170309030823 05:25:06 INFO - mozversion platform_changeset: 31f757e837abad20e44fff4700b1d3917ef5cd11 05:25:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:25:06 INFO - mozversion platform_version: 53.0 05:25:06 INFO - using testdate: 1489065906 05:25:06 INFO - actual date: 1489065906 05:25:06 INFO - starting webserver on 'localhost:52155' 05:25:06 INFO - SUITE-START | Running 2 tests 05:25:06 INFO - TEST-START | dromaeo_css 05:25:06 INFO - operating with platform_type : mac_ 05:25:06 INFO - Initialising browser for dromaeo_css test... 05:25:06 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpEFS8rs/profile http://localhost:52155/getInfo.html) 05:25:09 INFO - PROCESS | 1915 | 2017-03-09 05:25:09.370 plugin-container[1918:15522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 05:25:09 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:09 INFO - PROCESS | 1915 | 2017-03-09 05:25:09.371 plugin-container[1918:15522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 05:25:09 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:09 INFO - PROCESS | 1915 | 2017-03-09 05:25:09.371 plugin-container[1918:15522] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:25:09 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 05:25:09 INFO - PROCESS | 1915 | colorDepth:24 05:25:09 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 05:25:09 INFO - PROCESS | 1915 | __metrics 05:25:09 ERROR - PROCESS | 1915 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:25:10 INFO - TEST-INFO | 1915: exit 0 05:25:10 INFO - Browser initialized. 05:25:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:25:10 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpEFS8rs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:12 INFO - PROCESS | 1919 | 2017-03-09 05:25:12.488 plugin-container[1920:15654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 05:25:12 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:12 INFO - PROCESS | 1919 | 2017-03-09 05:25:12.489 plugin-container[1920:15654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x871b, name = 'com.apple.CFPasteboardClient' 05:25:12 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:25:12 INFO - PROCESS | 1919 | 2017-03-09 05:25:12.489 plugin-container[1920:15654] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:25:22 INFO - PROCESS | 1919 | RSS: Main: 172675072 05:25:22 INFO - PROCESS | 1919 | 05:28:24 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52155/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52155/tests/dromaeo/cssquery-ext.html) 05:28:26 INFO - PROCESS | 1919 | RSS: Main: 192888832 05:28:26 INFO - PROCESS | 1919 | 05:31:28 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52155/tests/dromaeo/cssquery-ext.html (next: http://localhost:52155/tests/dromaeo/cssquery-jquery.html) 05:31:29 INFO - PROCESS | 1919 | RSS: Main: 204406784 05:31:29 INFO - PROCESS | 1919 | 05:34:31 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52155/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52155/tests/dromaeo/cssquery-mootools.html) 05:34:32 INFO - PROCESS | 1919 | RSS: Main: 206110720 05:34:32 INFO - PROCESS | 1919 | 05:37:33 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52155/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52155/tests/dromaeo/cssquery-prototype.html) 05:37:34 INFO - PROCESS | 1919 | RSS: Main: 210169856 05:37:34 INFO - PROCESS | 1919 | 05:40:37 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52155/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52155/tests/dromaeo/cssquery-yui.html) 05:40:38 INFO - PROCESS | 1919 | RSS: Main: 211705856 05:40:38 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:52155/tests/dromaeo/cssquery-yui.html (next: http://localhost:52155/tests/dromaeo/cssquery-dojo.html) 05:43:40 INFO - PROCESS | 1919 | RSS: Main: 211750912 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | __start_tp_report 05:43:40 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 05:43:40 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 05:43:40 INFO - PROCESS | 1919 | |i|pagename|runs| 05:43:40 INFO - PROCESS | 1919 | |0;dojo.html;3496.0560648624887;3505.9123521911947;3523.700485458736;3537.699295559877;3541.220931395094;5974.940250597493;5976.3724808895095;6031.3968603139665;6039.124326972592;6058.030715085587;4666.603338716213;6038.215032045839;6123.755049798003;6144.477719393845;6153.230846144232;5422.2951016367915;5464.562834973192;5470.288862447876;5515.338159420875;5572;5526.778928842841;5549.084401073818;5560.277163968678;5562.360328562207;5565.30433695788;5404.4595540446035;5409.404965453797;5523.6409633373705;5544.417836127209;5549.223108764756;5437.075697131478;5458.4541545845295;5505.944940550583;5528.889422211533;5556.083246264361;4582.243929751587;5301.416844147141;5371.409144994048;5443.346798384179;5485.01269771441;5109.259157422191;5957.642541447527;6115.969420152871;6141.815745527641;6176.87646247077;5433.212184233264;6210.596311239755;6255.499560035232;6260.561760676755;6305.59013664115;6481.527596545574;6496.220453545558;6522.706478208492;6540.6729663516635;6570.310117437696;5092.465291144376;6126.448619624255;6144.446999770043;6151.384861513858;6193.194884664965;6366.6180029198395;6368.777092801823;6495.512836537278;6518.315576864456;6547.247066587403;5219.947800522022;6214.937850621526;6253.499720022387;6294.748210071546;6295.464885484694;6412.9679351602945;6424.229092508929;6443.130177426081;6453.838654033687;6462.3537646235;6180.443760061616;6210.937890621036;6218.595791273553;6227.159333490012;6308;6299.748010079637;6324.778632747832;6328.588641738273;6346;6357.554971151975;5196.350456192976;5198.818041368534;5209.661372010807;5213.2701421800975;5221.843344699665;4605.953940460619;4607.216773148572;4610.331501932201;4658.836940707127;4692.0381321828945;5128.8461346159675;6060;6060.909086363708;6090.147379366892;6168.290646575701;6465.3857883501;6482.740690372332;6494.383033611799;6501.122348482989;6511.967440162864;6002.159697642333;6026.246719160105;6067.75728970845;6102.664353460602;6128.662923539248;5721.914171287434;5760.74076666551;5765.596408251466;5793.94206057934;5806.071028635398;6536.4117229447675;6554.115194448784;6559.212894452506;6572.178477690289;6578.473722102125;6216.285127210429;6262;6291.433770960635;6345.238571371465;6464.903026454513;6430.260520040255;6436.841368553518;6444.291127976013;6447.548671592944;6467.805965821033;6306.621602703853;6307.3377295383325;6314.336994615499;6335.746570137143;6350.206224221973;6120.843333897766;6242.719077641426;6297.685115744102;6385.584936979268;6393.520485963664;6128.992047069418;6174.6912654368;6212.099245609452;6217.96891015542;6225.408586184305;4863.805447782049;6221.035739460391;6228.719707613078;6229.221347331583;6258.405451482102;6100.359462256577;6156.168917196301;6197.938020619736;6201.348858369806;6248.593841400295;6157.815265542041;6158.907616385668;6191.81424557264;6234.189555357774;6258.3428739983465;6190.535709821871;6202.596831205957;6247.718852651551;6251.281102673251;6265.122882796503 05:43:40 INFO - PROCESS | 1919 | |1;ext.html;485.63577317012243;514.8544488513058;520.5783315514434;524.4074196158341;556.3907521264214;39586;42320.576794232045;42441.15117697644;42502.72491825246;42503.937401564974;43533.782331088376;43757;44236;44266.77866610663;44347.77826110865;9508.191803696694;9766.755831104208;9781.853272200922;11587.65237042886;12014.339211343373;11814.409279536032;11865.16943813929;11899;11906.642800715992;11924.403779811018;11761.941190294037;11877;11886.346250956194;11931.403429828473;11950.342731149827;11743.295402275891;11753.471093800757;11800.881991180064;11833.644990650295;11904.464299106561;11854.822177667342;11871.228370155912;11948.641540753792;11986.340751258676;11986.460609272604;38798.612013879785;38875.80562097171;39030.219395612214;39182;39192.02019949495;4017.5762769799517;4088.5911408859174;4112;4222;4338.284183109783;28196;28306.57540136879;28684.569731454045;29012.70987290121;29054.564181537295;3337.0823023668445;3425.3149370126075;3513.3149035938004;3518.138056176253;3527.576690797095;502650;505058;507166;508145;509365;59050;59085.70457147773;59419.40580594139;59515;59658.10512842311;60218;61521;61925.690371547855;62032;62215.688921555105;16881.987080775194;16978.830211697972;17094.658106837793;17126.40057598003;17182.484525464257;12118.939405303094;12427.31649759272;12921.418536165895;13179.472821087242;13221.933890330487;18693.532661683566;18750.437486875417;18784;18907.621847562972;18928.242870285034;6637.900431493532;8289.295409890106;8341.41610087288;8345.874811877828;8367.497950123012;885.4333226734894;899.6131663384676;909.5861383070687;921.7833809054866;926.0970553710079;30176.09471715852;31075.378492430023;31209.21976950549;31696;31819.681803182135;874.4534665833854;970.7136394763562;1066.4642914203678;1071.6302875507938;1256.7432567432568;11923.946177601967;14698.926505367404;14715.043522171025;14735.336909839083;14836.3323650436;619589;619892;621200;624605;627278;53697.92604147895;53788.73105634447;54196.916061678545;54331;54421.45578544164;1182.9231099978474;1449.3333066789185;1483.7032593481347;1490.8658220759967;1499.1604701367269;24063.759362406152;26935.86532067327;27603.30991725223;27648.308792280353;28567.714322856504;38003;47356;47384.526154738014;47623.52376476191;48046.75976620234;596199;597479;598948;600217;600619;21266.83032433231;25208;25410;25649.717514123848;26181.869090654425;32078.35843283168;38649.6135038646;42979.78510107555;43024;43493;33475.50099151945;34699.4795078069;36129.458058129676;36888.52445902134;37251.44122838105;35529.28941421105;35655.82172089123;35718.107047322985;36658;36929.07677308089;34563.963081107606;35581.64418355783;35722.28555428929;35891.46162807508;35968;29580;31098.844505777328;31516;31555.36889262248;31628;22978.42553936165;23976.640350395108;24065.954393876716;24151;24330.323664397256 05:43:40 INFO - PROCESS | 1919 | |2;jquery.html;397.6758941462706;406.11669618579583;411.39730295117647;412.8162967479472;432.32341385731326;20393.898030509845;21048.89475552622;21811.127554897805;22026.33920982369;22372.552548949054;22239.44401389962;22305.55388892221;22664.660030099563;22710.88644556779;22928.770712292873;5536.529395001429;6070.2412198475195;6148.877022459548;6151.90772138416;6152.261728592553;5803.854903627422;5934.792282270121;6035.5473339499495;6052.697365131748;6064.514838812906;5961.5230781537375;6015.669138197398;6023.30731965823;6025.969870150665;6038.486728628071;5254.369475662907;5826.504747096481;5999.800039991997;6032.849178770522;6070.150178974947;5186.170212765939;5239.816606418757;5340.973295133538;5342.038433082043;5343.251944727741;26194;26194.73805261942;26785;26989.86505067462;27073.1878043659;2805.7474827265646;2831.9716802831913;2833.702461241581;2851.586519954595;2871.296532349567;15370.615734606612;15994.600134996717;16025.439109631223;16090.75863862043;16626.418075367303;1879.6522643311032;1889.7070954002152;1894.4222012286248;1901.077977181066;1923.8941858197793;122165;122297;123058.69413058623;132158.6784132156;132650;10637.414942178255;11800.70498237551;11813.291202527877;11829.290242585472;11968.10239232061;15033.248337583078;15159.545213643609;15210.619734506727;15295.541133766004;15324.616884577974;9087.318451116193;9279.67521136757;9286.860697089549;9421.104995025598;9477.289203309641;9198.264138868875;9234.984151743303;9292.488913109844;9346.859797103049;9459.76350591227;6107.389261073946;6442.387973142544;6818.590884546943;7567.470277080552;7595.392368610498;2007.628588711093;2025.797420257992;2025.9797402026086;2030.5735795483115;2037.4193354893785;895.9283257339397;915.8031023330016;916.8808054952814;921.9492927888896;938.1790932933683;21164.808178015766;21178.682319765216;22532.21137260188;22558.774412255996;22580.887095564416;828.9472370089952;882.9514376709343;944.6976967370513;962.4225464721251;987.7603607472576;11050.115990720724;11774.764504710029;11777.470013849397;11788.46951887167;11805.409729513662;683581;687442;689084;699095;700529;96962;116701;123548;125516;132004.33997829948;1022.892596277395;1064.7870425914693;1065.6536625596711;1065.706930594093;1076.0638244726981;17169.227384767466;19458.513537161118;19700.01499924969;20407.8979605101;21850.890745546807;56925;57176.98447206428;57335.713321433126;57810.13284800647;58023.83952321015;80202.59898700469;89785.55107224423;92386;100439;103989;7531.774046778605;7667.808304792425;7870.9606451967375;8317.376196785144;8873.423227490193;9308.069193080637;12688.74622507563;12691.238525688488;13384.598462046155;14277.500287489887;5709.714514274353;6159.876802464015;11592.95663390299;11770.58802941893;11865.11011674145;11626.418679065844;11651.650450486499;11675.766484670428;11843.526258949545;11846.644600661994;11104.722381940515;11316.547338106713;11910.34493102853;11930.701732456759;12051.156419050581;8661.956690216508;8686.91313086861;8875.556222188736;8894.4663320201;8913.108689131295;8662.956685216534;11193.432919391129;11600.6519804406;11633.709157271136;11894.881051189377 05:43:40 INFO - PROCESS | 1919 | |3;mootools.html;1403.7052219034003;1452.8837692984562;1503.4888138033066;1504.0749938787635;1509.728248915195;7714.691412343514;7980.9201907980905;8264.421490495668;8683.565821708913;8704.911886014248;8152.470089444197;8475.830483390344;8480.78798030048;8546.273566746833;8553.187447192522;2382.29722231942;2421.8546887186735;2424.8787560621986;2425.248173066355;2427.2718184544656;2197.241951526729;2357.750392292088;2406.1698713943747;2445.6942882139733;2452.288836237489;2237.8321625878125;2242.1703969531295;2259.20927675314;2273.27255278311;2283.7830406111475;2359.528094381122;2366.751491093428;2389.462370966536;2393.365758074112;2397.4485868250226;1984.345166095185;2329.9883500582387;2391.9162829301067;2401.183597576815;2420.056178090546;8325.167483251687;8336.333093352518;8386.161383861627;8406.747797566022;8446;5172.663776854492;5174.353205849269;5267.104592219293;5325.440828713007;5329.573634109262;3408.659134086596;3548.9112772180642;3658.6707196352454;3668.486411902336;3673.412254039368;1183.449695891406;1194.5401020607128;1210.2919791921736;1301.1867582760774;1330.3614265152685;10903.672889813319;10909.509072091774;10954.068904143158;10974.451277436096;10975.945120274428;4462.531434199427;4495.786137742856;4771.832985845479;4842.757862106881;4844.861457557471;8506.149385061444;9498.667546635834;9546.236301095896;9577.23382129428;9583.616655333848;5265.210218467184;5321.228421878806;5354.170103633943;5360.276362691065;5373.650712703662;4452.332150177499;4468.687191896601;4836.879078023007;4880.463149053601;4944.653874228841;3938.980305098456;4007.0783719744622;4056.0671045659665;4073.7555746655294;4083.1629515949157;1113.3431275547466;1119.2612875502293;1324.1525423728822;1327.6349004023778;1347.0974447120452;824.1593574554041;830.4353039932782;838.2071128145192;838.6058552480324;876.6011464783509;5198.194279886624;5574.554035677217;5772.7113644317615;5828.125781132864;5841.065429531254;971.9271054670928;985.2856678907877;1009.106940357774;1055.7888422315568;1085.1644233939821;3526;3724.329620668306;3835.980820095882;3861.942070868939;3914.3345631242755;8260.339172866155;9883.901160988298;10219.284650074424;10234.4136658818;10243.026912443305;9931.205503559699;9956.303058785961;10083.59665613382;10086.848697269546;10197.847032294521;7883.881741773878;7996;7996.960015200003;8005.559694216758;8080.2727754502375;5461.890762184655;5502.422245664147;5526.640768350045;5649.559362362571;5682.659040457585;9362.625494980124;9391.624335026523;9465.290103242147;9470.621175152917;9491.240700743785;7524.2851929066655;9101.77245568866;9361.906380936376;9631;9678.806423871341;6827.556208846409;6953.1656201254145;7039.366457018893;7236.674349654383;7315.817104572428;8022.879656805036;8084.231997960184;8101.635426405708;8101.959490202511;8103.473274237156;7085.256048114874;7122.679479423335;7216.9278307216255;7252.673629686571;7333.706651733871;7081.185663648746;7188.245234250328;7192.820179495555;7305.196428392766;7337.413006959324;6408.58344207625;6420.871582568416;6467.611943283418;6471.417572418317;6475.061116138228;4082.265192265421;4984.215153452581;5033.345665063518;5072.239164125411;5140.46025167352;4976.278439626306;5023.296738456692;5064.41759197697;5178.585713142864;5207.0366982108435 05:43:40 INFO - PROCESS | 1919 | |4;prototype.html;345.30421201279427;364.42057129164624;379.2812620084941;381.96180381961807;394.1368403197;4899.412070551539;4906.846890980617;5587.413321601234;5594.524465420434;5612.971935140318;4245.426867372904;4355.1038559357985;5094.694318340893;5164.715940623274;5201.271821944922;3771.528558930134;3994.7203695741237;4122.9381559276635;4366.388705581206;4393.780310984438;3856.267309211255;4275.87172384829;4284.2930916398755;4312;4338.739675619473;3508.4035713928565;4245.936310955338;4319.76241306728;4322.351647252922;4331.8700438986725;4033.3546632538655;4318.524962254165;4335.6748243881675;4347.500037495697;4364.214441400547;3125.749940004817;3382.3404436134965;3567.8216089195435;3627.455881617752;3810.733248672594;4604.401427814362;5197.818076367347;5363.249145119687;5379.65032272901;5379.784808607651;4380.759058251795;4804.5916097131785;4804.702730262809;4847;4852.320675105488;4729.9763501182615;4743.976280118612;5355.2770375999135;5861.648301101947;5911.379305172938;3440.4174079923264;3912.1784425270444;3963.4847469829024;3973.781442020688;4006.559278479365;4805.951940480585;5104.412992505872;5487.780488780484;5777.757782076832;6007.519398448114;3945.2504024235877;3956.40653901917;3991.341428664296;3996.780177090288;4016.5983401660183;3429.4855771633956;4056.472658554428;4066;4071.7353372030725;4078.490188726409;3635.781853088823;4140.648044916155;4178.603032711888;4719.339292499053;4794.304825800239;5744.655320680772;5849.970750146222;5850.063989761617;5880.794172203952;5897.380775018647;1325.357201757147;1325.5824415309146;1329.3286890120428;1333.8466076401144;1336.4787732784223;812.4556547113448;839.5004972041626;893.7586851550175;898.4070513461085;910.7267819654078;5154.505193493845;5155.458676838878;5177.301064356339;5198.246254293106;5221.216817477332;5168.9741551292;5363.222332761728;5395.541378982752;5749.1663708762835;5794.246747922743;5063.265826455144;5171.689698618095;5172.8965420691375;5178.300929374486;5414.458554144633;2413.7448526766334;2689.865506724656;2799.4820958122405;3023.470892593787;3025.969740302613;5691.231683722727;5845.837040295118;5859.794907178229;5884.204687976317;5908.615939963888;4376.49670287921;5558.360788509373;6067.969660151671;6147.293061297919;6158.815235542941;5326.573874089986;6111.297200821962;6111.602745821508;6167.876642467215;6176.35148309421;5425.023495770642;5454.645448046023;5697.829065128053;6040.728167232573;6055.212822333068;5954.523638109027;5996.850078748066;6005.519558435402;6027.276726792813;6063.302720187235;4783.230204824237;5767.538596912321;5889.204957330706;6124.6325220486915;6142.447179753844;3174.3016536361965;3976.4830572025453;4021.3163762159916;4048.3725022621543;4064.1668457965457;5028.622853285945;5113.667611605234;6008.339082700987;6020.939790602038;6036.555195145973;5065.797368105234;5366.838994830161;5752.568557358131;5876.882462350814;5897.373115534002;5155.381354237516;5863.061910094535;5870.412958704095;5905.586608937333;5923.378045305181;4948.925766113583;5185.248139019745;5690.77236910519;5717.1995920570325;5728.341240757366;4845.370101886873;5751.194832723339;5847.830433913149;5867.887560478139;5888.293404791452;5738.569607279387;5750.942490575041;5760.596758227051;5760.798372056958;5764.7188027275215 05:43:40 INFO - PROCESS | 1919 | |5;yui.html;646.5964704137625;713.4256923176844;787.1577412168978;832.3893266529266;835.4778263585257;15247.923760381196;16102.838971610281;16379.344826206967;16413.50759477216;16419.425320113798;14837.777433338508;14995.625109372271;15004.174770387624;15025.173615451145;15043;679.911611490508;712.2130046298843;731.2723839779427;865.208334374047;873.4191762477944;872.1845074854999;884.1203003012012;885.402353411448;887.5163036145316;889.799795046116;955.9187469065138;957.8467445208768;958.9856152157723;959.1655259923876;962.210986990668;787.763670898728;949.0367277213634;951.5908159491416;956.2350119904049;957.1098878043417;713.8001359619315;713.846522997558;714.674822955554;715.9212486626467;716.3982254905904;14087.36606852694;14241.931855110804;15590;15677.451289204822;16405.753913691184;13053.347332633426;14771.704565908729;14989.475368362055;14996.925015374962;15172.24138793067;11001.01981643304;11664.183507154503;11849.822252666218;11849.940750296193;11968.162228643998;3524.8942531724088;3602.4776407420777;3647.671709546127;3650.215203731184;3795.6394142556414;68664;98143;100556.99443005549;101280.9871901279;102130;10976.835347469794;10997.285176463503;11104.114710125867;11127.221094523467;11248.718782030515;22130.114795408004;22382.664260036112;22557.210497632503;22571.774282257295;22574.661380079313;7918.406119541058;7949.244821741925;7977.122516523178;8019.599020048974;8077.515349079074;4444.73331600105;4457.7548234846745;4477.538047191356;4481.843135490242;4504.954950450519;3206.486962086102;3690.0590349460717;3731.272401881607;3780.11167375672;3783.4135708965155;673.865226954611;675.5608854244682;680.4862328941617;695.982600434983;696.1889398850384;650.3366566102643;673.2022553274386;675.9019942108366;685.1025157044288;692.8256605054485;10398.376097434179;10422.218333624856;10461.163106951426;10754.247202695737;10845.295055821347;561.2927711084063;690.1496818141045;717.7774889784183;722.598957578549;729.1468981291902;4938;5150.201718733602;5168.147255702767;5175.197844334134;5179.456157103526;90187.64718529227;103368;104627;105440;107948;55993.16010259849;67702.3229767696;74259.62870185722;81798.36403271901;90274.19451610932;33230.8338458311;46040;46054;77968.22031779609;80481.19518804736;7670.923290767021;9328.766780830534;10599.205059620404;11105.278156919774;11338.433078345884;35985.40145985485;45080;46032;52237.7388113057;52922.73538632436;71988.64005679938;79293;83444.58277708573;84655.15344846473;84675.15324846673;14229.456810697206;14368.13791172533;14726.926365368105;15222.861250898794;15535.533933981998;15392.230388480755;15521.68956620884;15556.455524056604;15650.843491564938;15665.334630792022;14196.429461428928;14333;14482;14650.487232946796;14711;14838.332275047433;14885.776713349525;14898.106113633217;15124.773128403303;15268.160251186291;13840.377183026989;14362.712745745235;14455.710885782435;14462.927685361927;14729.04261223017;10836.068089234514;11163.609273675382;11285.266457680225;11470.483828227916;11497.19519633651;10507.579696812347;11329.830052549054;11724.479236963753;12030.819537706766;12155.878441215475 05:43:40 INFO - PROCESS | 1919 | __end_tp_report 05:43:40 INFO - PROCESS | 1919 | __start_cc_report 05:43:40 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,376 05:43:40 INFO - PROCESS | 1919 | __end_cc_report 05:43:40 INFO - PROCESS | 1919 | __startTimestamp1489067020817__endTimestamp 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | ------- Summary: start ------- 05:43:40 INFO - PROCESS | 1919 | Number of tests: 6 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | [#0] dojo.html Cycles:165 Average:5920.11 Median:6174.69 stddev:647.00 (10.5%) stddev-sans-first:620.41 05:43:40 INFO - PROCESS | 1919 | Values: 3496.1 3505.9 3523.7 3537.7 3541.2 5974.9 5976.4 6031.4 6039.1 6058.0 4666.6 6038.2 6123.8 6144.5 6153.2 5422.3 5464.6 5470.3 5515.3 5572.0 5526.8 5549.1 5560.3 5562.4 5565.3 5404.5 5409.4 5523.6 5544.4 5549.2 5437.1 5458.5 5505.9 5528.9 5556.1 4582.2 5301.4 5371.4 5443.3 5485.0 5109.3 5957.6 6116.0 6141.8 6176.9 5433.2 6210.6 6255.5 6260.6 6305.6 6481.5 6496.2 6522.7 6540.7 6570.3 5092.5 6126.4 6144.4 6151.4 6193.2 6366.6 6368.8 6495.5 6518.3 6547.2 5219.9 6214.9 6253.5 6294.7 6295.5 6413.0 6424.2 6443.1 6453.8 6462.4 6180.4 6210.9 6218.6 6227.2 6308.0 6299.7 6324.8 6328.6 6346.0 6357.6 5196.4 5198.8 5209.7 5213.3 5221.8 4606.0 4607.2 4610.3 4658.8 4692.0 5128.8 6060.0 6060.9 6090.1 6168.3 6465.4 6482.7 6494.4 6501.1 6512.0 6002.2 6026.2 6067.8 6102.7 6128.7 5721.9 5760.7 5765.6 5793.9 5806.1 6536.4 6554.1 6559.2 6572.2 6578.5 6216.3 6262.0 6291.4 6345.2 6464.9 6430.3 6436.8 6444.3 6447.5 6467.8 6306.6 6307.3 6314.3 6335.7 6350.2 6120.8 6242.7 6297.7 6385.6 6393.5 6129.0 6174.7 6212.1 6218.0 6225.4 4863.8 6221.0 6228.7 6229.2 6258.4 6100.4 6156.2 6197.9 6201.3 6248.6 6157.8 6158.9 6191.8 6234.2 6258.3 6190.5 6202.6 6247.7 6251.3 6265.1 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | [#1] ext.html Cycles:180 Average:70210.26 Median:35618.73 stddev:154496.29 (433.8%) stddev-sans-first:154841.00 05:43:40 INFO - PROCESS | 1919 | Values: 485.6 514.9 520.6 524.4 556.4 39586.0 42320.6 42441.2 42502.7 42503.9 43533.8 43757.0 44236.0 44266.8 44347.8 9508.2 9766.8 9781.9 11587.7 12014.3 11814.4 11865.2 11899.0 11906.6 11924.4 11761.9 11877.0 11886.3 11931.4 11950.3 11743.3 11753.5 11800.9 11833.6 11904.5 11854.8 11871.2 11948.6 11986.3 11986.5 38798.6 38875.8 39030.2 39182.0 39192.0 4017.6 4088.6 4112.0 4222.0 4338.3 28196.0 28306.6 28684.6 29012.7 29054.6 3337.1 3425.3 3513.3 3518.1 3527.6 502650.0 505058.0 507166.0 508145.0 509365.0 59050.0 59085.7 59419.4 59515.0 59658.1 60218.0 61521.0 61925.7 62032.0 62215.7 16882.0 16978.8 17094.7 17126.4 17182.5 12118.9 12427.3 12921.4 13179.5 13221.9 18693.5 18750.4 18784.0 18907.6 18928.2 6637.9 8289.3 8341.4 8345.9 8367.5 885.4 899.6 909.6 921.8 926.1 30176.1 31075.4 31209.2 31696.0 31819.7 874.5 970.7 1066.5 1071.6 1256.7 11923.9 14698.9 14715.0 14735.3 14836.3 619589.0 619892.0 621200.0 624605.0 627278.0 53697.9 53788.7 54196.9 54331.0 54421.5 1182.9 1449.3 1483.7 1490.9 1499.2 24063.8 26935.9 27603.3 27648.3 28567.7 38003.0 47356.0 47384.5 47623.5 48046.8 596199.0 597479.0 598948.0 600217.0 600619.0 21266.8 25208.0 25410.0 25649.7 26181.9 32078.4 38649.6 42979.8 43024.0 43493.0 33475.5 34699.5 36129.5 36888.5 37251.4 35529.3 35655.8 35718.1 36658.0 36929.1 34564.0 35581.6 35722.3 35891.5 35968.0 29580.0 31098.8 31516.0 31555.4 31628.0 22978.4 23976.6 24066.0 24151.0 24330.3 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | [#2] jquery.html Cycles:180 Average:38693.87 Median:27031.53 stddev:114805.43 (424.7%) stddev-sans-first:115091.47 05:43:40 INFO - PROCESS | 1919 | Values: 397.7 406.1 411.4 412.8 432.3 20393.9 21048.9 21811.1 22026.3 22372.6 22239.4 22305.6 22664.7 22710.9 22928.8 5536.5 6070.2 6148.9 6151.9 6152.3 5803.9 5934.8 6035.5 6052.7 6064.5 5961.5 6015.7 6023.3 6026.0 6038.5 5254.4 5826.5 5999.8 6032.8 6070.2 5186.2 5239.8 5341.0 5342.0 5343.3 26194.0 26194.7 26785.0 26989.9 27073.2 2805.7 2832.0 2833.7 2851.6 2871.3 15370.6 15994.6 16025.4 16090.8 16626.4 1879.7 1889.7 1894.4 1901.1 1923.9 122165.0 122297.0 123058.7 132158.7 132650.0 10637.4 11800.7 11813.3 11829.3 11968.1 15033.2 15159.5 15210.6 15295.5 15324.6 9087.3 9279.7 9286.9 9421.1 9477.3 9198.3 9235.0 9292.5 9346.9 9459.8 6107.4 6442.4 6818.6 7567.5 7595.4 2007.6 2025.8 2026.0 2030.6 2037.4 895.9 915.8 916.9 921.9 938.2 21164.8 21178.7 22532.2 22558.8 22580.9 828.9 883.0 944.7 962.4 987.8 11050.1 11774.8 11777.5 11788.5 11805.4 683581.0 687442.0 689084.0 699095.0 700529.0 96962.0 116701.0 123548.0 125516.0 132004.3 1022.9 1064.8 1065.7 1065.7 1076.1 17169.2 19458.5 19700.0 20407.9 21850.9 56925.0 57177.0 57335.7 57810.1 58023.8 80202.6 89785.6 92386.0 100439.0 103989.0 7531.8 7667.8 7871.0 8317.4 8873.4 9308.1 12688.7 12691.2 13384.6 14277.5 5709.7 6159.9 11593.0 11770.6 11865.1 11626.4 11651.7 11675.8 11843.5 11846.6 11104.7 11316.5 11910.3 11930.7 12051.2 8662.0 8686.9 8875.6 8894.5 8913.1 8663.0 11193.4 11600.7 11633.7 11894.9 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | [#3] mootools.html Cycles:180 Average:5495.04 Median:5202.62 stddev:2965.87 (57.0%) stddev-sans-first:2958.25 05:43:40 INFO - PROCESS | 1919 | Values: 1403.7 1452.9 1503.5 1504.1 1509.7 7714.7 7980.9 8264.4 8683.6 8704.9 8152.5 8475.8 8480.8 8546.3 8553.2 2382.3 2421.9 2424.9 2425.2 2427.3 2197.2 2357.8 2406.2 2445.7 2452.3 2237.8 2242.2 2259.2 2273.3 2283.8 2359.5 2366.8 2389.5 2393.4 2397.4 1984.3 2330.0 2391.9 2401.2 2420.1 8325.2 8336.3 8386.2 8406.7 8446.0 5172.7 5174.4 5267.1 5325.4 5329.6 3408.7 3548.9 3658.7 3668.5 3673.4 1183.4 1194.5 1210.3 1301.2 1330.4 10903.7 10909.5 10954.1 10974.5 10975.9 4462.5 4495.8 4771.8 4842.8 4844.9 8506.1 9498.7 9546.2 9577.2 9583.6 5265.2 5321.2 5354.2 5360.3 5373.7 4452.3 4468.7 4836.9 4880.5 4944.7 3939.0 4007.1 4056.1 4073.8 4083.2 1113.3 1119.3 1324.2 1327.6 1347.1 824.2 830.4 838.2 838.6 876.6 5198.2 5574.6 5772.7 5828.1 5841.1 971.9 985.3 1009.1 1055.8 1085.2 3526.0 3724.3 3836.0 3861.9 3914.3 8260.3 9883.9 10219.3 10234.4 10243.0 9931.2 9956.3 10083.6 10086.8 10197.8 7883.9 7996.0 7997.0 8005.6 8080.3 5461.9 5502.4 5526.6 5649.6 5682.7 9362.6 9391.6 9465.3 9470.6 9491.2 7524.3 9101.8 9361.9 9631.0 9678.8 6827.6 6953.2 7039.4 7236.7 7315.8 8022.9 8084.2 8101.6 8102.0 8103.5 7085.3 7122.7 7216.9 7252.7 7333.7 7081.2 7188.2 7192.8 7305.2 7337.4 6408.6 6420.9 6467.6 6471.4 6475.1 4082.3 4984.2 5033.3 5072.2 5140.5 4976.3 5023.3 5064.4 5178.6 5207.0 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | [#4] prototype.html Cycles:180 Average:4635.86 Median:5160.09 stddev:1434.63 (27.8%) stddev-sans-first:1402.04 05:43:40 INFO - PROCESS | 1919 | Values: 345.3 364.4 379.3 382.0 394.1 4899.4 4906.8 5587.4 5594.5 5613.0 4245.4 4355.1 5094.7 5164.7 5201.3 3771.5 3994.7 4122.9 4366.4 4393.8 3856.3 4275.9 4284.3 4312.0 4338.7 3508.4 4245.9 4319.8 4322.4 4331.9 4033.4 4318.5 4335.7 4347.5 4364.2 3125.7 3382.3 3567.8 3627.5 3810.7 4604.4 5197.8 5363.2 5379.7 5379.8 4380.8 4804.6 4804.7 4847.0 4852.3 4730.0 4744.0 5355.3 5861.6 5911.4 3440.4 3912.2 3963.5 3973.8 4006.6 4806.0 5104.4 5487.8 5777.8 6007.5 3945.3 3956.4 3991.3 3996.8 4016.6 3429.5 4056.5 4066.0 4071.7 4078.5 3635.8 4140.6 4178.6 4719.3 4794.3 5744.7 5850.0 5850.1 5880.8 5897.4 1325.4 1325.6 1329.3 1333.8 1336.5 812.5 839.5 893.8 898.4 910.7 5154.5 5155.5 5177.3 5198.2 5221.2 5169.0 5363.2 5395.5 5749.2 5794.2 5063.3 5171.7 5172.9 5178.3 5414.5 2413.7 2689.9 2799.5 3023.5 3026.0 5691.2 5845.8 5859.8 5884.2 5908.6 4376.5 5558.4 6068.0 6147.3 6158.8 5326.6 6111.3 6111.6 6167.9 6176.4 5425.0 5454.6 5697.8 6040.7 6055.2 5954.5 5996.9 6005.5 6027.3 6063.3 4783.2 5767.5 5889.2 6124.6 6142.4 3174.3 3976.5 4021.3 4048.4 4064.2 5028.6 5113.7 6008.3 6020.9 6036.6 5065.8 5366.8 5752.6 5876.9 5897.4 5155.4 5863.1 5870.4 5905.6 5923.4 4948.9 5185.2 5690.8 5717.2 5728.3 4845.4 5751.2 5847.8 5867.9 5888.3 5738.6 5750.9 5760.6 5760.8 5764.7 05:43:40 INFO - PROCESS | 1919 | 05:43:40 INFO - PROCESS | 1919 | [#5] yui.html Cycles:180 Average:19725.41 Median:3648.94 stddev:27404.30 (751.0%) stddev-sans-first:27443.73 05:43:40 INFO - PROCESS | 1919 | Values: 646.6 713.4 787.2 832.4 835.5 15247.9 16102.8 16379.3 16413.5 16419.4 14837.8 14995.6 15004.2 15025.2 15043.0 679.9 712.2 731.3 865.2 873.4 872.2 884.1 885.4 887.5 889.8 955.9 957.8 959.0 959.2 962.2 787.8 949.0 951.6 956.2 957.1 713.8 713.8 714.7 715.9 716.4 14087.4 14241.9 15590.0 15677.5 16405.8 13053.3 14771.7 14989.5 14996.9 15172.2 11001.0 11664.2 11849.8 11849.9 11968.2 3524.9 3602.5 3647.7 3650.2 3795.6 68664.0 98143.0 100557.0 101281.0 102130.0 10976.8 10997.3 11104.1 11127.2 11248.7 22130.1 22382.7 22557.2 22571.8 22574.7 7918.4 7949.2 7977.1 8019.6 8077.5 4444.7 4457.8 4477.5 4481.8 4505.0 3206.5 3690.1 3731.3 3780.1 3783.4 673.9 675.6 680.5 696.0 696.2 650.3 673.2 675.9 685.1 692.8 10398.4 10422.2 10461.2 10754.2 10845.3 561.3 690.1 717.8 722.6 729.1 4938.0 5150.2 5168.1 5175.2 5179.5 90187.6 103368.0 104627.0 105440.0 107948.0 55993.2 67702.3 74259.6 81798.4 90274.2 33230.8 46040.0 46054.0 77968.2 80481.2 7670.9 9328.8 10599.2 11105.3 11338.4 35985.4 45080.0 46032.0 52237.7 52922.7 71988.6 79293.0 83444.6 84655.2 84675.2 14229.5 14368.1 14726.9 15222.9 15535.5 15392.2 15521.7 15556.5 15650.8 15665.3 14196.4 14333.0 14482.0 14650.5 14711.0 14838.3 14885.8 14898.1 15124.8 15268.2 13840.4 14362.7 14455.7 14462.9 14729.0 10836.1 11163.6 11285.3 11470.5 11497.2 10507.6 11329.8 11724.5 12030.8 12155.9 05:43:40 INFO - PROCESS | 1919 | -------- Summary: end -------- 05:43:40 INFO - PROCESS | 1919 | 05:43:41 INFO - TEST-INFO | 1919: exit 0 05:43:41 INFO - TEST-OK | dromaeo_css | took 1115034ms 05:43:41 INFO - TEST-START | kraken 05:43:41 INFO - operating with platform_type : mac_ 05:43:41 INFO - Initialising browser for kraken test... 05:43:41 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpUGaYwo/profile http://localhost:52155/getInfo.html) 05:43:44 INFO - PROCESS | 1926 | 2017-03-09 05:43:44.100 plugin-container[1928:17593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 05:43:44 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:44 INFO - PROCESS | 1926 | 2017-03-09 05:43:44.100 plugin-container[1928:17593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 05:43:44 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:44 INFO - PROCESS | 1926 | 2017-03-09 05:43:44.100 plugin-container[1928:17593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:43:44 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 05:43:44 INFO - PROCESS | 1926 | colorDepth:24 05:43:44 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 05:43:44 INFO - PROCESS | 1926 | __metrics 05:43:44 ERROR - PROCESS | 1926 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:43:44 INFO - TEST-INFO | 1926: exit 0 05:43:44 INFO - Browser initialized. 05:43:44 INFO - Running cycle 1/1 for kraken test... 05:43:44 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpUGaYwo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:47 INFO - PROCESS | 1929 | 2017-03-09 05:43:47.101 plugin-container[1930:17723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 05:43:47 INFO - PROCESS | 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:47 INFO - PROCESS | 1929 | 2017-03-09 05:43:47.102 plugin-container[1930:17723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 05:43:47 INFO - PROCESS | 1929 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:47 INFO - PROCESS | 1929 | 2017-03-09 05:43:47.102 plugin-container[1930:17723] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:43:56 INFO - PROCESS | 1929 | RSS: Main: 172548096 05:43:56 INFO - PROCESS | 1929 | 05:43:59 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52155/tests/kraken/driver.html?testName=audio-beat-detection) 05:44:00 INFO - PROCESS | 1929 | RSS: Main: 185561088 05:44:00 INFO - PROCESS | 1929 | 05:44:03 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52155/tests/kraken/driver.html?testName=audio-dft) 05:44:04 INFO - PROCESS | 1929 | RSS: Main: 193646592 05:44:04 INFO - PROCESS | 1929 | 05:44:07 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52155/tests/kraken/driver.html?testName=audio-fft) 05:44:08 INFO - PROCESS | 1929 | RSS: Main: 197644288 05:44:08 INFO - PROCESS | 1929 | 05:44:10 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52155/tests/kraken/driver.html?testName=audio-oscillator) 05:44:10 INFO - PROCESS | 1929 | RSS: Main: 198721536 05:44:10 INFO - PROCESS | 1929 | 05:44:12 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52155/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:44:13 INFO - PROCESS | 1929 | RSS: Main: 198742016 05:44:13 INFO - PROCESS | 1929 | 05:44:15 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52155/tests/kraken/driver.html?testName=imaging-darkroom) 05:44:16 INFO - PROCESS | 1929 | RSS: Main: 203530240 05:44:16 INFO - PROCESS | 1929 | 05:44:19 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52155/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:20 INFO - PROCESS | 1929 | RSS: Main: 203694080 05:44:20 INFO - PROCESS | 1929 | 05:44:23 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52155/tests/kraken/driver.html?testName=json-parse-financial) 05:44:23 INFO - PROCESS | 1929 | RSS: Main: 203075584 05:44:23 INFO - PROCESS | 1929 | 05:44:25 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52155/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:26 INFO - PROCESS | 1929 | RSS: Main: 202149888 05:44:26 INFO - PROCESS | 1929 | 05:44:28 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:28 INFO - PROCESS | 1929 | RSS: Main: 202174464 05:44:28 INFO - PROCESS | 1929 | 05:44:30 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:44:31 INFO - PROCESS | 1929 | RSS: Main: 202072064 05:44:31 INFO - PROCESS | 1929 | 05:44:34 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:35 INFO - PROCESS | 1929 | RSS: Main: 202514432 05:44:35 INFO - PROCESS | 1929 | 05:44:38 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:38 INFO - PROCESS | 1929 | RSS: Main: 202407936 05:44:38 INFO - PROCESS | 1929 | 05:44:40 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:52155/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52155/tests/kraken/driver.html?testName=ai-astar) 05:44:41 INFO - PROCESS | 1929 | RSS: Main: 204140544 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | __start_tp_report 05:44:41 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 05:44:41 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 05:44:41 INFO - PROCESS | 1929 | |i|pagename|runs| 05:44:41 INFO - PROCESS | 1929 | |0;ai-astar;106;119;117;98;164;177;154;118;119;118 05:44:41 INFO - PROCESS | 1929 | |1;audio-beat-detection;133;130;140;181;143;112;112;111;133;119 05:44:41 INFO - PROCESS | 1929 | |2;audio-dft;197;197;191;271;275;203;198;200;195;200 05:44:41 INFO - PROCESS | 1929 | |3;audio-fft;82;57;59;88;64;58;60;58;71;59 05:44:41 INFO - PROCESS | 1929 | |4;audio-oscillator;89;70;85;73;70;72;79;71;73;78 05:44:41 INFO - PROCESS | 1929 | |5;imaging-gaussian-blur;90;87;89;87;90;86;104;88;88;87 05:44:41 INFO - PROCESS | 1929 | |6;imaging-darkroom;95;95;103;96;109;151;151;95;96;98 05:44:41 INFO - PROCESS | 1929 | |7;imaging-desaturate;114;146;90;91;95;91;122;102;94;94 05:44:41 INFO - PROCESS | 1929 | |8;json-parse-financial;58;58;57;59;59;62;62;61;62;60 05:44:41 INFO - PROCESS | 1929 | |9;json-stringify-tinderbox;69;95;60;91;113;102;103;86;70;70 05:44:41 INFO - PROCESS | 1929 | |10;stanford-crypto-aes;84;82;85;87;93;72;73;71;73;94 05:44:41 INFO - PROCESS | 1929 | |11;stanford-crypto-ccm;154;151;137;176;136;153;159;163;169;124 05:44:41 INFO - PROCESS | 1929 | |12;stanford-crypto-pbkdf2;180;149;232;176;170;162;169;186;228;181 05:44:41 INFO - PROCESS | 1929 | |13;stanford-crypto-sha256-iterative;60;65;91;88;85;85;89;71;65;68 05:44:41 INFO - PROCESS | 1929 | __end_tp_report 05:44:41 INFO - PROCESS | 1929 | __start_cc_report 05:44:41 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,939 05:44:41 INFO - PROCESS | 1929 | __end_cc_report 05:44:41 INFO - PROCESS | 1929 | __startTimestamp1489067081015__endTimestamp 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | ------- Summary: start ------- 05:44:41 INFO - PROCESS | 1929 | Number of tests: 14 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#0] ai-astar Cycles:10 Average:129.00 Median:136.50 stddev:26.31 (19.3%) stddev-sans-first:26.56 05:44:41 INFO - PROCESS | 1929 | Values: 106.0 119.0 117.0 98.0 164.0 177.0 154.0 118.0 119.0 118.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:133.00 stddev:21.08 (15.8%) stddev-sans-first:22.35 05:44:41 INFO - PROCESS | 1929 | Values: 133.0 130.0 140.0 181.0 143.0 112.0 112.0 111.0 133.0 119.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#2] audio-dft Cycles:10 Average:212.70 Median:200.00 stddev:31.96 (16.0%) stddev-sans-first:33.38 05:44:41 INFO - PROCESS | 1929 | Values: 197.0 197.0 191.0 271.0 275.0 203.0 198.0 200.0 195.0 200.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#3] audio-fft Cycles:10 Average:65.60 Median:62.00 stddev:11.11 (17.9%) stddev-sans-first:10.07 05:44:41 INFO - PROCESS | 1929 | Values: 82.0 57.0 59.0 88.0 64.0 58.0 60.0 58.0 71.0 59.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:75.50 stddev:6.62 (8.8%) stddev-sans-first:5.08 05:44:41 INFO - PROCESS | 1929 | Values: 89.0 70.0 85.0 73.0 70.0 72.0 79.0 71.0 73.0 78.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:88.00 stddev:5.23 (5.9%) stddev-sans-first:5.55 05:44:41 INFO - PROCESS | 1929 | Values: 90.0 87.0 89.0 87.0 90.0 86.0 104.0 88.0 88.0 87.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:95.00 stddev:22.63 (23.8%) stddev-sans-first:23.44 05:44:41 INFO - PROCESS | 1929 | Values: 95.0 95.0 103.0 96.0 109.0 151.0 151.0 95.0 96.0 98.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#7] imaging-desaturate Cycles:10 Average:103.90 Median:91.00 stddev:18.28 (20.1%) stddev-sans-first:19.02 05:44:41 INFO - PROCESS | 1929 | Values: 114.0 146.0 90.0 91.0 95.0 91.0 122.0 102.0 94.0 94.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:60.50 stddev:1.87 (3.1%) stddev-sans-first:1.87 05:44:41 INFO - PROCESS | 1929 | Values: 58.0 58.0 57.0 59.0 59.0 62.0 62.0 61.0 62.0 60.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#9] json-stringify-tinderbox Cycles:10 Average:85.90 Median:70.00 stddev:17.82 (25.5%) stddev-sans-first:17.82 05:44:41 INFO - PROCESS | 1929 | Values: 69.0 95.0 60.0 91.0 113.0 102.0 103.0 86.0 70.0 70.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:84.50 stddev:8.71 (10.3%) stddev-sans-first:9.18 05:44:41 INFO - PROCESS | 1929 | Values: 84.0 82.0 85.0 87.0 93.0 72.0 73.0 71.0 73.0 94.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:156.50 stddev:16.01 (10.2%) stddev-sans-first:16.96 05:44:41 INFO - PROCESS | 1929 | Values: 154.0 151.0 137.0 176.0 136.0 153.0 159.0 163.0 169.0 124.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.30 Median:180.50 stddev:26.79 (14.8%) stddev-sans-first:28.39 05:44:41 INFO - PROCESS | 1929 | Values: 180.0 149.0 232.0 176.0 170.0 162.0 169.0 186.0 228.0 181.0 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - PROCESS | 1929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:85.00 stddev:11.94 (14.0%) stddev-sans-first:11.02 05:44:41 INFO - PROCESS | 1929 | Values: 60.0 65.0 91.0 88.0 85.0 85.0 89.0 71.0 65.0 68.0 05:44:41 INFO - PROCESS | 1929 | -------- Summary: end -------- 05:44:41 INFO - PROCESS | 1929 | 05:44:41 INFO - TEST-INFO | 1929: exit 0 05:44:41 INFO - TEST-OK | kraken | took 60399ms 05:44:41 INFO - SUITE-END | took 1175s 05:44:42 INFO - Completed test suite (00:19:35) 05:44:42 INFO - javascript benchmark 05:44:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7471.934241865461, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3496.0560648624887, 3505.9123521911947, 3523.700485458736, 3537.699295559877, 3541.220931395094, 5974.940250597493, 5976.3724808895095, 6031.3968603139665, 6039.124326972592, 6058.030715085587, 4666.603338716213, 6038.215032045839, 6123.755049798003, 6144.477719393845, 6153.230846144232, 5422.2951016367915, 5464.562834973192, 5470.288862447876, 5515.338159420875, 5572.0, 5526.778928842841, 5549.084401073818, 5560.277163968678, 5562.360328562207, 5565.30433695788, 5404.4595540446035, 5409.404965453797, 5523.6409633373705, 5544.417836127209, 5549.223108764756, 5437.075697131478, 5458.4541545845295, 5505.944940550583, 5528.889422211533, 5556.083246264361, 4582.243929751587, 5301.416844147141, 5371.409144994048, 5443.346798384179, 5485.01269771441, 5109.259157422191, 5957.642541447527, 6115.969420152871, 6141.815745527641, 6176.87646247077, 5433.212184233264, 6210.596311239755, 6255.499560035232, 6260.561760676755, 6305.59013664115, 6481.527596545574, 6496.220453545558, 6522.706478208492, 6540.6729663516635, 6570.310117437696, 5092.465291144376, 6126.448619624255, 6144.446999770043, 6151.384861513858, 6193.194884664965, 6366.6180029198395, 6368.777092801823, 6495.512836537278, 6518.315576864456, 6547.247066587403, 5219.947800522022, 6214.937850621526, 6253.499720022387, 6294.748210071546, 6295.464885484694, 6412.9679351602945, 6424.229092508929, 6443.130177426081, 6453.838654033687, 6462.3537646235, 6180.443760061616, 6210.937890621036, 6218.595791273553, 6227.159333490012, 6308.0, 6299.748010079637, 6324.778632747832, 6328.588641738273, 6346.0, 6357.554971151975, 5196.350456192976, 5198.818041368534, 5209.661372010807, 5213.2701421800975, 5221.843344699665, 4605.953940460619, 4607.216773148572, 4610.331501932201, 4658.836940707127, 4692.0381321828945, 5128.8461346159675, 6060.0, 6060.909086363708, 6090.147379366892, 6168.290646575701, 6465.3857883501, 6482.740690372332, 6494.383033611799, 6501.122348482989, 6511.967440162864, 6002.159697642333, 6026.246719160105, 6067.75728970845, 6102.664353460602, 6128.662923539248, 5721.914171287434, 5760.74076666551, 5765.596408251466, 5793.94206057934, 5806.071028635398, 6536.4117229447675, 6554.115194448784, 6559.212894452506, 6572.178477690289, 6578.473722102125, 6216.285127210429, 6262.0, 6291.433770960635, 6345.238571371465, 6464.903026454513, 6430.260520040255, 6436.841368553518, 6444.291127976013, 6447.548671592944, 6467.805965821033, 6306.621602703853, 6307.3377295383325, 6314.336994615499, 6335.746570137143, 6350.206224221973, 6120.843333897766, 6242.719077641426, 6297.685115744102, 6385.584936979268, 6393.520485963664, 6128.992047069418, 6174.6912654368, 6212.099245609452, 6217.96891015542, 6225.408586184305, 4863.805447782049, 6221.035739460391, 6228.719707613078, 6229.221347331583, 6258.405451482102, 6100.359462256577, 6156.168917196301, 6197.938020619736, 6201.348858369806, 6248.593841400295, 6157.815265542041, 6158.907616385668, 6191.81424557264, 6234.189555357774, 6258.3428739983465, 6190.535709821871, 6202.596831205957, 6247.718852651551, 6251.281102673251, 6265.122882796503], "value": 5882.71227744573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.63577317012243, 514.8544488513058, 520.5783315514434, 524.4074196158341, 556.3907521264214, 39586.0, 42320.576794232045, 42441.15117697644, 42502.72491825246, 42503.937401564974, 43533.782331088376, 43757.0, 44236.0, 44266.77866610663, 44347.77826110865, 9508.191803696694, 9766.755831104208, 9781.853272200922, 11587.65237042886, 12014.339211343373, 11814.409279536032, 11865.16943813929, 11899.0, 11906.642800715992, 11924.403779811018, 11761.941190294037, 11877.0, 11886.346250956194, 11931.403429828473, 11950.342731149827, 11743.295402275891, 11753.471093800757, 11800.881991180064, 11833.644990650295, 11904.464299106561, 11854.822177667342, 11871.228370155912, 11948.641540753792, 11986.340751258676, 11986.460609272604, 38798.612013879785, 38875.80562097171, 39030.219395612214, 39182.0, 39192.02019949495, 4017.5762769799517, 4088.5911408859174, 4112.0, 4222.0, 4338.284183109783, 28196.0, 28306.57540136879, 28684.569731454045, 29012.70987290121, 29054.564181537295, 3337.0823023668445, 3425.3149370126075, 3513.3149035938004, 3518.138056176253, 3527.576690797095, 502650.0, 505058.0, 507166.0, 508145.0, 509365.0, 59050.0, 59085.70457147773, 59419.40580594139, 59515.0, 59658.10512842311, 60218.0, 61521.0, 61925.690371547855, 62032.0, 62215.688921555105, 16881.987080775194, 16978.830211697972, 17094.658106837793, 17126.40057598003, 17182.484525464257, 12118.939405303094, 12427.31649759272, 12921.418536165895, 13179.472821087242, 13221.933890330487, 18693.532661683566, 18750.437486875417, 18784.0, 18907.621847562972, 18928.242870285034, 6637.900431493532, 8289.295409890106, 8341.41610087288, 8345.874811877828, 8367.497950123012, 885.4333226734894, 899.6131663384676, 909.5861383070687, 921.7833809054866, 926.0970553710079, 30176.09471715852, 31075.378492430023, 31209.21976950549, 31696.0, 31819.681803182135, 874.4534665833854, 970.7136394763562, 1066.4642914203678, 1071.6302875507938, 1256.7432567432568, 11923.946177601967, 14698.926505367404, 14715.043522171025, 14735.336909839083, 14836.3323650436, 619589.0, 619892.0, 621200.0, 624605.0, 627278.0, 53697.92604147895, 53788.73105634447, 54196.916061678545, 54331.0, 54421.45578544164, 1182.9231099978474, 1449.3333066789185, 1483.7032593481347, 1490.8658220759967, 1499.1604701367269, 24063.759362406152, 26935.86532067327, 27603.30991725223, 27648.308792280353, 28567.714322856504, 38003.0, 47356.0, 47384.526154738014, 47623.52376476191, 48046.75976620234, 596199.0, 597479.0, 598948.0, 600217.0, 600619.0, 21266.83032433231, 25208.0, 25410.0, 25649.717514123848, 26181.869090654425, 32078.35843283168, 38649.6135038646, 42979.78510107555, 43024.0, 43493.0, 33475.50099151945, 34699.4795078069, 36129.458058129676, 36888.52445902134, 37251.44122838105, 35529.28941421105, 35655.82172089123, 35718.107047322985, 36658.0, 36929.07677308089, 34563.963081107606, 35581.64418355783, 35722.28555428929, 35891.46162807508, 35968.0, 29580.0, 31098.844505777328, 31516.0, 31555.36889262248, 31628.0, 22978.42553936165, 23976.640350395108, 24065.954393876716, 24151.0, 24330.323664397256], "value": 20202.152749265348, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.6758941462706, 406.11669618579583, 411.39730295117647, 412.8162967479472, 432.32341385731326, 20393.898030509845, 21048.89475552622, 21811.127554897805, 22026.33920982369, 22372.552548949054, 22239.44401389962, 22305.55388892221, 22664.660030099563, 22710.88644556779, 22928.770712292873, 5536.529395001429, 6070.2412198475195, 6148.877022459548, 6151.90772138416, 6152.261728592553, 5803.854903627422, 5934.792282270121, 6035.5473339499495, 6052.697365131748, 6064.514838812906, 5961.5230781537375, 6015.669138197398, 6023.30731965823, 6025.969870150665, 6038.486728628071, 5254.369475662907, 5826.504747096481, 5999.800039991997, 6032.849178770522, 6070.150178974947, 5186.170212765939, 5239.816606418757, 5340.973295133538, 5342.038433082043, 5343.251944727741, 26194.0, 26194.73805261942, 26785.0, 26989.86505067462, 27073.1878043659, 2805.7474827265646, 2831.9716802831913, 2833.702461241581, 2851.586519954595, 2871.296532349567, 15370.615734606612, 15994.600134996717, 16025.439109631223, 16090.75863862043, 16626.418075367303, 1879.6522643311032, 1889.7070954002152, 1894.4222012286248, 1901.077977181066, 1923.8941858197793, 122165.0, 122297.0, 123058.69413058623, 132158.6784132156, 132650.0, 10637.414942178255, 11800.70498237551, 11813.291202527877, 11829.290242585472, 11968.10239232061, 15033.248337583078, 15159.545213643609, 15210.619734506727, 15295.541133766004, 15324.616884577974, 9087.318451116193, 9279.67521136757, 9286.860697089549, 9421.104995025598, 9477.289203309641, 9198.264138868875, 9234.984151743303, 9292.488913109844, 9346.859797103049, 9459.76350591227, 6107.389261073946, 6442.387973142544, 6818.590884546943, 7567.470277080552, 7595.392368610498, 2007.628588711093, 2025.797420257992, 2025.9797402026086, 2030.5735795483115, 2037.4193354893785, 895.9283257339397, 915.8031023330016, 916.8808054952814, 921.9492927888896, 938.1790932933683, 21164.808178015766, 21178.682319765216, 22532.21137260188, 22558.774412255996, 22580.887095564416, 828.9472370089952, 882.9514376709343, 944.6976967370513, 962.4225464721251, 987.7603607472576, 11050.115990720724, 11774.764504710029, 11777.470013849397, 11788.46951887167, 11805.409729513662, 683581.0, 687442.0, 689084.0, 699095.0, 700529.0, 96962.0, 116701.0, 123548.0, 125516.0, 132004.33997829948, 1022.892596277395, 1064.7870425914693, 1065.6536625596711, 1065.706930594093, 1076.0638244726981, 17169.227384767466, 19458.513537161118, 19700.01499924969, 20407.8979605101, 21850.890745546807, 56925.0, 57176.98447206428, 57335.713321433126, 57810.13284800647, 58023.83952321015, 80202.59898700469, 89785.55107224423, 92386.0, 100439.0, 103989.0, 7531.774046778605, 7667.808304792425, 7870.9606451967375, 8317.376196785144, 8873.423227490193, 9308.069193080637, 12688.74622507563, 12691.238525688488, 13384.598462046155, 14277.500287489887, 5709.714514274353, 6159.876802464015, 11592.95663390299, 11770.58802941893, 11865.11011674145, 11626.418679065844, 11651.650450486499, 11675.766484670428, 11843.526258949545, 11846.644600661994, 11104.722381940515, 11316.547338106713, 11910.34493102853, 11930.701732456759, 12051.156419050581, 8661.956690216508, 8686.91313086861, 8875.556222188736, 8894.4663320201, 8913.108689131295, 8662.956685216534, 11193.432919391129, 11600.6519804406, 11633.709157271136, 11894.881051189377], "value": 10207.212123185696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.7052219034003, 1452.8837692984562, 1503.4888138033066, 1504.0749938787635, 1509.728248915195, 7714.691412343514, 7980.9201907980905, 8264.421490495668, 8683.565821708913, 8704.911886014248, 8152.470089444197, 8475.830483390344, 8480.78798030048, 8546.273566746833, 8553.187447192522, 2382.29722231942, 2421.8546887186735, 2424.8787560621986, 2425.248173066355, 2427.2718184544656, 2197.241951526729, 2357.750392292088, 2406.1698713943747, 2445.6942882139733, 2452.288836237489, 2237.8321625878125, 2242.1703969531295, 2259.20927675314, 2273.27255278311, 2283.7830406111475, 2359.528094381122, 2366.751491093428, 2389.462370966536, 2393.365758074112, 2397.4485868250226, 1984.345166095185, 2329.9883500582387, 2391.9162829301067, 2401.183597576815, 2420.056178090546, 8325.167483251687, 8336.333093352518, 8386.161383861627, 8406.747797566022, 8446.0, 5172.663776854492, 5174.353205849269, 5267.104592219293, 5325.440828713007, 5329.573634109262, 3408.659134086596, 3548.9112772180642, 3658.6707196352454, 3668.486411902336, 3673.412254039368, 1183.449695891406, 1194.5401020607128, 1210.2919791921736, 1301.1867582760774, 1330.3614265152685, 10903.672889813319, 10909.509072091774, 10954.068904143158, 10974.451277436096, 10975.945120274428, 4462.531434199427, 4495.786137742856, 4771.832985845479, 4842.757862106881, 4844.861457557471, 8506.149385061444, 9498.667546635834, 9546.236301095896, 9577.23382129428, 9583.616655333848, 5265.210218467184, 5321.228421878806, 5354.170103633943, 5360.276362691065, 5373.650712703662, 4452.332150177499, 4468.687191896601, 4836.879078023007, 4880.463149053601, 4944.653874228841, 3938.980305098456, 4007.0783719744622, 4056.0671045659665, 4073.7555746655294, 4083.1629515949157, 1113.3431275547466, 1119.2612875502293, 1324.1525423728822, 1327.6349004023778, 1347.0974447120452, 824.1593574554041, 830.4353039932782, 838.2071128145192, 838.6058552480324, 876.6011464783509, 5198.194279886624, 5574.554035677217, 5772.7113644317615, 5828.125781132864, 5841.065429531254, 971.9271054670928, 985.2856678907877, 1009.106940357774, 1055.7888422315568, 1085.1644233939821, 3526.0, 3724.329620668306, 3835.980820095882, 3861.942070868939, 3914.3345631242755, 8260.339172866155, 9883.901160988298, 10219.284650074424, 10234.4136658818, 10243.026912443305, 9931.205503559699, 9956.303058785961, 10083.59665613382, 10086.848697269546, 10197.847032294521, 7883.881741773878, 7996.0, 7996.960015200003, 8005.559694216758, 8080.2727754502375, 5461.890762184655, 5502.422245664147, 5526.640768350045, 5649.559362362571, 5682.659040457585, 9362.625494980124, 9391.624335026523, 9465.290103242147, 9470.621175152917, 9491.240700743785, 7524.2851929066655, 9101.77245568866, 9361.906380936376, 9631.0, 9678.806423871341, 6827.556208846409, 6953.1656201254145, 7039.366457018893, 7236.674349654383, 7315.817104572428, 8022.879656805036, 8084.231997960184, 8101.635426405708, 8101.959490202511, 8103.473274237156, 7085.256048114874, 7122.679479423335, 7216.9278307216255, 7252.673629686571, 7333.706651733871, 7081.185663648746, 7188.245234250328, 7192.820179495555, 7305.196428392766, 7337.413006959324, 6408.58344207625, 6420.871582568416, 6467.611943283418, 6471.417572418317, 6475.061116138228, 4082.265192265421, 4984.215153452581, 5033.345665063518, 5072.239164125411, 5140.46025167352, 4976.278439626306, 5023.296738456692, 5064.41759197697, 5178.585713142864, 5207.0366982108435], "value": 4481.826909357041, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.30421201279427, 364.42057129164624, 379.2812620084941, 381.96180381961807, 394.1368403197, 4899.412070551539, 4906.846890980617, 5587.413321601234, 5594.524465420434, 5612.971935140318, 4245.426867372904, 4355.1038559357985, 5094.694318340893, 5164.715940623274, 5201.271821944922, 3771.528558930134, 3994.7203695741237, 4122.9381559276635, 4366.388705581206, 4393.780310984438, 3856.267309211255, 4275.87172384829, 4284.2930916398755, 4312.0, 4338.739675619473, 3508.4035713928565, 4245.936310955338, 4319.76241306728, 4322.351647252922, 4331.8700438986725, 4033.3546632538655, 4318.524962254165, 4335.6748243881675, 4347.500037495697, 4364.214441400547, 3125.749940004817, 3382.3404436134965, 3567.8216089195435, 3627.455881617752, 3810.733248672594, 4604.401427814362, 5197.818076367347, 5363.249145119687, 5379.65032272901, 5379.784808607651, 4380.759058251795, 4804.5916097131785, 4804.702730262809, 4847.0, 4852.320675105488, 4729.9763501182615, 4743.976280118612, 5355.2770375999135, 5861.648301101947, 5911.379305172938, 3440.4174079923264, 3912.1784425270444, 3963.4847469829024, 3973.781442020688, 4006.559278479365, 4805.951940480585, 5104.412992505872, 5487.780488780484, 5777.757782076832, 6007.519398448114, 3945.2504024235877, 3956.40653901917, 3991.341428664296, 3996.780177090288, 4016.5983401660183, 3429.4855771633956, 4056.472658554428, 4066.0, 4071.7353372030725, 4078.490188726409, 3635.781853088823, 4140.648044916155, 4178.603032711888, 4719.339292499053, 4794.304825800239, 5744.655320680772, 5849.970750146222, 5850.063989761617, 5880.794172203952, 5897.380775018647, 1325.357201757147, 1325.5824415309146, 1329.3286890120428, 1333.8466076401144, 1336.4787732784223, 812.4556547113448, 839.5004972041626, 893.7586851550175, 898.4070513461085, 910.7267819654078, 5154.505193493845, 5155.458676838878, 5177.301064356339, 5198.246254293106, 5221.216817477332, 5168.9741551292, 5363.222332761728, 5395.541378982752, 5749.1663708762835, 5794.246747922743, 5063.265826455144, 5171.689698618095, 5172.8965420691375, 5178.300929374486, 5414.458554144633, 2413.7448526766334, 2689.865506724656, 2799.4820958122405, 3023.470892593787, 3025.969740302613, 5691.231683722727, 5845.837040295118, 5859.794907178229, 5884.204687976317, 5908.615939963888, 4376.49670287921, 5558.360788509373, 6067.969660151671, 6147.293061297919, 6158.815235542941, 5326.573874089986, 6111.297200821962, 6111.602745821508, 6167.876642467215, 6176.35148309421, 5425.023495770642, 5454.645448046023, 5697.829065128053, 6040.728167232573, 6055.212822333068, 5954.523638109027, 5996.850078748066, 6005.519558435402, 6027.276726792813, 6063.302720187235, 4783.230204824237, 5767.538596912321, 5889.204957330706, 6124.6325220486915, 6142.447179753844, 3174.3016536361965, 3976.4830572025453, 4021.3163762159916, 4048.3725022621543, 4064.1668457965457, 5028.622853285945, 5113.667611605234, 6008.339082700987, 6020.939790602038, 6036.555195145973, 5065.797368105234, 5366.838994830161, 5752.568557358131, 5876.882462350814, 5897.373115534002, 5155.381354237516, 5863.061910094535, 5870.412958704095, 5905.586608937333, 5923.378045305181, 4948.925766113583, 5185.248139019745, 5690.77236910519, 5717.1995920570325, 5728.341240757366, 4845.370101886873, 5751.194832723339, 5847.830433913149, 5867.887560478139, 5888.293404791452, 5738.569607279387, 5750.942490575041, 5760.596758227051, 5760.798372056958, 5764.7188027275215], "value": 4193.596281645198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.5964704137625, 713.4256923176844, 787.1577412168978, 832.3893266529266, 835.4778263585257, 15247.923760381196, 16102.838971610281, 16379.344826206967, 16413.50759477216, 16419.425320113798, 14837.777433338508, 14995.625109372271, 15004.174770387624, 15025.173615451145, 15043.0, 679.911611490508, 712.2130046298843, 731.2723839779427, 865.208334374047, 873.4191762477944, 872.1845074854999, 884.1203003012012, 885.402353411448, 887.5163036145316, 889.799795046116, 955.9187469065138, 957.8467445208768, 958.9856152157723, 959.1655259923876, 962.210986990668, 787.763670898728, 949.0367277213634, 951.5908159491416, 956.2350119904049, 957.1098878043417, 713.8001359619315, 713.846522997558, 714.674822955554, 715.9212486626467, 716.3982254905904, 14087.36606852694, 14241.931855110804, 15590.0, 15677.451289204822, 16405.753913691184, 13053.347332633426, 14771.704565908729, 14989.475368362055, 14996.925015374962, 15172.24138793067, 11001.01981643304, 11664.183507154503, 11849.822252666218, 11849.940750296193, 11968.162228643998, 3524.8942531724088, 3602.4776407420777, 3647.671709546127, 3650.215203731184, 3795.6394142556414, 68664.0, 98143.0, 100556.99443005549, 101280.9871901279, 102130.0, 10976.835347469794, 10997.285176463503, 11104.114710125867, 11127.221094523467, 11248.718782030515, 22130.114795408004, 22382.664260036112, 22557.210497632503, 22571.774282257295, 22574.661380079313, 7918.406119541058, 7949.244821741925, 7977.122516523178, 8019.599020048974, 8077.515349079074, 4444.73331600105, 4457.7548234846745, 4477.538047191356, 4481.843135490242, 4504.954950450519, 3206.486962086102, 3690.0590349460717, 3731.272401881607, 3780.11167375672, 3783.4135708965155, 673.865226954611, 675.5608854244682, 680.4862328941617, 695.982600434983, 696.1889398850384, 650.3366566102643, 673.2022553274386, 675.9019942108366, 685.1025157044288, 692.8256605054485, 10398.376097434179, 10422.218333624856, 10461.163106951426, 10754.247202695737, 10845.295055821347, 561.2927711084063, 690.1496818141045, 717.7774889784183, 722.598957578549, 729.1468981291902, 4938.0, 5150.201718733602, 5168.147255702767, 5175.197844334134, 5179.456157103526, 90187.64718529227, 103368.0, 104627.0, 105440.0, 107948.0, 55993.16010259849, 67702.3229767696, 74259.62870185722, 81798.36403271901, 90274.19451610932, 33230.8338458311, 46040.0, 46054.0, 77968.22031779609, 80481.19518804736, 7670.923290767021, 9328.766780830534, 10599.205059620404, 11105.278156919774, 11338.433078345884, 35985.40145985485, 45080.0, 46032.0, 52237.7388113057, 52922.73538632436, 71988.64005679938, 79293.0, 83444.58277708573, 84655.15344846473, 84675.15324846673, 14229.456810697206, 14368.13791172533, 14726.926365368105, 15222.861250898794, 15535.533933981998, 15392.230388480755, 15521.68956620884, 15556.455524056604, 15650.843491564938, 15665.334630792022, 14196.429461428928, 14333.0, 14482.0, 14650.487232946796, 14711.0, 14838.332275047433, 14885.776713349525, 14898.106113633217, 15124.773128403303, 15268.160251186291, 13840.377183026989, 14362.712745745235, 14455.710885782435, 14462.927685361927, 14729.04261223017, 10836.068089234514, 11163.609273675382, 11285.266457680225, 11470.483828227916, 11497.19519633651, 10507.579696812347, 11329.830052549054, 11724.479236963753, 12030.819537706766, 12155.878441215475], "value": 7631.81200900458, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 117.0, 98.0, 164.0, 177.0, 154.0, 118.0, 119.0, 118.0], "value": 129.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 130.0, 140.0, 181.0, 143.0, 112.0, 112.0, 111.0, 133.0, 119.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 191.0, 271.0, 275.0, 203.0, 198.0, 200.0, 195.0, 200.0], "value": 212.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 59.0, 88.0, 64.0, 58.0, 60.0, 58.0, 71.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 85.0, 73.0, 70.0, 72.0, 79.0, 71.0, 73.0, 78.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 90.0, 86.0, 104.0, 88.0, 88.0, 87.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 103.0, 96.0, 109.0, 151.0, 151.0, 95.0, 96.0, 98.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 146.0, 90.0, 91.0, 95.0, 91.0, 122.0, 102.0, 94.0, 94.0], "value": 103.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 59.0, 59.0, 62.0, 62.0, 61.0, 62.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 95.0, 60.0, 91.0, 113.0, 102.0, 103.0, 86.0, 70.0, 70.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 85.0, 87.0, 93.0, 72.0, 73.0, 71.0, 73.0, 94.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 137.0, 176.0, 136.0, 153.0, 159.0, 163.0, 169.0, 124.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 149.0, 232.0, 176.0, 170.0, 162.0, 169.0, 186.0, 228.0, 181.0], "value": 183.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 91.0, 88.0, 85.0, 85.0, 89.0, 71.0, 65.0, 68.0], "value": 76.7, "unit": "score"}]}]} 05:44:42 INFO - Return code: 0 05:44:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:44:42 ERROR - # TBPL SUCCESS # 05:44:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:44:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:44:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:44:42 INFO - 2017-03-09 05:44:42.364 screenresolution[1934:18108] starting screenresolution argv=screenresolution get 05:44:42 INFO - 2017-03-09 05:44:42.367 screenresolution[1934:18108] Display 0: 1600x1200x32@60 05:44:42 INFO - 2017-03-09 05:44:42.373 screenresolution[1935:18111] starting screenresolution argv=screenresolution list 05:44:42 INFO - Available Modes on Display 0 05:44:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:44:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:44:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:44:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:44:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:44:42 INFO - Intel Iris: 05:44:42 INFO - Chipset Model: Intel Iris 05:44:42 INFO - Type: GPU 05:44:42 INFO - Bus: Built-In 05:44:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:44:42 INFO - Vendor: Intel (0x8086) 05:44:42 INFO - Device ID: 0x0a2e 05:44:42 INFO - Revision ID: 0x0009 05:44:42 INFO - Displays: 05:44:42 INFO - Display: 05:44:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:44:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:44:42 INFO - Display Serial Number: 1600x1200 60 05:44:42 INFO - Main Display: Yes 05:44:42 INFO - Mirror: Off 05:44:42 INFO - Online: Yes 05:44:42 INFO - Rotation: Supported 05:44:42 INFO - Return code: 0 05:44:42 INFO - Running post-action listener: _resource_record_post_action 05:44:42 INFO - [mozharness: 2017-03-09 13:44:42.608789Z] Finished run-tests step (success) 05:44:42 INFO - Running post-run listener: _resource_record_post_run 05:44:42 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 1613824; Write bytes: 427054592; Read time: 18; Write time: 1622 05:44:42 INFO - TinderboxPrint: CPU usage
54.1% 05:44:42 INFO - TinderboxPrint: I/O read bytes / time
1,613,824 / 18 05:44:42 INFO - TinderboxPrint: I/O write bytes / time
427,054,592 / 1,622 05:44:42 INFO - TinderboxPrint: CPU idle
2,186.9 (45.9%) 05:44:42 INFO - TinderboxPrint: CPU system
957.9 (20.1%) 05:44:42 INFO - TinderboxPrint: CPU user
1,620.8 (34.0%) 05:44:42 INFO - TinderboxPrint: Swap in / out
123,793,408 / 0 05:44:42 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109672960; Write bytes: 102219776; Read time: 10032; Write time: 195 05:44:42 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1613824; Write bytes: 304358912; Read time: 18; Write time: 1368 05:44:43 INFO - Running post-run listener: _upload_blobber_files 05:44:43 INFO - Blob upload gear active. 05:44:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:43 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:44:43 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:44:43 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:44:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:44 INFO - (blobuploader) - INFO - Done attempting. 05:44:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:44:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:45 INFO - (blobuploader) - INFO - Done attempting. 05:44:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:44:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:44:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:46 INFO - (blobuploader) - INFO - Done attempting. 05:44:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:44:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:48 INFO - (blobuploader) - INFO - Done attempting. 05:44:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:48 INFO - Return code: 0 05:44:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b03db388579c39d298a60a7fc1cf2714c38dd9e7101711591ffc70421e1e508001e60b1dd2d1e26b6f379e7a9948d4f566970a979c8ad865bf2875063e75e09b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49c256f8e6cf92d2d8059ea077399959237b69b8a617332379fe19abddf84ffc399527b7d2e3da4cde10fbf8a4ecd95415bc385845f1a5fff50a42bfaca0accc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5aa87781564d1ab0f6c63b9f6d00da6dd37ae785bb4b55044fc53070e87c3cd5d2b76460dfec9a8f90a74bdf7d626efc9a7f11ed40aaaaead11bf75bb3d24fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9129a9f617a70d5366470a5f39f30ac93b717db472f4fad16384464be17c45ecfa8f0df511d13a814de6f2c77b04b62e32eafbfb6423accc297c8fc89fedc95"} 05:44:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:48 INFO - Contents: 05:44:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b03db388579c39d298a60a7fc1cf2714c38dd9e7101711591ffc70421e1e508001e60b1dd2d1e26b6f379e7a9948d4f566970a979c8ad865bf2875063e75e09b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49c256f8e6cf92d2d8059ea077399959237b69b8a617332379fe19abddf84ffc399527b7d2e3da4cde10fbf8a4ecd95415bc385845f1a5fff50a42bfaca0accc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5aa87781564d1ab0f6c63b9f6d00da6dd37ae785bb4b55044fc53070e87c3cd5d2b76460dfec9a8f90a74bdf7d626efc9a7f11ed40aaaaead11bf75bb3d24fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9129a9f617a70d5366470a5f39f30ac93b717db472f4fad16384464be17c45ecfa8f0df511d13a814de6f2c77b04b62e32eafbfb6423accc297c8fc89fedc95"} 05:44:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:48 INFO - Copying logs to upload dir... 05:44:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.496641 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-09 05:44:48.634049) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 05:44:48.637171) ========= 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.nXlFSKJmnI/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.oWchrrwtWN/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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/Mozilla-Beta/sha512/b03db388579c39d298a60a7fc1cf2714c38dd9e7101711591ffc70421e1e508001e60b1dd2d1e26b6f379e7a9948d4f566970a979c8ad865bf2875063e75e09b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49c256f8e6cf92d2d8059ea077399959237b69b8a617332379fe19abddf84ffc399527b7d2e3da4cde10fbf8a4ecd95415bc385845f1a5fff50a42bfaca0accc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5aa87781564d1ab0f6c63b9f6d00da6dd37ae785bb4b55044fc53070e87c3cd5d2b76460dfec9a8f90a74bdf7d626efc9a7f11ed40aaaaead11bf75bb3d24fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9129a9f617a70d5366470a5f39f30ac93b717db472f4fad16384464be17c45ecfa8f0df511d13a814de6f2c77b04b62e32eafbfb6423accc297c8fc89fedc95"} build_url:https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011327 build_url: 'https://queue.taskcluster.net/v1/task/H2UjSFLOR42GnWetahONug/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b03db388579c39d298a60a7fc1cf2714c38dd9e7101711591ffc70421e1e508001e60b1dd2d1e26b6f379e7a9948d4f566970a979c8ad865bf2875063e75e09b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49c256f8e6cf92d2d8059ea077399959237b69b8a617332379fe19abddf84ffc399527b7d2e3da4cde10fbf8a4ecd95415bc385845f1a5fff50a42bfaca0accc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5aa87781564d1ab0f6c63b9f6d00da6dd37ae785bb4b55044fc53070e87c3cd5d2b76460dfec9a8f90a74bdf7d626efc9a7f11ed40aaaaead11bf75bb3d24fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9129a9f617a70d5366470a5f39f30ac93b717db472f4fad16384464be17c45ecfa8f0df511d13a814de6f2c77b04b62e32eafbfb6423accc297c8fc89fedc95"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 05:44:48.672601) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 05:44:48.672971) ========= 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.nXlFSKJmnI/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.oWchrrwtWN/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.005134 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 05:44:48.721508) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 05:44:48.721845) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 05:44:48.722177) ========= ========= Total master_lag: 0.20 =========