builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0386 starttime: 1488389855.88 results: success (0) buildid: 20170301074622 builduid: cc43dd05af6d4e9ca1e0898fd80fef13 revision: d68639a64412962018e2d516035038216d602f4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:35.877474) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:35.878195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:35.878480) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:35.922710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:35.923228) ========= 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.uyVjPO0jGw/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.agDOlZ0URa/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-01 09:37:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-03-01 09:37:36 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.512351 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:36.462038) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:36.462577) ========= 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.uyVjPO0jGw/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.agDOlZ0URa/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.033527 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:36.521156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 09:37:36.521730) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d68639a64412962018e2d516035038216d602f4d --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-aurora --rev d68639a64412962018e2d516035038216d602f4d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uyVjPO0jGw/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.agDOlZ0URa/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-01 09:37:36,574 truncating revision to first 12 chars 2017-03-01 09:37:36,575 Setting DEBUG logging. 2017-03-01 09:37:36,575 attempt 1/10 2017-03-01 09:37:36,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d68639a64412?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-01 09:37:38,214 unpacking tar archive at: mozilla-aurora-d68639a64412/testing/mozharness/ program finished with exit code 0 elapsedTime=1.920154 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-01 09:37:38.466623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:38.470079) ========= script_repo_revision: d68639a64412962018e2d516035038216d602f4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:38.470600) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:38.470889) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 09:37:38.491247) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-01 09:37:38.491667) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uyVjPO0jGw/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.agDOlZ0URa/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:37:38 INFO - MultiFileLogger online at 20170301 09:37:38 in /builds/slave/test 09:37:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:37:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:38 INFO - {'append_to_log': False, 09:37:38 INFO - 'base_work_dir': '/builds/slave/test', 09:37:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:37:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:38 INFO - 'branch': 'Mozilla-Aurora', 09:37:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:38 INFO - 'config_files': ('talos/mac_config.py',), 09:37:38 INFO - 'default_actions': ('clobber', 09:37:38 INFO - 'read-buildbot-config', 09:37:38 INFO - 'download-and-extract', 09:37:38 INFO - 'populate-webroot', 09:37:38 INFO - 'create-virtualenv', 09:37:38 INFO - 'install', 09:37:38 INFO - 'run-tests'), 09:37:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:38 INFO - 'download_minidump_stackwalk': True, 09:37:38 INFO - 'download_symbols': 'ondemand', 09:37:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:38 INFO - 'installer_path': 'installer.exe', 09:37:38 INFO - 'log_level': 'info', 09:37:38 INFO - 'log_name': 'talos', 09:37:38 INFO - 'log_to_console': True, 09:37:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:37:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:37:38 INFO - 'opt_config_files': (), 09:37:38 INFO - 'pip_index': False, 09:37:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:38 INFO - 'cmd': ('bash', 09:37:38 INFO - '-c', 09:37:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:38 INFO - 'enabled': True, 09:37:38 INFO - 'halt_on_failure': False, 09:37:38 INFO - 'name': 'check_screen_resolution'},), 09:37:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:38 INFO - 'cmd': ('bash', 09:37:38 INFO - '-c', 09:37:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:38 INFO - 'enabled': True, 09:37:38 INFO - 'halt_on_failure': False, 09:37:38 INFO - 'name': 'check_screen_resolution'},), 09:37:38 INFO - 'run_cmd_checks_enabled': True, 09:37:38 INFO - 'sps_profile': False, 09:37:38 INFO - 'sps_profile_interval': 0, 09:37:38 INFO - 'suite': 'dromaeojs-e10s', 09:37:38 INFO - 'system_bits': '32', 09:37:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:38 INFO - 'title': 't-yosemite-r7-0386', 09:37:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:38 INFO - 'use_talos_json': True, 09:37:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:38 INFO - 'work_dir': 'build'} 09:37:38 INFO - [mozharness: 2017-03-01 17:37:38.650045Z] Running clobber step. 09:37:38 INFO - Running pre-action listener: _resource_record_pre_action 09:37:38 INFO - Running main action method: clobber 09:37:38 INFO - rmtree: /builds/slave/test/build 09:37:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:41 INFO - Running post-action listener: _resource_record_post_action 09:37:41 INFO - [mozharness: 2017-03-01 17:37:41.437150Z] Finished clobber step (success) 09:37:41 INFO - [mozharness: 2017-03-01 17:37:41.437304Z] Running read-buildbot-config step. 09:37:41 INFO - Running pre-action listener: _resource_record_pre_action 09:37:41 INFO - Running main action method: read_buildbot_config 09:37:41 INFO - Using buildbot properties: 09:37:41 INFO - { 09:37:41 INFO - "project": "", 09:37:41 INFO - "product": "firefox", 09:37:41 INFO - "script_repo_revision": "production", 09:37:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:37:41 INFO - "repository": "", 09:37:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:37:41 INFO - "buildid": "20170301074622", 09:37:41 INFO - "pgo_build": "False", 09:37:41 INFO - "basedir": "/builds/slave/test", 09:37:41 INFO - "buildnumber": 75, 09:37:41 INFO - "slavename": "t-yosemite-r7-0386", 09:37:41 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:37:41 INFO - "platform": "yosemite_r7", 09:37:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:37:41 INFO - "branch": "mozilla-aurora", 09:37:41 INFO - "repo_path": "releases/mozilla-aurora", 09:37:41 INFO - "revision": "d68639a64412962018e2d516035038216d602f4d", 09:37:41 INFO - "stage_platform": "macosx64", 09:37:41 INFO - "builduid": "cc43dd05af6d4e9ca1e0898fd80fef13", 09:37:41 INFO - "slavebuilddir": "test" 09:37:41 INFO - } 09:37:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:37:41 INFO - Running post-action listener: _resource_record_post_action 09:37:41 INFO - [mozharness: 2017-03-01 17:37:41.440867Z] Finished read-buildbot-config step (success) 09:37:41 INFO - [mozharness: 2017-03-01 17:37:41.441002Z] Running download-and-extract step. 09:37:41 INFO - Running pre-action listener: _resource_record_pre_action 09:37:41 INFO - Running main action method: download_and_extract 09:37:41 INFO - mkdir: /builds/slave/test/build/tests 09:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:41 INFO - trying https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:37:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:37:42 INFO - Downloaded 1199 bytes. 09:37:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:37:42 INFO - Using the following test package requirements: 09:37:42 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:37:42 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:37:42 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:37:42 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'jsshell-mac.zip'], 09:37:42 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:37:42 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:37:42 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:37:42 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:37:42 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:37:42 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:37:42 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:37:42 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:37:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:37:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:37:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:37:43 INFO - Expected file size: 16357790 09:37:43 INFO - Obtained file size: 16357790 09:37:50 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:37:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:37:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:37:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:37:51 INFO - Expected file size: 16357790 09:37:51 INFO - Obtained file size: 16357790 09:37:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:37:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:37:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:37:58 INFO - Expected file size: 14403472 09:37:58 INFO - Obtained file size: 14403472 09:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:59 INFO - trying https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:37:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:37:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:38:01 INFO - Downloaded 73911435 bytes. 09:38:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:38:01 INFO - mkdir: /builds/slave/test/properties 09:38:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:01 INFO - Contents: 09:38:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:38:01 INFO - Running post-action listener: _resource_record_post_action 09:38:01 INFO - Running post-action listener: set_extra_try_arguments 09:38:01 INFO - [mozharness: 2017-03-01 17:38:01.977784Z] Finished download-and-extract step (success) 09:38:01 INFO - [mozharness: 2017-03-01 17:38:01.977910Z] Running populate-webroot step. 09:38:01 INFO - Running pre-action listener: _resource_record_pre_action 09:38:01 INFO - Running main action method: populate_webroot 09:38:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:01 INFO - '--tppagecycles', 09:38:01 INFO - '7'], 09:38:01 INFO - 'tests': ['tsvgm']}}, 09:38:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['damp', 'tps']}, 09:38:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'tests': ['damp', 'tps']}, 09:38:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['dromaeo_dom']}, 09:38:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['basic_compositor_video']}, 09:38:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:38:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['a11yr', 09:38:01 INFO - 'ts_paint', 09:38:01 INFO - 'tpaint', 09:38:01 INFO - 'sessionrestore', 09:38:01 INFO - 'sessionrestore_no_auto_restore', 09:38:01 INFO - 'tabpaint']}, 09:38:01 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:01 INFO - 'ts_paint', 09:38:01 INFO - 'tpaint', 09:38:01 INFO - 'sessionrestore', 09:38:01 INFO - 'sessionrestore_no_auto_restore', 09:38:01 INFO - 'tabpaint']}, 09:38:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:01 INFO - 'ts_paint', 09:38:01 INFO - 'tpaint', 09:38:01 INFO - 'sessionrestore', 09:38:01 INFO - 'sessionrestore_no_auto_restore', 09:38:01 INFO - 'tabpaint']}, 09:38:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:01 INFO - 'ts_paint', 09:38:01 INFO - 'tpaint', 09:38:01 INFO - 'sessionrestore', 09:38:01 INFO - 'sessionrestore_no_auto_restore', 09:38:01 INFO - 'tabpaint']}, 09:38:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['a11yr', 09:38:01 INFO - 'ts_paint', 09:38:01 INFO - 'tpaint', 09:38:01 INFO - 'sessionrestore', 09:38:01 INFO - 'sessionrestore_no_auto_restore', 09:38:01 INFO - 'tabpaint']}, 09:38:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['a11yr', 09:38:01 INFO - 'ts_paint', 09:38:01 INFO - 'tpaint', 09:38:01 INFO - 'sessionrestore', 09:38:01 INFO - 'sessionrestore_no_auto_restore', 09:38:01 INFO - 'tabpaint']}, 09:38:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['tsvgx', 09:38:01 INFO - 'tsvgr_opacity', 09:38:01 INFO - 'tart', 09:38:01 INFO - 'tscrollx', 09:38:01 INFO - 'cart', 09:38:01 INFO - 'tsvg_static']}, 09:38:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:01 INFO - 'tsvgr_opacity', 09:38:01 INFO - 'tart', 09:38:01 INFO - 'tscrollx', 09:38:01 INFO - 'cart', 09:38:01 INFO - 'tsvg_static']}, 09:38:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'talos_options': ['--disable-e10s'], 09:38:01 INFO - 'tests': ['tp5o']}, 09:38:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:38:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'talos_options': ['--disable-e10s', 09:38:01 INFO - '--xperf_path', 09:38:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:01 INFO - 'tests': ['tp5n']}, 09:38:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:38:01 INFO - 'talos_options': ['--xperf_path', 09:38:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:01 INFO - 'tests': ['tp5n']}}, 09:38:01 INFO - 'talos.zip': {'path': '', 09:38:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:01 INFO - Running post-action listener: _resource_record_post_action 09:38:01 INFO - [mozharness: 2017-03-01 17:38:01.996254Z] Finished populate-webroot step (success) 09:38:01 INFO - [mozharness: 2017-03-01 17:38:01.996383Z] Running create-virtualenv step. 09:38:01 INFO - Running pre-action listener: _resource_record_pre_action 09:38:01 INFO - Running main action method: create_virtualenv 09:38:01 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:38:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:02 INFO - Using real prefix '/tools/python27' 09:38:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:05 INFO - Installing pip.................done. 09:38:05 INFO - Return code: 0 09:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:38:05 INFO - Reading from file tmpfile_stdout 09:38:05 INFO - Output received: 09:38:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:38:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:05 INFO - 'HOME': '/Users/cltbld', 09:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:05 INFO - 'LOGNAME': 'cltbld', 09:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:05 INFO - 'MOZ_NO_REMOTE': '1', 09:38:05 INFO - 'NO_EM_RESTART': '1', 09:38:05 INFO - 'PAGER': '/bin/cat', 09:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:05 INFO - 'PWD': '/builds/slave/test', 09:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:05 INFO - 'SHELL': '/bin/bash', 09:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:05 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:05 INFO - 'USER': 'cltbld', 09:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:05 INFO - 'XPC_FLAGS': '0x0', 09:38:05 INFO - 'XPC_SERVICE_NAME': '0', 09:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:38:06 INFO - Cleaning up... 09:38:06 INFO - Return code: 0 09:38:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:06 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:38:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:06 INFO - 'HOME': '/Users/cltbld', 09:38:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:06 INFO - 'LOGNAME': 'cltbld', 09:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:06 INFO - 'MOZ_NO_REMOTE': '1', 09:38:06 INFO - 'NO_EM_RESTART': '1', 09:38:06 INFO - 'PAGER': '/bin/cat', 09:38:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:06 INFO - 'PWD': '/builds/slave/test', 09:38:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:06 INFO - 'SHELL': '/bin/bash', 09:38:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:06 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:06 INFO - 'USER': 'cltbld', 09:38:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:06 INFO - 'XPC_FLAGS': '0x0', 09:38:06 INFO - 'XPC_SERVICE_NAME': '0', 09:38:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:06 INFO - Downloading/unpacking psutil>=3.1.1 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:11 INFO - Installing collected packages: psutil 09:38:11 INFO - Running setup.py install for psutil 09:38:11 INFO - building 'psutil._psutil_osx' extension 09:38:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:38:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:38:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:38:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:38:12 INFO - building 'psutil._psutil_posix' extension 09:38:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:38:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:38:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:38:12 INFO - ^ 09:38:12 INFO - 1 warning generated. 09:38:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:38:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:12 INFO - Successfully installed psutil 09:38:12 INFO - Cleaning up... 09:38:12 INFO - Return code: 0 09:38:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:12 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:38:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:12 INFO - 'HOME': '/Users/cltbld', 09:38:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:12 INFO - 'LOGNAME': 'cltbld', 09:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:12 INFO - 'MOZ_NO_REMOTE': '1', 09:38:12 INFO - 'NO_EM_RESTART': '1', 09:38:12 INFO - 'PAGER': '/bin/cat', 09:38:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:12 INFO - 'PWD': '/builds/slave/test', 09:38:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:12 INFO - 'SHELL': '/bin/bash', 09:38:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:12 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:12 INFO - 'USER': 'cltbld', 09:38:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:12 INFO - 'XPC_FLAGS': '0x0', 09:38:12 INFO - 'XPC_SERVICE_NAME': '0', 09:38:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:15 INFO - Installing collected packages: mozsystemmonitor 09:38:15 INFO - Running setup.py install for mozsystemmonitor 09:38:15 INFO - Successfully installed mozsystemmonitor 09:38:15 INFO - Cleaning up... 09:38:15 INFO - Return code: 0 09:38:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:15 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:38:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:38:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:15 INFO - 'HOME': '/Users/cltbld', 09:38:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:15 INFO - 'LOGNAME': 'cltbld', 09:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:15 INFO - 'MOZ_NO_REMOTE': '1', 09:38:15 INFO - 'NO_EM_RESTART': '1', 09:38:15 INFO - 'PAGER': '/bin/cat', 09:38:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:15 INFO - 'PWD': '/builds/slave/test', 09:38:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:15 INFO - 'SHELL': '/bin/bash', 09:38:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:15 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:15 INFO - 'USER': 'cltbld', 09:38:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:15 INFO - 'XPC_FLAGS': '0x0', 09:38:15 INFO - 'XPC_SERVICE_NAME': '0', 09:38:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:19 INFO - warning: no files found matching '*.txt' 09:38:19 INFO - no previously-included directories found matching 'build' 09:38:19 INFO - no previously-included directories found matching 'dist' 09:38:19 INFO - no previously-included directories found matching '.git*' 09:38:19 INFO - Installing collected packages: jsonschema, functools32 09:38:19 INFO - Running setup.py install for functools32 09:38:19 INFO - warning: no files found matching '*.txt' 09:38:19 INFO - no previously-included directories found matching 'build' 09:38:19 INFO - no previously-included directories found matching 'dist' 09:38:19 INFO - no previously-included directories found matching '.git*' 09:38:19 INFO - Successfully installed jsonschema functools32 09:38:19 INFO - Cleaning up... 09:38:19 INFO - Return code: 0 09:38:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:19 INFO - 'HOME': '/Users/cltbld', 09:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PAGER': '/bin/cat', 09:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:19 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - 'XPC_FLAGS': '0x0', 09:38:19 INFO - 'XPC_SERVICE_NAME': '0', 09:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:38:19 INFO - Cleaning up... 09:38:19 INFO - Return code: 0 09:38:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:19 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:19 INFO - 'HOME': '/Users/cltbld', 09:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PAGER': '/bin/cat', 09:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:19 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - 'XPC_FLAGS': '0x0', 09:38:19 INFO - 'XPC_SERVICE_NAME': '0', 09:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:19 INFO - Downloading/unpacking blobuploader==1.2.4 09:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:23 INFO - Downloading docopt-0.6.1.tar.gz 09:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:23 INFO - Running setup.py install for blobuploader 09:38:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:24 INFO - Running setup.py install for requests 09:38:24 INFO - Running setup.py install for docopt 09:38:24 INFO - Successfully installed blobuploader requests docopt 09:38:24 INFO - Cleaning up... 09:38:24 INFO - Return code: 0 09:38:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:24 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:24 INFO - 'HOME': '/Users/cltbld', 09:38:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:24 INFO - 'LOGNAME': 'cltbld', 09:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:24 INFO - 'MOZ_NO_REMOTE': '1', 09:38:24 INFO - 'NO_EM_RESTART': '1', 09:38:24 INFO - 'PAGER': '/bin/cat', 09:38:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:24 INFO - 'PWD': '/builds/slave/test', 09:38:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:24 INFO - 'SHELL': '/bin/bash', 09:38:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:24 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:24 INFO - 'USER': 'cltbld', 09:38:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:24 INFO - 'XPC_FLAGS': '0x0', 09:38:24 INFO - 'XPC_SERVICE_NAME': '0', 09:38:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-k6XbT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-_PQ9B6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3Vep_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-KL6DVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-AXQ0bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-G_mEdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Ij1H3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-9aY5PE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Rm9pAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-y3d2ST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-WPwK9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-1iuPrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7MziOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-aAf4Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-9RDa1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-yfMCuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-DmSZY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:26 INFO - Running setup.py install for manifestparser 09:38:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:26 INFO - Running setup.py install for mozcrash 09:38:27 INFO - Running setup.py install for mozdebug 09:38:27 INFO - Running setup.py install for mozdevice 09:38:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Running setup.py install for mozfile 09:38:27 INFO - Running setup.py install for mozhttpd 09:38:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Running setup.py install for mozinfo 09:38:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Running setup.py install for mozInstall 09:38:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:27 INFO - Running setup.py install for mozleak 09:38:28 INFO - Running setup.py install for mozlog 09:38:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:28 INFO - Running setup.py install for moznetwork 09:38:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:28 INFO - Running setup.py install for mozprocess 09:38:28 INFO - Running setup.py install for mozprofile 09:38:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:28 INFO - Running setup.py install for mozrunner 09:38:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:28 INFO - Running setup.py install for mozscreenshot 09:38:29 INFO - Running setup.py install for moztest 09:38:29 INFO - Running setup.py install for mozversion 09:38:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:29 INFO - Cleaning up... 09:38:29 INFO - Return code: 0 09:38:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:29 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:29 INFO - 'HOME': '/Users/cltbld', 09:38:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:29 INFO - 'LOGNAME': 'cltbld', 09:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:29 INFO - 'MOZ_NO_REMOTE': '1', 09:38:29 INFO - 'NO_EM_RESTART': '1', 09:38:29 INFO - 'PAGER': '/bin/cat', 09:38:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:29 INFO - 'PWD': '/builds/slave/test', 09:38:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:29 INFO - 'SHELL': '/bin/bash', 09:38:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:29 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:29 INFO - 'USER': 'cltbld', 09:38:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:29 INFO - 'XPC_FLAGS': '0x0', 09:38:29 INFO - 'XPC_SERVICE_NAME': '0', 09:38:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-eINiiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-mC4UGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-n2pjou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:29 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-TiL5X6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-o1N32I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-5FQlR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Nj8uEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-llD861-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-eQh1fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-9UYLH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-YTu4vB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-SeHLRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:30 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)) 09:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:30 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pihFvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Or5Mas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Cnr2qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-actZxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:31 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-_Y5Lwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:34 INFO - Downloading blessings-1.6.tar.gz 09:38:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:34 INFO - Installing collected packages: blessings 09:38:34 INFO - Running setup.py install for blessings 09:38:34 INFO - Successfully installed blessings 09:38:34 INFO - Cleaning up... 09:38:34 INFO - Return code: 0 09:38:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:34 INFO - Reading from file tmpfile_stdout 09:38:34 INFO - Current package versions: 09:38:34 INFO - blessings == 1.6 09:38:34 INFO - blobuploader == 1.2.4 09:38:34 INFO - docopt == 0.6.1 09:38:34 INFO - functools32 == 3.2.3-2 09:38:34 INFO - jsonschema == 2.5.1 09:38:34 INFO - manifestparser == 1.1 09:38:34 INFO - mozInstall == 1.12 09:38:34 INFO - mozcrash == 1.0 09:38:34 INFO - mozdebug == 0.1 09:38:34 INFO - mozdevice == 0.49 09:38:34 INFO - mozfile == 1.2 09:38:34 INFO - mozhttpd == 0.7 09:38:34 INFO - mozinfo == 0.9 09:38:34 INFO - mozleak == 0.1 09:38:34 INFO - mozlog == 3.4 09:38:34 INFO - moznetwork == 0.27 09:38:34 INFO - mozprocess == 0.24 09:38:34 INFO - mozprofile == 0.28 09:38:34 INFO - mozrunner == 6.13 09:38:34 INFO - mozscreenshot == 0.1 09:38:34 INFO - mozsystemmonitor == 0.3 09:38:34 INFO - moztest == 0.8 09:38:34 INFO - mozversion == 1.4 09:38:34 INFO - psutil == 3.1.1 09:38:34 INFO - requests == 1.2.3 09:38:34 INFO - wsgiref == 0.1.2 09:38:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38: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')]} 09:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38: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')]} 09:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:34 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:34 INFO - 'HOME': '/Users/cltbld', 09:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:34 INFO - 'LOGNAME': 'cltbld', 09:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:34 INFO - 'MOZ_NO_REMOTE': '1', 09:38:34 INFO - 'NO_EM_RESTART': '1', 09:38:34 INFO - 'PAGER': '/bin/cat', 09:38:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:34 INFO - 'PWD': '/builds/slave/test', 09:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:34 INFO - 'SHELL': '/bin/bash', 09:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:34 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:34 INFO - 'USER': 'cltbld', 09:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:34 INFO - 'XPC_FLAGS': '0x0', 09:38:34 INFO - 'XPC_SERVICE_NAME': '0', 09:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:34 INFO - Cleaning up... 09:38:35 INFO - Return code: 0 09:38:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:35 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 0x10963fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109642768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc16bd60340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, '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.uyVjPO0jGw/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.agDOlZ0URa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:35 INFO - 'HOME': '/Users/cltbld', 09:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:35 INFO - 'LOGNAME': 'cltbld', 09:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:35 INFO - 'MOZ_NO_REMOTE': '1', 09:38:35 INFO - 'NO_EM_RESTART': '1', 09:38:35 INFO - 'PAGER': '/bin/cat', 09:38:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:35 INFO - 'PWD': '/builds/slave/test', 09:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:35 INFO - 'SHELL': '/bin/bash', 09:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:35 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:35 INFO - 'USER': 'cltbld', 09:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:35 INFO - 'XPC_FLAGS': '0x0', 09:38:35 INFO - 'XPC_SERVICE_NAME': '0', 09:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:35 INFO - Cleaning up... 09:38:35 INFO - Return code: 0 09:38:35 INFO - Running post-action listener: _resource_record_post_action 09:38:35 INFO - Running post-action listener: _start_resource_monitoring 09:38:35 INFO - Starting resource monitoring. 09:38:35 INFO - [mozharness: 2017-03-01 17:38:35.301218Z] Finished create-virtualenv step (success) 09:38:35 INFO - [mozharness: 2017-03-01 17:38:35.302176Z] Running install step. 09:38:35 INFO - Running pre-action listener: _resource_record_pre_action 09:38:35 INFO - Running main action method: install 09:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:35 INFO - Reading from file tmpfile_stdout 09:38:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:35 INFO - Reading from file tmpfile_stdout 09:38:35 INFO - Output received: 09:38:35 INFO - Usage: mozinstall [options] installer 09:38:35 INFO - Options: 09:38:35 INFO - -h, --help show this help message and exit 09:38:35 INFO - -d DEST, --destination=DEST 09:38:35 INFO - Directory to install application into. [default: 09:38:35 INFO - "/builds/slave/test"] 09:38:35 INFO - --app=APP Application being installed. [default: firefox] 09:38:35 INFO - mkdir: /builds/slave/test/build/application 09:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:38:55 INFO - Reading from file tmpfile_stdout 09:38:55 INFO - Output received: 09:38:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:38:55 INFO - Running post-action listener: _resource_record_post_action 09:38:55 INFO - [mozharness: 2017-03-01 17:38:55.883448Z] Finished install step (success) 09:38:55 INFO - [mozharness: 2017-03-01 17:38:55.883576Z] Running run-tests step. 09:38:55 INFO - Running pre-action listener: _resource_record_pre_action 09:38:55 INFO - Running main action method: run_tests 09:38:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:55 INFO - 2017-03-01 09:38:55.898 screenresolution[1628:7896] starting screenresolution argv=screenresolution get 09:38:55 INFO - 2017-03-01 09:38:55.900 screenresolution[1628:7896] Display 0: 1600x1200x32@60 09:38:55 INFO - 2017-03-01 09:38:55.906 screenresolution[1629:7899] starting screenresolution argv=screenresolution list 09:38:55 INFO - Available Modes on Display 0 09:38:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:56 INFO - Intel Iris: 09:38:56 INFO - Chipset Model: Intel Iris 09:38:56 INFO - Type: GPU 09:38:56 INFO - Bus: Built-In 09:38:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:56 INFO - Vendor: Intel (0x8086) 09:38:56 INFO - Device ID: 0x0a2e 09:38:56 INFO - Revision ID: 0x0009 09:38:56 INFO - Displays: 09:38:56 INFO - Display: 09:38:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:56 INFO - Display Serial Number: 1600x1200 60 09:38:56 INFO - Main Display: Yes 09:38:56 INFO - Mirror: Off 09:38:56 INFO - Online: Yes 09:38:56 INFO - Rotation: Supported 09:38:56 INFO - Return code: 0 09:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:56 INFO - Python 2.7.3 09:38:56 INFO - Return code: 0 09:38:56 INFO - grabbing minidump binary from tooltool 09:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:56 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 0x10941dd30>, '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 0x109344db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109412be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:38:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:38:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:38:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:56 INFO - Return code: 0 09:38:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--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 09:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --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 09:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uyVjPO0jGw/Render', 09:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:56 INFO - 'HOME': '/Users/cltbld', 09:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:56 INFO - 'LOGNAME': 'cltbld', 09:38:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:56 INFO - 'MOZ_NO_REMOTE': '1', 09:38:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:56 INFO - 'NO_EM_RESTART': '1', 09:38:56 INFO - 'PAGER': '/bin/cat', 09:38:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:56 INFO - 'PWD': '/builds/slave/test', 09:38:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:56 INFO - 'SHELL': '/bin/bash', 09:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agDOlZ0URa/Listeners', 09:38:56 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:56 INFO - 'USER': 'cltbld', 09:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:56 INFO - 'XPC_FLAGS': '0x0', 09:38:56 INFO - 'XPC_SERVICE_NAME': '0', 09:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--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 09:38:56 INFO - mozversion application_buildid: 20170301074622 09:38:56 INFO - mozversion application_changeset: d68639a64412962018e2d516035038216d602f4d 09:38:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:38:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:56 INFO - mozversion application_name: Firefox 09:38:56 INFO - mozversion application_remotingname: firefox-dev 09:38:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:56 INFO - mozversion application_vendor: Mozilla 09:38:56 INFO - mozversion application_version: 53.0a2 09:38:56 INFO - mozversion platform_buildid: 20170301074622 09:38:56 INFO - mozversion platform_changeset: d68639a64412962018e2d516035038216d602f4d 09:38:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:56 INFO - mozversion platform_version: 53.0a2 09:38:56 INFO - using testdate: 1488389936 09:38:56 INFO - actual date: 1488389936 09:38:56 INFO - starting webserver on 'localhost:49247' 09:38:56 INFO - SUITE-START | Running 2 tests 09:38:56 INFO - TEST-START | dromaeo_css 09:38:56 INFO - operating with platform_type : mac_ 09:38:56 INFO - Initialising browser for dromaeo_css test... 09:38:56 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpP7guHt/profile http://localhost:49247/getInfo.html) 09:38:59 INFO - PROCESS | 1639 | 2017-03-01 09:38:59.594 plugin-container[1642:8107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c4b, name = 'com.apple.tsm.portname' 09:38:59 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:59 INFO - PROCESS | 1639 | 2017-03-01 09:38:59.598 plugin-container[1642:8107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 09:38:59 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:59 INFO - PROCESS | 1639 | 2017-03-01 09:38:59.598 plugin-container[1642:8107] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:59 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 09:38:59 INFO - PROCESS | 1639 | colorDepth:24 09:38:59 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 09:38:59 INFO - PROCESS | 1639 | __metrics 09:38:59 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:39:00 INFO - TEST-INFO | 1639: exit 0 09:39:00 INFO - Browser initialized. 09:39:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:00 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpP7guHt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:03 INFO - PROCESS | 1643 | 2017-03-01 09:39:03.256 plugin-container[1644:8251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:39:03 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:03 INFO - PROCESS | 1643 | 2017-03-01 09:39:03.257 plugin-container[1644:8251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:39:03 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:39:03 INFO - PROCESS | 1643 | 2017-03-01 09:39:03.257 plugin-container[1644:8251] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:39:12 INFO - PROCESS | 1643 | RSS: Main: 219115520 09:39:12 INFO - PROCESS | 1643 | 09:42:15 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 09:42:16 INFO - PROCESS | 1643 | RSS: Main: 239206400 09:42:16 INFO - PROCESS | 1643 | 09:44:01 INFO - PROCESS | 1643 | ************************* 09:44:01 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 09:44:01 INFO - PROCESS | 1643 | 09:44:01 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:44:01 INFO - PROCESS | 1643 | Full stack: 09:44:01 INFO - PROCESS | 1643 | ************************* 09:45:19 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 09:45:20 INFO - PROCESS | 1643 | RSS: Main: 246091776 09:45:20 INFO - PROCESS | 1643 | 09:48:21 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 09:48:22 INFO - PROCESS | 1643 | RSS: Main: 254136320 09:48:22 INFO - PROCESS | 1643 | 09:51:24 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 09:51:25 INFO - PROCESS | 1643 | RSS: Main: 250720256 09:51:25 INFO - PROCESS | 1643 | 09:54:27 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 09:54:28 INFO - PROCESS | 1643 | RSS: Main: 254513152 09:54:28 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 09:57:31 INFO - PROCESS | 1643 | RSS: Main: 255987712 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | __start_tp_report 09:57:31 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:57:31 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:57:31 INFO - PROCESS | 1643 | |i|pagename|runs| 09:57:31 INFO - PROCESS | 1643 | |0;dojo.html;2625.973740262598;2816.8028238023335;2971.91084267472;3393.16867367495;3437.5187473753663;4989.301497790312;5690.3171619405675;5700.401457846923;5746.942530574693;5779.479846813786;5699.458551437607;5707.828765137042;5709.6574205547595;5713.485786279234;5713.885722285552;5190.610704197182;5225.790968361261;5265.999460102587;5266.078436273656;5276.182191760264;5252.343457067866;5289.153735402337;5292.285541451894;5297.602679799012;5309.73451327434;5198.974005129969;5244.239585260135;5285.445028272034;5288.286081379022;5337.3061502004875;5284.890173063662;5286.947130528684;5307.416184219733;5312.707801070939;5321.148616221425;4237.851675191384;5160.14857548504;5260.210968354739;5272.393674727417;5327.46725327468;5759.107338362561;5782.277215348082;5788.710564471759;5799.159121927297;5809.302883653946;6058.666773327465;6123.557162997608;6181.165542651729;6207.565470417073;6213;6249.156363890862;6264.373562643699;6286.685665716742;6292.905606415908;6307.990721484586;5845.912311315334;5856.677882716449;5870.412958704138;5873.972054890377;5902.793402230945;6223.782167624111;6260.123582698426;6304.085907543381;6322.272938612026;6329.240491141;6126.151269885539;6147.477464415485;6162.383761623802;6257.530685198628;6260;6268.874622507525;6290.748370065146;6310.05349197624;6312.589681670768;6319.652419116901;5633.746481408347;5793.507551858139;6278.277998030194;6280.654563999024;6298;6166.938330616636;6169.907451388232;6178.567500274938;6210.006398976142;6307.842303942438;5001.024800163933;5006.07387633289;5017.347744793154;5034.949650503521;5044.495550444926;4479.193745125908;4492.124035812985;4497.550244975541;4506.684532082788;4540.160070386989;5702.6293290935955;5728.312602487728;5744.569157313218;5755.482006619424;5785.508231800344;6237;6258.311585725566;6265.843353916189;6269.749210031548;6283.403076707797;5757;5780.16187652788;5797.130430435384;5815.854603634858;5819.854503637358;5382.03123437785;5415.566754659618;5423.484768946943;5434.402215756264;5460.590455715757;5419.783208671609;6145.6927153641245;6257.155284036597;6289.9371006291185;6301.432871041445;5230.503102205284;6100.1154832548145;6243.250809902841;6257.78097766576;6285.5600107992;5264.815731449381;6295.244570651551;6339.524535659752;6364.936350636434;6407.167068281094;6118.847028824315;6129.817282374446;6131.437835141539;6143.96928015357;6224.128621992834;4858.781354839111;5647.983362994701;6263.6241825490615;6287.339829317856;6312.274088479883;5328.987492376436;6202.131701561875;6207.348228435949;6235.96882015587;6240.188775459343;6049.637021778707;6090.512758979359;6102.420270074336;6123.877522449615;6220.471259942956;5898.675572843535;5948.940510595012;5965.552583556338;6052.061930400795;6054.485368743706;6152.569320147547;6192.225971753531;6195.628262304276;6198.783042593487;6214.316425193135;5865.794697185578;5964.373740757332;5986.311574169026;6041.214642096499;6059.757609695563 09:57:31 INFO - PROCESS | 1643 | |1;ext.html;449.5369769137789;474.7887190200361;499.18881817047276;515.3377909386439;525.976974784045;38628.80685596572;40657.39013914794;40818.18363632725;40887;40912.795436022854;42356.57643423565;42895.57104428955;42976.785116074374;43091.78454107725;43093.35359969603;8251.711190108348;8473.915260847374;9080.636774529028;9314.86027709585;9331.160195582395;11032.558697652124;11326.943365283203;11345.43272836359;11401.885981140165;11455.312681239111;11236.269642473255;11239.943800280987;11270.380129092897;11313.321200727942;11315.490802913848;11119.110471162288;11162.441877906112;11211.439428028565;11227.27022743519;11240.718982025473;11179.161562882817;11278.210525263235;11282.830757538644;11297.548098076068;11306.886931130666;39935.800320998496;40195.799021005;40277.59722402797;40378.394324085166;40382.192356153006;3913.882583522499;3931.4495970564144;3963.6829053675638;3973.662238709713;3983.223271462066;28159.859200703864;29267.853660731773;29385.265368365748;29424.264393390124;29503;3135.827529485877;3183.220111072802;3249.723773479257;3351.2124650707065;3353.1281866714585;419802.9009854962;424335;426557;427878;430434.847825762;57111;57276;57355.42644573501;57621.71189144111;57754;55051.724741376034;55898;55941;55958.44041559614;55976.44023559794;12422.689432764253;16017.599560011093;16042.839571604134;16052.438164664181;16088.839111608968;9143.817123657489;11542.538298467967;12268.018558515298;12498.125131240726;12601.936990314989;18051.458456246335;18073.36743213981;18112.45662630123;18125.456236312933;18178.90926544385;6426.746784377002;7761;7803.336716379058;7812.492188007875;7830.686772529149;782.6243403166512;880.4673172730534;882.3382463152635;905.791667916383;914.2457472585144;26022.86988565045;30229.69770302313;30380;31108;31202.68797312043;825.8472182646229;1082.1451053667515;1092.180864351736;1100.389283947417;1180.9489554296683;13359.465621375231;15642.608934776721;15843;15905.602359940862;16111.838881611267;479056;510011;569704;571853;574133;59166.70416647889;59710.40289597048;59764;59859;59877.40122598718;1227.6685294970487;1256.7549327881159;1421.7085497472992;1462.817147856518;1470.9558713238835;27728.168154955383;29002.7099729;29209.831606735494;30043.69956300409;30276;51065.978680426924;51385.74307128441;51449;51469;51600;529388;535334;538318;539640;540245;25307.620385694598;25902.611460827724;25994.610080848423;26338.078167264055;26604.201873943814;41875.371869421375;42695.786521067195;43158;43709.68870933877;43826;35296.11759705925;35312.47031294481;35479.29041419209;35531;36711.449328259565;35256;35584;36091.09772255715;36376.27247455089;36386;34266.82866585651;34368.65631343654;34374.312513749086;35176;35299.11752206215;29755.958541450946;31337;31445.213869652525;31516;31563.73745050172;23838.88080559586;23895.163669271493;23990.28029159128;24150.51698966046;24249.636255456535 09:57:31 INFO - PROCESS | 1643 | |2;jquery.html;360.17519879476004;387.80997311317464;389.827088060561;402.2718878829318;418.7257346438082;16645.750313745302;20100.89949550252;20895.373138805822;21247.893760531213;21268.57462850742;21163.47091322718;21817.454563635918;22043;22048.779512204874;22256.777432225674;5559.332880054389;5857;5864.23764910561;5866.6480011199465;5870.26621672291;5470.124780035195;5687.9146812797635;5723.742431590588;5766.625169363978;5818.563607729416;5674.035413979634;5674.886502269952;5679.4036626154275;5681;5696;5474.698891560983;5671.120976248688;5709.172170035363;5731.885362292773;5753.194552762575;5057.367829021372;5088.720120393377;5093.643444958854;5106.361704786902;5117.078925793356;26257.868710656323;26364;26758.33104172392;26908.923643054255;27166.864165679242;2231.5760005598886;2640.722724113979;2710.9322266943486;2747.7252274772563;2776.972230277692;14827.48103816372;15817;15863.20683965797;16078;16394.672106557922;1813.5466133466634;1851.463075708043;1866.188208129468;1882.632886587116;1883.340830709241;118433.81566184314;121515;124707;127268;128177;9469.573869175903;11452;11856.940715296369;11873.228240164362;12114.454849531792;13345.199288042748;13947;15180.696386072217;15196.468123615841;15222.771658425132;8514.276286515715;8817.515036673045;8848.67269909506;8894.777630559158;9142.862857057149;7662.846743065219;8872;8894.822103558023;8918.464892106495;8919.777005574913;7591.164971853092;7690.701143709597;7704.499207551491;7759.7672069838;7779.027621547307;1685.502776680882;1985.7716362618485;2039.5411032517566;2041.2345370486069;2096.22439697313;858.9098144694842;889.2574700125467;890.5324704530174;913.2374467319862;921.1755478846395;20954.790452095283;21312.04095815692;21404.25085122013;21446.678299825515;21579.89210053971;757.9128400233933;900.1133883125117;924.861270809384;928.252756530999;931.4411353188033;9331.440113593206;9901.356411833207;11296.604618838466;11323.60367387154;11428.314301141958;633801;701629.4918525375;739846;744142;757211;111698;137838;138651.61348386388;142926;144059;956.5791051937125;967.322873988197;992.3153024413132;1024.9897501025043;1047.675220681591;19484.928328942045;19714.704279436108;20326.695099573815;20946.685799713323;21388.679169812152;59304;59856.10935289192;60026.19921402365;60606.39393606008;61125;63775;66530;84520;84635.30729385254;84874.57562712394;5931.051031834886;6403.64779937108;6994.300569943169;8205.95897020511;8535.53054582004;12239.75520489603;12283.938580307042;12836;13215.273159976294;13296.534621288209;5507.366652834975;7284.635768211674;9244.214241789254;11321.830172547254;11574.768504629692;8922.821543568962;9567.712968610953;9748.463834488888;11212.43937803123;11506.597269095542;9158.587863546023;11370.54517819311;11578.42107894585;11777;12085.697857553632;6181.907271391023;7233.746818861315;7575.24247575238;7742.303192712683;8671.826563468569;9855.507224638883;11084.667459976214;11384.032357249394;11438.370889601152;11481.885181148416 09:57:31 INFO - PROCESS | 1643 | |3;mootools.html;1423.9074460160089;1435.9824446652444;1456.0753921259998;1457.1475686723265;1464.9121052736834;7634;7907;8095.150009249032;8535.615897284622;8575.313974882003;8134.471259368137;8450.36622253332;8453.816465694792;8463.534505602205;8477.406581539295;2323.61660326046;2360.0559776089617;2365.763423657629;2367.7277113131954;2372.0511795281855;2338.088145623208;2368.2303251443263;2371.1108334374608;2375.0024989504363;2377.465070359173;2342.0631747301045;2343.1915988983774;2352.8235382346393;2363.267387109993;2367.242482405631;2253.515494168745;2263.6870615043235;2290.03818396274;2290.2213247495765;2291.049214575949;2320.4453016478833;2339.5671800716923;2359.8466099703464;2361.633946738258;2365.1367250953276;8488.448250863674;8534.146585341477;8552.444091134057;8562.74311770648;8572.014218364842;5000.0999820032375;5044.646874718771;5188.273641690166;5201.453847346012;5228.424873263938;3363.9495407568907;3485.9651403486146;3582.3372676055014;3583.050491619723;3592.3354179476883;1163.7905177068124;1212.605903081502;1247.7878760610627;1249.7562975219835;1251.6307689231699;10767.676969690921;10817.188710846718;10893.67318980424;10956.506957186866;10978.286411383315;4415.889602759957;4578.397560853675;4659.860204193879;4667.906641867144;4772.427308722906;9674.9032509674;9785.461799601091;9798.706038818704;9803.215742740564;9814.558344874498;5246.344206974129;5309.814156504503;5310.097283461821;5328.014317351302;5328.893422131536;4245.808938597709;4323.221820072417;4752.287156926489;4787.688800227974;4834.250691142878;3888.650021498079;3924.9215015699524;3998.3402738547816;4021.115354622037;4043.353063509824;1283.1018287199;1301.811237370576;1307.0654482045384;1316.6642506160867;1317.3479127831786;924.4961495984704;931.4225180388203;938.7981583959481;943.4669411782321;975.4683697384942;5430.701311427831;5540.695261760561;5824.417558244142;5836.358000619928;5890.263717035371;859;896.5923500104903;968.2157452463528;1031.9845202321972;1037.7613148975777;3079.4918838391864;3594.8382322795537;3790.65884070435;3859.807009649506;3880.1851611161974;8203.7949051273;9787;10133.290669653054;10147.441890696082;10147.441890696082;8675.132486751401;10071.848922266172;10085;10105.29262951601;10131;7482.802751559724;7765.417593680497;7820;7894.684212631545;7926.48477848938;5738.856528586819;5797.246357973437;5889.52883769306;5893.557983151195;5933.821985340447;8731.276916774175;9048.41185322952;9181.77045573866;9284;9303.488308143116;8956.014838367644;9188.770280743034;9261.861072084059;9323.347365684336;9384.390014649027;6701.899471508029;6912.343327383891;7039.260877607778;7200.531965422231;7330.853382932205;7856.332211762065;8036.959815200887;8039.557824319719;8061.31478824283;8072.5963701815845;6931.341522555349;7117.7152913882865;7247.637618118967;7310.268973102648;7363.374113200223;6846.028787818645;6969.7909062728195;7008.404285635778;7077.858442831218;7123.109611298588;6380.968095159495;6389.808305750835;6478.255000674794;6482.675866206765;6491.513136514686;4954.5788607968725;4986.401631804207;5003.3495645565845;5036.143855544639;5062.265971434195;4939.901201975868;5007.84976450707;5015.1474249378425;5113.795448182031;5135.204043373283 09:57:31 INFO - PROCESS | 1643 | |4;prototype.html;345.6871531264205;346.82426573914427;351.7748640869845;353.219385158799;356.056450406423;4563.88590285243;4773.57037866592;5042.495750424951;5338.199270109486;5416.8104116355935;4965.478624744395;5089.803896084417;5417.945820541793;5450.29146210992;5481.314835645544;3402.625711171769;3694.926101477969;3945.737364043507;4146.3158578834455;4171.270027745148;3223.4842425211973;3371.410003249434;4010.6590939770153;4023.903785764569;4077.776666999888;3734.4958430611928;4057.5942405759483;4097.795110244476;4104.384342348657;4172.895677608069;4099.918001639965;4138.317177665682;4144.254034273845;4153.626173644357;4154.4183814266025;3424;3567.7324200684793;3572.678458938682;3574.5692928808658;3583.4266517357096;4367.934480982788;4535.705179163344;4581.289900065462;4939.382577177853;5187.948120518784;3614.8011859347725;3886.8639597613947;4444.822207111712;4578.793954272066;4693.389859318301;5432.592555558349;5541.639793413416;5757.798477053325;5774.498630356095;5782.537397008229;3863.0298547740854;3884.553276373225;3885.417187421881;3886.24218277436;3894.7273690841653;5473.562115030828;5575.940571291441;5754.88490230193;5902.852428689317;6004.829058333627;3497.755157138976;3503.562054743157;3930.270680900395;3932.980335098306;3972.1261322509004;3393.5927688677516;3821.522309711286;4029.315016447211;4031.7544376776978;4039.5354534228354;4565.516207232663;4681.6825671292245;4684.882877928079;4684.969306752509;4712.575868171881;4720.740359280204;4912.852614421573;5731.971340143273;5824;5836.824895253149;1147.5926046253626;1169.497116240008;1246.1712960881093;1289.3682095773004;1304.8890844278342;700.3731660164226;757.81812365032;817.7669364231166;877.4735158904733;888.480239060157;4477.798499067485;5113.335266415343;5140.254663073833;5180.948190518046;5196.740162991911;5382.73086345689;5434.8913021739345;5695.971520142372;5745;5752.676884316631;4447.552447552447;4577.633789296849;4636.281376386598;4849.636277279218;5244.685318880879;2723.3872378715027;2845.331347133422;2966.718161774628;2995;3012.4335345620157;4889.853304400874;5794.913076303858;5818.5636077294375;5883.852903677443;5928.525717942554;5476.698781566973;5731.917995626147;5775.563539693875;5810.970945145247;5864;5315.813946511854;5833.499900060015;5900.784072680313;5940.851478713067;5962.761489540369;5329.493698098674;5583.715745378505;5719.542436605145;5721.427857214246;5764.538836893123;5909.645421274738;5916.822495325147;5918.7928422505;5933.202750239764;5945.948647716531;5078.333874715656;5424.294841670558;5615.775368985195;5936.732847021808;6068.8786224276155;3508.8947331580093;3925.666318362971;3964.592569637852;3981.2037592481042;4005.1989602079116;5849.912251316148;5996.640201587918;6000.97983342824;6017.18767966319;6027.336992930862;5680.488756011979;5720.570957178145;5741.91387129185;5749.770009199585;5805.651660900359;5763.337216220189;5773.422657734361;5781.5663825214115;5787.652740835395;5805.896879593033;5264.552513036281;5329.7868085277705;5419.485148910847;5507;5640.661560306395;5687;5762.654240745569;5790.9152705027;5823.631446609958;5847.970760146172;4721.8819529510665;4995.975020124876;5690.8577285566535;5711.228984087095;5754.942450575608 09:57:31 INFO - PROCESS | 1643 | |5;yui.html;700.2227527444539;705.9647017649123;770.3721467004391;811.5693497973568;817.0388291754253;14881.255937203137;15285.541433756976;15628.984116032465;15807.920960395197;15911.681766364665;13522;14149.787753183711;14154.938379621519;14592.635184120401;14619.195944223064;843.0557775291667;857.4340934982913;859.2867919626694;859.6088779605265;859.7463748194298;671.7816709569385;692.4149094015548;838.8657814749641;849.7875531117221;851.4040171879678;902.7246689759621;913.4747520175893;915.5422288855572;918.5682729117304;919.0350132361027;904.4889637354906;911.0616065452595;911.8221946720392;912.16536868765;914.8536234202529;671.2079745899837;674.9639303668894;675.3449154320301;676.7597502886454;677.0960767375559;14863.628409289746;15318.463853765064;15462.30419631108;15528;15616.141112238822;13630.318484075757;13864.930675346559;14099.718005639932;14099.78850317246;14157.504487342996;11321.865752453932;11504.769904601944;11567.421628918604;11607.361595112265;11686.824697629543;3528.982355088234;3569.161247106928;3586.7309951753464;3614.060344310472;3718.2563487302377;113828;114558.28162577485;115081;116464.99999999916;117334;10408.47957602117;10582.682519524427;10594.841077383846;10602.416867072385;10864.348139111678;22357.441063973532;22557.323280301618;22558.548829023326;22569.322920312417;22650;7568;7583.203763604853;7588.354989825816;7710.653020614086;7756.53460792343;4139.627433530996;4164.729292595972;4227.8097485613225;4229;4293.97853010733;3410.9317813644084;3493.371193185251;3506.561679790026;3507.964920350764;3511.5610548681416;551.3989751171242;636.2492259134266;637.3148864970176;638.4955884850916;645.4126744662428;669.1668872254074;686.3171144711044;693.3240090911321;709.2127738210582;722.4762047515509;8697.043325234219;9527.618895244252;9883.369244074791;10086.24353173515;10251.692449226535;600.7997466149384;685.413971054751;689.689639662154;711.2887112887113;715.2316566888031;4594.356790049395;4864.73243971585;4877.097736918681;4887.95112048882;4889.853304400874;103120;106405.46797265964;106473.40289895657;106712.93287067185;108076.4596177014;68010.57931234453;73944;74393.6280318595;74810.87783683249;78841.60579197182;74733;74882.6255868728;76201;76846.6157669208;77169.2283077162;9594.472304023346;10447.895521044693;10554.788904222027;10583;10740.409277489813;31832.68167318297;43861.12277754491;45088.774556127006;46218.76890615525;47409;57630.13554796765;59403;59796.40203597908;61370;62275.065874012835;12327.198732082386;12549;12756.425960831599;12783.23300601967;12828.219702605962;13995;14166.78749818733;14186.432542698176;14411.639709007359;14466.710665786415;12867;13224.669383265495;13451.125676830974;13890.097143685629;13952.790708139182;12825;13278.80081798793;13880;13885.444582216598;14078.084924479876;11184.832227516756;12742.362881856056;13345.599632011055;13357.732845343233;13538.458461661423;10613.363198207824;11336.489857956576;11418.942905285421;11711.329079452971;11774.94112529432;10677.46612669379;10767.515461804081;10887.673369798918;10892.292001019909;11058.22592418523 09:57:31 INFO - PROCESS | 1643 | __end_tp_report 09:57:31 INFO - PROCESS | 1643 | __start_cc_report 09:57:31 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,394 09:57:31 INFO - PROCESS | 1643 | __end_cc_report 09:57:31 INFO - PROCESS | 1643 | __startTimestamp1488391051555__endTimestamp 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:57:31 INFO - PROCESS | 1643 | Number of tests: 6 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5726.20 Median:5873.97 stddev:678.92 (11.6%) stddev-sans-first:635.95 09:57:31 INFO - PROCESS | 1643 | Values: 2626.0 2816.8 2971.9 3393.2 3437.5 4989.3 5690.3 5700.4 5746.9 5779.5 5699.5 5707.8 5709.7 5713.5 5713.9 5190.6 5225.8 5266.0 5266.1 5276.2 5252.3 5289.2 5292.3 5297.6 5309.7 5199.0 5244.2 5285.4 5288.3 5337.3 5284.9 5286.9 5307.4 5312.7 5321.1 4237.9 5160.1 5260.2 5272.4 5327.5 5759.1 5782.3 5788.7 5799.2 5809.3 6058.7 6123.6 6181.2 6207.6 6213.0 6249.2 6264.4 6286.7 6292.9 6308.0 5845.9 5856.7 5870.4 5874.0 5902.8 6223.8 6260.1 6304.1 6322.3 6329.2 6126.2 6147.5 6162.4 6257.5 6260.0 6268.9 6290.7 6310.1 6312.6 6319.7 5633.7 5793.5 6278.3 6280.7 6298.0 6166.9 6169.9 6178.6 6210.0 6307.8 5001.0 5006.1 5017.3 5034.9 5044.5 4479.2 4492.1 4497.6 4506.7 4540.2 5702.6 5728.3 5744.6 5755.5 5785.5 6237.0 6258.3 6265.8 6269.7 6283.4 5757.0 5780.2 5797.1 5815.9 5819.9 5382.0 5415.6 5423.5 5434.4 5460.6 5419.8 6145.7 6257.2 6289.9 6301.4 5230.5 6100.1 6243.3 6257.8 6285.6 5264.8 6295.2 6339.5 6364.9 6407.2 6118.8 6129.8 6131.4 6144.0 6224.1 4858.8 5648.0 6263.6 6287.3 6312.3 5329.0 6202.1 6207.3 6236.0 6240.2 6049.6 6090.5 6102.4 6123.9 6220.5 5898.7 5948.9 5965.6 6052.1 6054.5 6152.6 6192.2 6195.6 6198.8 6214.3 5865.8 5964.4 5986.3 6041.2 6059.8 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:63963.77 Median:35395.88 stddev:134318.91 (379.5%) stddev-sans-first:134611.05 09:57:31 INFO - PROCESS | 1643 | Values: 449.5 474.8 499.2 515.3 526.0 38628.8 40657.4 40818.2 40887.0 40912.8 42356.6 42895.6 42976.8 43091.8 43093.4 8251.7 8473.9 9080.6 9314.9 9331.2 11032.6 11326.9 11345.4 11401.9 11455.3 11236.3 11239.9 11270.4 11313.3 11315.5 11119.1 11162.4 11211.4 11227.3 11240.7 11179.2 11278.2 11282.8 11297.5 11306.9 39935.8 40195.8 40277.6 40378.4 40382.2 3913.9 3931.4 3963.7 3973.7 3983.2 28159.9 29267.9 29385.3 29424.3 29503.0 3135.8 3183.2 3249.7 3351.2 3353.1 419802.9 424335.0 426557.0 427878.0 430434.8 57111.0 57276.0 57355.4 57621.7 57754.0 55051.7 55898.0 55941.0 55958.4 55976.4 12422.7 16017.6 16042.8 16052.4 16088.8 9143.8 11542.5 12268.0 12498.1 12601.9 18051.5 18073.4 18112.5 18125.5 18178.9 6426.7 7761.0 7803.3 7812.5 7830.7 782.6 880.5 882.3 905.8 914.2 26022.9 30229.7 30380.0 31108.0 31202.7 825.8 1082.1 1092.2 1100.4 1180.9 13359.5 15642.6 15843.0 15905.6 16111.8 479056.0 510011.0 569704.0 571853.0 574133.0 59166.7 59710.4 59764.0 59859.0 59877.4 1227.7 1256.8 1421.7 1462.8 1471.0 27728.2 29002.7 29209.8 30043.7 30276.0 51066.0 51385.7 51449.0 51469.0 51600.0 529388.0 535334.0 538318.0 539640.0 540245.0 25307.6 25902.6 25994.6 26338.1 26604.2 41875.4 42695.8 43158.0 43709.7 43826.0 35296.1 35312.5 35479.3 35531.0 36711.4 35256.0 35584.0 36091.1 36376.3 36386.0 34266.8 34368.7 34374.3 35176.0 35299.1 29756.0 31337.0 31445.2 31516.0 31563.7 23838.9 23895.2 23990.3 24150.5 24249.6 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:39049.18 Median:2738.05 stddev:118941.66 (4344.0%) stddev-sans-first:119239.85 09:57:31 INFO - PROCESS | 1643 | Values: 360.2 387.8 389.8 402.3 418.7 16645.8 20100.9 20895.4 21247.9 21268.6 21163.5 21817.5 22043.0 22048.8 22256.8 5559.3 5857.0 5864.2 5866.6 5870.3 5470.1 5687.9 5723.7 5766.6 5818.6 5674.0 5674.9 5679.4 5681.0 5696.0 5474.7 5671.1 5709.2 5731.9 5753.2 5057.4 5088.7 5093.6 5106.4 5117.1 26257.9 26364.0 26758.3 26908.9 27166.9 2231.6 2640.7 2710.9 2747.7 2777.0 14827.5 15817.0 15863.2 16078.0 16394.7 1813.5 1851.5 1866.2 1882.6 1883.3 118433.8 121515.0 124707.0 127268.0 128177.0 9469.6 11452.0 11856.9 11873.2 12114.5 13345.2 13947.0 15180.7 15196.5 15222.8 8514.3 8817.5 8848.7 8894.8 9142.9 7662.8 8872.0 8894.8 8918.5 8919.8 7591.2 7690.7 7704.5 7759.8 7779.0 1685.5 1985.8 2039.5 2041.2 2096.2 858.9 889.3 890.5 913.2 921.2 20954.8 21312.0 21404.3 21446.7 21579.9 757.9 900.1 924.9 928.3 931.4 9331.4 9901.4 11296.6 11323.6 11428.3 633801.0 701629.5 739846.0 744142.0 757211.0 111698.0 137838.0 138651.6 142926.0 144059.0 956.6 967.3 992.3 1025.0 1047.7 19484.9 19714.7 20326.7 20946.7 21388.7 59304.0 59856.1 60026.2 60606.4 61125.0 63775.0 66530.0 84520.0 84635.3 84874.6 5931.1 6403.6 6994.3 8206.0 8535.5 12239.8 12283.9 12836.0 13215.3 13296.5 5507.4 7284.6 9244.2 11321.8 11574.8 8922.8 9567.7 9748.5 11212.4 11506.6 9158.6 11370.5 11578.4 11777.0 12085.7 6181.9 7233.7 7575.2 7742.3 8671.8 9855.5 11084.7 11384.0 11438.4 11481.9 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5472.78 Median:5161.74 stddev:2951.81 (57.2%) stddev-sans-first:2944.41 09:57:31 INFO - PROCESS | 1643 | Values: 1423.9 1436.0 1456.1 1457.1 1464.9 7634.0 7907.0 8095.2 8535.6 8575.3 8134.5 8450.4 8453.8 8463.5 8477.4 2323.6 2360.1 2365.8 2367.7 2372.1 2338.1 2368.2 2371.1 2375.0 2377.5 2342.1 2343.2 2352.8 2363.3 2367.2 2253.5 2263.7 2290.0 2290.2 2291.0 2320.4 2339.6 2359.8 2361.6 2365.1 8488.4 8534.1 8552.4 8562.7 8572.0 5000.1 5044.6 5188.3 5201.5 5228.4 3363.9 3486.0 3582.3 3583.1 3592.3 1163.8 1212.6 1247.8 1249.8 1251.6 10767.7 10817.2 10893.7 10956.5 10978.3 4415.9 4578.4 4659.9 4667.9 4772.4 9674.9 9785.5 9798.7 9803.2 9814.6 5246.3 5309.8 5310.1 5328.0 5328.9 4245.8 4323.2 4752.3 4787.7 4834.3 3888.7 3924.9 3998.3 4021.1 4043.4 1283.1 1301.8 1307.1 1316.7 1317.3 924.5 931.4 938.8 943.5 975.5 5430.7 5540.7 5824.4 5836.4 5890.3 859.0 896.6 968.2 1032.0 1037.8 3079.5 3594.8 3790.7 3859.8 3880.2 8203.8 9787.0 10133.3 10147.4 10147.4 8675.1 10071.8 10085.0 10105.3 10131.0 7482.8 7765.4 7820.0 7894.7 7926.5 5738.9 5797.2 5889.5 5893.6 5933.8 8731.3 9048.4 9181.8 9284.0 9303.5 8956.0 9188.8 9261.9 9323.3 9384.4 6701.9 6912.3 7039.3 7200.5 7330.9 7856.3 8037.0 8039.6 8061.3 8072.6 6931.3 7117.7 7247.6 7310.3 7363.4 6846.0 6969.8 7008.4 7077.9 7123.1 6381.0 6389.8 6478.3 6482.7 6491.5 4954.6 4986.4 5003.3 5036.1 5062.3 4939.9 5007.8 5015.1 5113.8 5135.2 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4571.93 Median:5126.79 stddev:1435.16 (28.0%) stddev-sans-first:1403.70 09:57:31 INFO - PROCESS | 1643 | Values: 345.7 346.8 351.8 353.2 356.1 4563.9 4773.6 5042.5 5338.2 5416.8 4965.5 5089.8 5417.9 5450.3 5481.3 3402.6 3694.9 3945.7 4146.3 4171.3 3223.5 3371.4 4010.7 4023.9 4077.8 3734.5 4057.6 4097.8 4104.4 4172.9 4099.9 4138.3 4144.3 4153.6 4154.4 3424.0 3567.7 3572.7 3574.6 3583.4 4367.9 4535.7 4581.3 4939.4 5187.9 3614.8 3886.9 4444.8 4578.8 4693.4 5432.6 5541.6 5757.8 5774.5 5782.5 3863.0 3884.6 3885.4 3886.2 3894.7 5473.6 5575.9 5754.9 5902.9 6004.8 3497.8 3503.6 3930.3 3933.0 3972.1 3393.6 3821.5 4029.3 4031.8 4039.5 4565.5 4681.7 4684.9 4685.0 4712.6 4720.7 4912.9 5732.0 5824.0 5836.8 1147.6 1169.5 1246.2 1289.4 1304.9 700.4 757.8 817.8 877.5 888.5 4477.8 5113.3 5140.3 5180.9 5196.7 5382.7 5434.9 5696.0 5745.0 5752.7 4447.6 4577.6 4636.3 4849.6 5244.7 2723.4 2845.3 2966.7 2995.0 3012.4 4889.9 5794.9 5818.6 5883.9 5928.5 5476.7 5731.9 5775.6 5811.0 5864.0 5315.8 5833.5 5900.8 5940.9 5962.8 5329.5 5583.7 5719.5 5721.4 5764.5 5909.6 5916.8 5918.8 5933.2 5945.9 5078.3 5424.3 5615.8 5936.7 6068.9 3508.9 3925.7 3964.6 3981.2 4005.2 5849.9 5996.6 6001.0 6017.2 6027.3 5680.5 5720.6 5741.9 5749.8 5805.7 5763.3 5773.4 5781.6 5787.7 5805.9 5264.6 5329.8 5419.5 5507.0 5640.7 5687.0 5762.7 5790.9 5823.6 5848.0 4721.9 4996.0 5690.9 5711.2 5754.9 09:57:31 INFO - PROCESS | 1643 | 09:57:31 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:19945.45 Median:3509.76 stddev:29009.90 (826.5%) stddev-sans-first:29055.29 09:57:31 INFO - PROCESS | 1643 | Values: 700.2 706.0 770.4 811.6 817.0 14881.3 15285.5 15629.0 15807.9 15911.7 13522.0 14149.8 14154.9 14592.6 14619.2 843.1 857.4 859.3 859.6 859.7 671.8 692.4 838.9 849.8 851.4 902.7 913.5 915.5 918.6 919.0 904.5 911.1 911.8 912.2 914.9 671.2 675.0 675.3 676.8 677.1 14863.6 15318.5 15462.3 15528.0 15616.1 13630.3 13864.9 14099.7 14099.8 14157.5 11321.9 11504.8 11567.4 11607.4 11686.8 3529.0 3569.2 3586.7 3614.1 3718.3 113828.0 114558.3 115081.0 116465.0 117334.0 10408.5 10582.7 10594.8 10602.4 10864.3 22357.4 22557.3 22558.5 22569.3 22650.0 7568.0 7583.2 7588.4 7710.7 7756.5 4139.6 4164.7 4227.8 4229.0 4294.0 3410.9 3493.4 3506.6 3508.0 3511.6 551.4 636.2 637.3 638.5 645.4 669.2 686.3 693.3 709.2 722.5 8697.0 9527.6 9883.4 10086.2 10251.7 600.8 685.4 689.7 711.3 715.2 4594.4 4864.7 4877.1 4888.0 4889.9 103120.0 106405.5 106473.4 106712.9 108076.5 68010.6 73944.0 74393.6 74810.9 78841.6 74733.0 74882.6 76201.0 76846.6 77169.2 9594.5 10447.9 10554.8 10583.0 10740.4 31832.7 43861.1 45088.8 46218.8 47409.0 57630.1 59403.0 59796.4 61370.0 62275.1 12327.2 12549.0 12756.4 12783.2 12828.2 13995.0 14166.8 14186.4 14411.6 14466.7 12867.0 13224.7 13451.1 13890.1 13952.8 12825.0 13278.8 13880.0 13885.4 14078.1 11184.8 12742.4 13345.6 13357.7 13538.5 10613.4 11336.5 11418.9 11711.3 11774.9 10677.5 10767.5 10887.7 10892.3 11058.2 09:57:31 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:57:31 INFO - PROCESS | 1643 | 09:57:32 INFO - TEST-INFO | 1643: exit 0 09:57:32 INFO - TEST-OK | dromaeo_css | took 1115713ms 09:57:32 INFO - TEST-START | kraken 09:57:32 INFO - operating with platform_type : mac_ 09:57:32 INFO - Initialising browser for kraken test... 09:57:32 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp9zc2FB/profile http://localhost:49247/getInfo.html) 09:57:34 INFO - PROCESS | 1651 | 2017-03-01 09:57:34.862 plugin-container[1652:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:57:34 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:34 INFO - PROCESS | 1651 | 2017-03-01 09:57:34.865 plugin-container[1652:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 09:57:34 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:34 INFO - PROCESS | 1651 | 2017-03-01 09:57:34.865 plugin-container[1652:10530] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:34 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:57:34 INFO - PROCESS | 1651 | colorDepth:24 09:57:34 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:57:34 INFO - PROCESS | 1651 | __metrics 09:57:35 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:57:35 INFO - TEST-INFO | 1651: exit 0 09:57:35 INFO - Browser initialized. 09:57:35 INFO - Running cycle 1/1 for kraken test... 09:57:35 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp9zc2FB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:38 INFO - PROCESS | 1653 | 2017-03-01 09:57:38.250 plugin-container[1654:10646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:57:38 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:38 INFO - PROCESS | 1653 | 2017-03-01 09:57:38.251 plugin-container[1654:10646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 09:57:38 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:38 INFO - PROCESS | 1653 | 2017-03-01 09:57:38.251 plugin-container[1654:10646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:47 INFO - PROCESS | 1653 | RSS: Main: 218464256 09:57:47 INFO - PROCESS | 1653 | 09:57:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:51 INFO - PROCESS | 1653 | RSS: Main: 233156608 09:57:51 INFO - PROCESS | 1653 | 09:57:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 09:57:54 INFO - PROCESS | 1653 | RSS: Main: 241152000 09:57:54 INFO - PROCESS | 1653 | 09:57:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 09:57:58 INFO - PROCESS | 1653 | RSS: Main: 244809728 09:57:58 INFO - PROCESS | 1653 | 09:58:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 09:58:01 INFO - PROCESS | 1653 | RSS: Main: 245338112 09:58:01 INFO - PROCESS | 1653 | 09:58:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:03 INFO - PROCESS | 1653 | RSS: Main: 245198848 09:58:03 INFO - PROCESS | 1653 | 09:58:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:07 INFO - PROCESS | 1653 | RSS: Main: 250691584 09:58:07 INFO - PROCESS | 1653 | 09:58:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:11 INFO - PROCESS | 1653 | RSS: Main: 250912768 09:58:11 INFO - PROCESS | 1653 | 09:58:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 09:58:15 INFO - PROCESS | 1653 | RSS: Main: 249917440 09:58:15 INFO - PROCESS | 1653 | 09:58:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:17 INFO - PROCESS | 1653 | RSS: Main: 248918016 09:58:17 INFO - PROCESS | 1653 | 09:58:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:20 INFO - PROCESS | 1653 | RSS: Main: 248963072 09:58:20 INFO - PROCESS | 1653 | 09:58:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:22 INFO - PROCESS | 1653 | RSS: Main: 249024512 09:58:22 INFO - PROCESS | 1653 | 09:58:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:26 INFO - PROCESS | 1653 | RSS: Main: 249401344 09:58:26 INFO - PROCESS | 1653 | 09:58:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:30 INFO - PROCESS | 1653 | RSS: Main: 249442304 09:58:30 INFO - PROCESS | 1653 | 09:58:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 09:58:32 INFO - PROCESS | 1653 | RSS: Main: 251396096 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | __start_tp_report 09:58:32 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:58:32 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:58:32 INFO - PROCESS | 1653 | |i|pagename|runs| 09:58:32 INFO - PROCESS | 1653 | |0;ai-astar;103;117;117;118;118;116;119;117;117;118 09:58:32 INFO - PROCESS | 1653 | |1;audio-beat-detection;142;115;115;145;131;114;115;111;130;114 09:58:32 INFO - PROCESS | 1653 | |2;audio-dft;196;201;191;195;205;194;196;196;196;197 09:58:32 INFO - PROCESS | 1653 | |3;audio-fft;80;59;60;88;61;61;60;60;72;60 09:58:32 INFO - PROCESS | 1653 | |4;audio-oscillator;82;71;86;73;70;72;85;58;91;112 09:58:32 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;88;91;88;90;86;91;102;89;132 09:58:32 INFO - PROCESS | 1653 | |6;imaging-darkroom;100;129;101;101;140;173;135;106;100;106 09:58:32 INFO - PROCESS | 1653 | |7;imaging-desaturate;93;127;125;131;75;149;90;146;78;75 09:58:32 INFO - PROCESS | 1653 | |8;json-parse-financial;60;59;61;71;98;95;98;64;63;62 09:58:32 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;68;75;71;75;74;69;71;72;70;71 09:58:32 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;105;106;84;87;106;78;100;104;71;97 09:58:32 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;141;127;142;142;151;161;159;141;240;139 09:58:32 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;167;172;168;171;170;173;167;170;169;169 09:58:32 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;69;64;68;66;70;65;67;67;67;65 09:58:32 INFO - PROCESS | 1653 | __end_tp_report 09:58:32 INFO - PROCESS | 1653 | __start_cc_report 09:58:32 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,970 09:58:32 INFO - PROCESS | 1653 | __end_cc_report 09:58:32 INFO - PROCESS | 1653 | __startTimestamp1488391112059__endTimestamp 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:58:32 INFO - PROCESS | 1653 | Number of tests: 14 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.50 stddev:4.64 (4.0%) stddev-sans-first:0.88 09:58:32 INFO - PROCESS | 1653 | Values: 103.0 117.0 117.0 118.0 118.0 116.0 119.0 117.0 117.0 118.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:122.50 stddev:12.72 (10.4%) stddev-sans-first:11.53 09:58:32 INFO - PROCESS | 1653 | Values: 142.0 115.0 115.0 145.0 131.0 114.0 115.0 111.0 130.0 114.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.70 Median:196.00 stddev:3.83 (2.0%) stddev-sans-first:4.06 09:58:32 INFO - PROCESS | 1653 | Values: 196.0 201.0 191.0 195.0 205.0 194.0 196.0 196.0 196.0 197.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:66.10 Median:61.00 stddev:10.32 (16.9%) stddev-sans-first:9.65 09:58:32 INFO - PROCESS | 1653 | Values: 80.0 59.0 60.0 88.0 61.0 61.0 60.0 60.0 72.0 60.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:77.50 stddev:14.86 (19.2%) stddev-sans-first:15.75 09:58:32 INFO - PROCESS | 1653 | Values: 82.0 71.0 86.0 73.0 70.0 72.0 85.0 58.0 91.0 112.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:89.50 stddev:13.80 (15.4%) stddev-sans-first:14.53 09:58:32 INFO - PROCESS | 1653 | Values: 90.0 88.0 91.0 88.0 90.0 86.0 91.0 102.0 89.0 132.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:119.10 Median:117.50 stddev:24.54 (20.9%) stddev-sans-first:25.04 09:58:32 INFO - PROCESS | 1653 | Values: 100.0 129.0 101.0 101.0 140.0 173.0 135.0 106.0 100.0 106.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:108.90 Median:75.00 stddev:29.67 (39.6%) stddev-sans-first:30.91 09:58:32 INFO - PROCESS | 1653 | Values: 93.0 127.0 125.0 131.0 75.0 149.0 90.0 146.0 78.0 75.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:73.10 Median:67.50 stddev:16.83 (24.9%) stddev-sans-first:17.17 09:58:32 INFO - PROCESS | 1653 | Values: 60.0 59.0 61.0 71.0 98.0 95.0 98.0 64.0 63.0 62.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:2.41 (3.4%) stddev-sans-first:2.18 09:58:32 INFO - PROCESS | 1653 | Values: 68.0 75.0 71.0 75.0 74.0 69.0 71.0 72.0 70.0 71.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:74.50 stddev:12.86 (17.3%) stddev-sans-first:12.98 09:58:32 INFO - PROCESS | 1653 | Values: 105.0 106.0 84.0 87.0 106.0 78.0 100.0 104.0 71.0 97.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:154.30 Median:146.50 stddev:31.72 (21.7%) stddev-sans-first:33.28 09:58:32 INFO - PROCESS | 1653 | Values: 141.0 127.0 142.0 142.0 151.0 161.0 159.0 141.0 240.0 139.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:170.00 stddev:2.01 (1.2%) stddev-sans-first:1.90 09:58:32 INFO - PROCESS | 1653 | Values: 167.0 172.0 168.0 171.0 170.0 173.0 167.0 170.0 169.0 169.0 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.87 (2.8%) stddev-sans-first:1.81 09:58:32 INFO - PROCESS | 1653 | Values: 69.0 64.0 68.0 66.0 70.0 65.0 67.0 67.0 67.0 65.0 09:58:32 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:58:32 INFO - PROCESS | 1653 | 09:58:32 INFO - TEST-INFO | 1653: exit 0 09:58:32 INFO - TEST-OK | kraken | took 60709ms 09:58:32 INFO - SUITE-END | took 1176s 09:58:33 INFO - Completed test suite (00:19:36) 09:58:33 INFO - javascript benchmark 09:58:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7286.157873564416, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2625.973740262598, 2816.8028238023335, 2971.91084267472, 3393.16867367495, 3437.5187473753663, 4989.301497790312, 5690.3171619405675, 5700.401457846923, 5746.942530574693, 5779.479846813786, 5699.458551437607, 5707.828765137042, 5709.6574205547595, 5713.485786279234, 5713.885722285552, 5190.610704197182, 5225.790968361261, 5265.999460102587, 5266.078436273656, 5276.182191760264, 5252.343457067866, 5289.153735402337, 5292.285541451894, 5297.602679799012, 5309.73451327434, 5198.974005129969, 5244.239585260135, 5285.445028272034, 5288.286081379022, 5337.3061502004875, 5284.890173063662, 5286.947130528684, 5307.416184219733, 5312.707801070939, 5321.148616221425, 4237.851675191384, 5160.14857548504, 5260.210968354739, 5272.393674727417, 5327.46725327468, 5759.107338362561, 5782.277215348082, 5788.710564471759, 5799.159121927297, 5809.302883653946, 6058.666773327465, 6123.557162997608, 6181.165542651729, 6207.565470417073, 6213.0, 6249.156363890862, 6264.373562643699, 6286.685665716742, 6292.905606415908, 6307.990721484586, 5845.912311315334, 5856.677882716449, 5870.412958704138, 5873.972054890377, 5902.793402230945, 6223.782167624111, 6260.123582698426, 6304.085907543381, 6322.272938612026, 6329.240491141, 6126.151269885539, 6147.477464415485, 6162.383761623802, 6257.530685198628, 6260.0, 6268.874622507525, 6290.748370065146, 6310.05349197624, 6312.589681670768, 6319.652419116901, 5633.746481408347, 5793.507551858139, 6278.277998030194, 6280.654563999024, 6298.0, 6166.938330616636, 6169.907451388232, 6178.567500274938, 6210.006398976142, 6307.842303942438, 5001.024800163933, 5006.07387633289, 5017.347744793154, 5034.949650503521, 5044.495550444926, 4479.193745125908, 4492.124035812985, 4497.550244975541, 4506.684532082788, 4540.160070386989, 5702.6293290935955, 5728.312602487728, 5744.569157313218, 5755.482006619424, 5785.508231800344, 6237.0, 6258.311585725566, 6265.843353916189, 6269.749210031548, 6283.403076707797, 5757.0, 5780.16187652788, 5797.130430435384, 5815.854603634858, 5819.854503637358, 5382.03123437785, 5415.566754659618, 5423.484768946943, 5434.402215756264, 5460.590455715757, 5419.783208671609, 6145.6927153641245, 6257.155284036597, 6289.9371006291185, 6301.432871041445, 5230.503102205284, 6100.1154832548145, 6243.250809902841, 6257.78097766576, 6285.5600107992, 5264.815731449381, 6295.244570651551, 6339.524535659752, 6364.936350636434, 6407.167068281094, 6118.847028824315, 6129.817282374446, 6131.437835141539, 6143.96928015357, 6224.128621992834, 4858.781354839111, 5647.983362994701, 6263.6241825490615, 6287.339829317856, 6312.274088479883, 5328.987492376436, 6202.131701561875, 6207.348228435949, 6235.96882015587, 6240.188775459343, 6049.637021778707, 6090.512758979359, 6102.420270074336, 6123.877522449615, 6220.471259942956, 5898.675572843535, 5948.940510595012, 5965.552583556338, 6052.061930400795, 6054.485368743706, 6152.569320147547, 6192.225971753531, 6195.628262304276, 6198.783042593487, 6214.316425193135, 5865.794697185578, 5964.373740757332, 5986.311574169026, 6041.214642096499, 6059.757609695563], "value": 5680.187786995873, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.5369769137789, 474.7887190200361, 499.18881817047276, 515.3377909386439, 525.976974784045, 38628.80685596572, 40657.39013914794, 40818.18363632725, 40887.0, 40912.795436022854, 42356.57643423565, 42895.57104428955, 42976.785116074374, 43091.78454107725, 43093.35359969603, 8251.711190108348, 8473.915260847374, 9080.636774529028, 9314.86027709585, 9331.160195582395, 11032.558697652124, 11326.943365283203, 11345.43272836359, 11401.885981140165, 11455.312681239111, 11236.269642473255, 11239.943800280987, 11270.380129092897, 11313.321200727942, 11315.490802913848, 11119.110471162288, 11162.441877906112, 11211.439428028565, 11227.27022743519, 11240.718982025473, 11179.161562882817, 11278.210525263235, 11282.830757538644, 11297.548098076068, 11306.886931130666, 39935.800320998496, 40195.799021005, 40277.59722402797, 40378.394324085166, 40382.192356153006, 3913.882583522499, 3931.4495970564144, 3963.6829053675638, 3973.662238709713, 3983.223271462066, 28159.859200703864, 29267.853660731773, 29385.265368365748, 29424.264393390124, 29503.0, 3135.827529485877, 3183.220111072802, 3249.723773479257, 3351.2124650707065, 3353.1281866714585, 419802.9009854962, 424335.0, 426557.0, 427878.0, 430434.847825762, 57111.0, 57276.0, 57355.42644573501, 57621.71189144111, 57754.0, 55051.724741376034, 55898.0, 55941.0, 55958.44041559614, 55976.44023559794, 12422.689432764253, 16017.599560011093, 16042.839571604134, 16052.438164664181, 16088.839111608968, 9143.817123657489, 11542.538298467967, 12268.018558515298, 12498.125131240726, 12601.936990314989, 18051.458456246335, 18073.36743213981, 18112.45662630123, 18125.456236312933, 18178.90926544385, 6426.746784377002, 7761.0, 7803.336716379058, 7812.492188007875, 7830.686772529149, 782.6243403166512, 880.4673172730534, 882.3382463152635, 905.791667916383, 914.2457472585144, 26022.86988565045, 30229.69770302313, 30380.0, 31108.0, 31202.68797312043, 825.8472182646229, 1082.1451053667515, 1092.180864351736, 1100.389283947417, 1180.9489554296683, 13359.465621375231, 15642.608934776721, 15843.0, 15905.602359940862, 16111.838881611267, 479056.0, 510011.0, 569704.0, 571853.0, 574133.0, 59166.70416647889, 59710.40289597048, 59764.0, 59859.0, 59877.40122598718, 1227.6685294970487, 1256.7549327881159, 1421.7085497472992, 1462.817147856518, 1470.9558713238835, 27728.168154955383, 29002.7099729, 29209.831606735494, 30043.69956300409, 30276.0, 51065.978680426924, 51385.74307128441, 51449.0, 51469.0, 51600.0, 529388.0, 535334.0, 538318.0, 539640.0, 540245.0, 25307.620385694598, 25902.611460827724, 25994.610080848423, 26338.078167264055, 26604.201873943814, 41875.371869421375, 42695.786521067195, 43158.0, 43709.68870933877, 43826.0, 35296.11759705925, 35312.47031294481, 35479.29041419209, 35531.0, 36711.449328259565, 35256.0, 35584.0, 36091.09772255715, 36376.27247455089, 36386.0, 34266.82866585651, 34368.65631343654, 34374.312513749086, 35176.0, 35299.11752206215, 29755.958541450946, 31337.0, 31445.213869652525, 31516.0, 31563.73745050172, 23838.88080559586, 23895.163669271493, 23990.28029159128, 24150.51698966046, 24249.636255456535], "value": 19618.020714274055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.17519879476004, 387.80997311317464, 389.827088060561, 402.2718878829318, 418.7257346438082, 16645.750313745302, 20100.89949550252, 20895.373138805822, 21247.893760531213, 21268.57462850742, 21163.47091322718, 21817.454563635918, 22043.0, 22048.779512204874, 22256.777432225674, 5559.332880054389, 5857.0, 5864.23764910561, 5866.6480011199465, 5870.26621672291, 5470.124780035195, 5687.9146812797635, 5723.742431590588, 5766.625169363978, 5818.563607729416, 5674.035413979634, 5674.886502269952, 5679.4036626154275, 5681.0, 5696.0, 5474.698891560983, 5671.120976248688, 5709.172170035363, 5731.885362292773, 5753.194552762575, 5057.367829021372, 5088.720120393377, 5093.643444958854, 5106.361704786902, 5117.078925793356, 26257.868710656323, 26364.0, 26758.33104172392, 26908.923643054255, 27166.864165679242, 2231.5760005598886, 2640.722724113979, 2710.9322266943486, 2747.7252274772563, 2776.972230277692, 14827.48103816372, 15817.0, 15863.20683965797, 16078.0, 16394.672106557922, 1813.5466133466634, 1851.463075708043, 1866.188208129468, 1882.632886587116, 1883.340830709241, 118433.81566184314, 121515.0, 124707.0, 127268.0, 128177.0, 9469.573869175903, 11452.0, 11856.940715296369, 11873.228240164362, 12114.454849531792, 13345.199288042748, 13947.0, 15180.696386072217, 15196.468123615841, 15222.771658425132, 8514.276286515715, 8817.515036673045, 8848.67269909506, 8894.777630559158, 9142.862857057149, 7662.846743065219, 8872.0, 8894.822103558023, 8918.464892106495, 8919.777005574913, 7591.164971853092, 7690.701143709597, 7704.499207551491, 7759.7672069838, 7779.027621547307, 1685.502776680882, 1985.7716362618485, 2039.5411032517566, 2041.2345370486069, 2096.22439697313, 858.9098144694842, 889.2574700125467, 890.5324704530174, 913.2374467319862, 921.1755478846395, 20954.790452095283, 21312.04095815692, 21404.25085122013, 21446.678299825515, 21579.89210053971, 757.9128400233933, 900.1133883125117, 924.861270809384, 928.252756530999, 931.4411353188033, 9331.440113593206, 9901.356411833207, 11296.604618838466, 11323.60367387154, 11428.314301141958, 633801.0, 701629.4918525375, 739846.0, 744142.0, 757211.0, 111698.0, 137838.0, 138651.61348386388, 142926.0, 144059.0, 956.5791051937125, 967.322873988197, 992.3153024413132, 1024.9897501025043, 1047.675220681591, 19484.928328942045, 19714.704279436108, 20326.695099573815, 20946.685799713323, 21388.679169812152, 59304.0, 59856.10935289192, 60026.19921402365, 60606.39393606008, 61125.0, 63775.0, 66530.0, 84520.0, 84635.30729385254, 84874.57562712394, 5931.051031834886, 6403.64779937108, 6994.300569943169, 8205.95897020511, 8535.53054582004, 12239.75520489603, 12283.938580307042, 12836.0, 13215.273159976294, 13296.534621288209, 5507.366652834975, 7284.635768211674, 9244.214241789254, 11321.830172547254, 11574.768504629692, 8922.821543568962, 9567.712968610953, 9748.463834488888, 11212.43937803123, 11506.597269095542, 9158.587863546023, 11370.54517819311, 11578.42107894585, 11777.0, 12085.697857553632, 6181.907271391023, 7233.746818861315, 7575.24247575238, 7742.303192712683, 8671.826563468569, 9855.507224638883, 11084.667459976214, 11384.032357249394, 11438.370889601152, 11481.885181148416], "value": 9853.447245173187, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.9074460160089, 1435.9824446652444, 1456.0753921259998, 1457.1475686723265, 1464.9121052736834, 7634.0, 7907.0, 8095.150009249032, 8535.615897284622, 8575.313974882003, 8134.471259368137, 8450.36622253332, 8453.816465694792, 8463.534505602205, 8477.406581539295, 2323.61660326046, 2360.0559776089617, 2365.763423657629, 2367.7277113131954, 2372.0511795281855, 2338.088145623208, 2368.2303251443263, 2371.1108334374608, 2375.0024989504363, 2377.465070359173, 2342.0631747301045, 2343.1915988983774, 2352.8235382346393, 2363.267387109993, 2367.242482405631, 2253.515494168745, 2263.6870615043235, 2290.03818396274, 2290.2213247495765, 2291.049214575949, 2320.4453016478833, 2339.5671800716923, 2359.8466099703464, 2361.633946738258, 2365.1367250953276, 8488.448250863674, 8534.146585341477, 8552.444091134057, 8562.74311770648, 8572.014218364842, 5000.0999820032375, 5044.646874718771, 5188.273641690166, 5201.453847346012, 5228.424873263938, 3363.9495407568907, 3485.9651403486146, 3582.3372676055014, 3583.050491619723, 3592.3354179476883, 1163.7905177068124, 1212.605903081502, 1247.7878760610627, 1249.7562975219835, 1251.6307689231699, 10767.676969690921, 10817.188710846718, 10893.67318980424, 10956.506957186866, 10978.286411383315, 4415.889602759957, 4578.397560853675, 4659.860204193879, 4667.906641867144, 4772.427308722906, 9674.9032509674, 9785.461799601091, 9798.706038818704, 9803.215742740564, 9814.558344874498, 5246.344206974129, 5309.814156504503, 5310.097283461821, 5328.014317351302, 5328.893422131536, 4245.808938597709, 4323.221820072417, 4752.287156926489, 4787.688800227974, 4834.250691142878, 3888.650021498079, 3924.9215015699524, 3998.3402738547816, 4021.115354622037, 4043.353063509824, 1283.1018287199, 1301.811237370576, 1307.0654482045384, 1316.6642506160867, 1317.3479127831786, 924.4961495984704, 931.4225180388203, 938.7981583959481, 943.4669411782321, 975.4683697384942, 5430.701311427831, 5540.695261760561, 5824.417558244142, 5836.358000619928, 5890.263717035371, 859.0, 896.5923500104903, 968.2157452463528, 1031.9845202321972, 1037.7613148975777, 3079.4918838391864, 3594.8382322795537, 3790.65884070435, 3859.807009649506, 3880.1851611161974, 8203.7949051273, 9787.0, 10133.290669653054, 10147.441890696082, 10147.441890696082, 8675.132486751401, 10071.848922266172, 10085.0, 10105.29262951601, 10131.0, 7482.802751559724, 7765.417593680497, 7820.0, 7894.684212631545, 7926.48477848938, 5738.856528586819, 5797.246357973437, 5889.52883769306, 5893.557983151195, 5933.821985340447, 8731.276916774175, 9048.41185322952, 9181.77045573866, 9284.0, 9303.488308143116, 8956.014838367644, 9188.770280743034, 9261.861072084059, 9323.347365684336, 9384.390014649027, 6701.899471508029, 6912.343327383891, 7039.260877607778, 7200.531965422231, 7330.853382932205, 7856.332211762065, 8036.959815200887, 8039.557824319719, 8061.31478824283, 8072.5963701815845, 6931.341522555349, 7117.7152913882865, 7247.637618118967, 7310.268973102648, 7363.374113200223, 6846.028787818645, 6969.7909062728195, 7008.404285635778, 7077.858442831218, 7123.109611298588, 6380.968095159495, 6389.808305750835, 6478.255000674794, 6482.675866206765, 6491.513136514686, 4954.5788607968725, 4986.401631804207, 5003.3495645565845, 5036.143855544639, 5062.265971434195, 4939.901201975868, 5007.84976450707, 5015.1474249378425, 5113.795448182031, 5135.204043373283], "value": 4470.33991025614, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.6871531264205, 346.82426573914427, 351.7748640869845, 353.219385158799, 356.056450406423, 4563.88590285243, 4773.57037866592, 5042.495750424951, 5338.199270109486, 5416.8104116355935, 4965.478624744395, 5089.803896084417, 5417.945820541793, 5450.29146210992, 5481.314835645544, 3402.625711171769, 3694.926101477969, 3945.737364043507, 4146.3158578834455, 4171.270027745148, 3223.4842425211973, 3371.410003249434, 4010.6590939770153, 4023.903785764569, 4077.776666999888, 3734.4958430611928, 4057.5942405759483, 4097.795110244476, 4104.384342348657, 4172.895677608069, 4099.918001639965, 4138.317177665682, 4144.254034273845, 4153.626173644357, 4154.4183814266025, 3424.0, 3567.7324200684793, 3572.678458938682, 3574.5692928808658, 3583.4266517357096, 4367.934480982788, 4535.705179163344, 4581.289900065462, 4939.382577177853, 5187.948120518784, 3614.8011859347725, 3886.8639597613947, 4444.822207111712, 4578.793954272066, 4693.389859318301, 5432.592555558349, 5541.639793413416, 5757.798477053325, 5774.498630356095, 5782.537397008229, 3863.0298547740854, 3884.553276373225, 3885.417187421881, 3886.24218277436, 3894.7273690841653, 5473.562115030828, 5575.940571291441, 5754.88490230193, 5902.852428689317, 6004.829058333627, 3497.755157138976, 3503.562054743157, 3930.270680900395, 3932.980335098306, 3972.1261322509004, 3393.5927688677516, 3821.522309711286, 4029.315016447211, 4031.7544376776978, 4039.5354534228354, 4565.516207232663, 4681.6825671292245, 4684.882877928079, 4684.969306752509, 4712.575868171881, 4720.740359280204, 4912.852614421573, 5731.971340143273, 5824.0, 5836.824895253149, 1147.5926046253626, 1169.497116240008, 1246.1712960881093, 1289.3682095773004, 1304.8890844278342, 700.3731660164226, 757.81812365032, 817.7669364231166, 877.4735158904733, 888.480239060157, 4477.798499067485, 5113.335266415343, 5140.254663073833, 5180.948190518046, 5196.740162991911, 5382.73086345689, 5434.8913021739345, 5695.971520142372, 5745.0, 5752.676884316631, 4447.552447552447, 4577.633789296849, 4636.281376386598, 4849.636277279218, 5244.685318880879, 2723.3872378715027, 2845.331347133422, 2966.718161774628, 2995.0, 3012.4335345620157, 4889.853304400874, 5794.913076303858, 5818.5636077294375, 5883.852903677443, 5928.525717942554, 5476.698781566973, 5731.917995626147, 5775.563539693875, 5810.970945145247, 5864.0, 5315.813946511854, 5833.499900060015, 5900.784072680313, 5940.851478713067, 5962.761489540369, 5329.493698098674, 5583.715745378505, 5719.542436605145, 5721.427857214246, 5764.538836893123, 5909.645421274738, 5916.822495325147, 5918.7928422505, 5933.202750239764, 5945.948647716531, 5078.333874715656, 5424.294841670558, 5615.775368985195, 5936.732847021808, 6068.8786224276155, 3508.8947331580093, 3925.666318362971, 3964.592569637852, 3981.2037592481042, 4005.1989602079116, 5849.912251316148, 5996.640201587918, 6000.97983342824, 6017.18767966319, 6027.336992930862, 5680.488756011979, 5720.570957178145, 5741.91387129185, 5749.770009199585, 5805.651660900359, 5763.337216220189, 5773.422657734361, 5781.5663825214115, 5787.652740835395, 5805.896879593033, 5264.552513036281, 5329.7868085277705, 5419.485148910847, 5507.0, 5640.661560306395, 5687.0, 5762.654240745569, 5790.9152705027, 5823.631446609958, 5847.970760146172, 4721.8819529510665, 4995.975020124876, 5690.8577285566535, 5711.228984087095, 5754.942450575608], "value": 4115.468259062258, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.2227527444539, 705.9647017649123, 770.3721467004391, 811.5693497973568, 817.0388291754253, 14881.255937203137, 15285.541433756976, 15628.984116032465, 15807.920960395197, 15911.681766364665, 13522.0, 14149.787753183711, 14154.938379621519, 14592.635184120401, 14619.195944223064, 843.0557775291667, 857.4340934982913, 859.2867919626694, 859.6088779605265, 859.7463748194298, 671.7816709569385, 692.4149094015548, 838.8657814749641, 849.7875531117221, 851.4040171879678, 902.7246689759621, 913.4747520175893, 915.5422288855572, 918.5682729117304, 919.0350132361027, 904.4889637354906, 911.0616065452595, 911.8221946720392, 912.16536868765, 914.8536234202529, 671.2079745899837, 674.9639303668894, 675.3449154320301, 676.7597502886454, 677.0960767375559, 14863.628409289746, 15318.463853765064, 15462.30419631108, 15528.0, 15616.141112238822, 13630.318484075757, 13864.930675346559, 14099.718005639932, 14099.78850317246, 14157.504487342996, 11321.865752453932, 11504.769904601944, 11567.421628918604, 11607.361595112265, 11686.824697629543, 3528.982355088234, 3569.161247106928, 3586.7309951753464, 3614.060344310472, 3718.2563487302377, 113828.0, 114558.28162577485, 115081.0, 116464.99999999916, 117334.0, 10408.47957602117, 10582.682519524427, 10594.841077383846, 10602.416867072385, 10864.348139111678, 22357.441063973532, 22557.323280301618, 22558.548829023326, 22569.322920312417, 22650.0, 7568.0, 7583.203763604853, 7588.354989825816, 7710.653020614086, 7756.53460792343, 4139.627433530996, 4164.729292595972, 4227.8097485613225, 4229.0, 4293.97853010733, 3410.9317813644084, 3493.371193185251, 3506.561679790026, 3507.964920350764, 3511.5610548681416, 551.3989751171242, 636.2492259134266, 637.3148864970176, 638.4955884850916, 645.4126744662428, 669.1668872254074, 686.3171144711044, 693.3240090911321, 709.2127738210582, 722.4762047515509, 8697.043325234219, 9527.618895244252, 9883.369244074791, 10086.24353173515, 10251.692449226535, 600.7997466149384, 685.413971054751, 689.689639662154, 711.2887112887113, 715.2316566888031, 4594.356790049395, 4864.73243971585, 4877.097736918681, 4887.95112048882, 4889.853304400874, 103120.0, 106405.46797265964, 106473.40289895657, 106712.93287067185, 108076.4596177014, 68010.57931234453, 73944.0, 74393.6280318595, 74810.87783683249, 78841.60579197182, 74733.0, 74882.6255868728, 76201.0, 76846.6157669208, 77169.2283077162, 9594.472304023346, 10447.895521044693, 10554.788904222027, 10583.0, 10740.409277489813, 31832.68167318297, 43861.12277754491, 45088.774556127006, 46218.76890615525, 47409.0, 57630.13554796765, 59403.0, 59796.40203597908, 61370.0, 62275.065874012835, 12327.198732082386, 12549.0, 12756.425960831599, 12783.23300601967, 12828.219702605962, 13995.0, 14166.78749818733, 14186.432542698176, 14411.639709007359, 14466.710665786415, 12867.0, 13224.669383265495, 13451.125676830974, 13890.097143685629, 13952.790708139182, 12825.0, 13278.80081798793, 13880.0, 13885.444582216598, 14078.084924479876, 11184.832227516756, 12742.362881856056, 13345.599632011055, 13357.732845343233, 13538.458461661423, 10613.363198207824, 11336.489857956576, 11418.942905285421, 11711.329079452971, 11774.94112529432, 10677.46612669379, 10767.515461804081, 10887.673369798918, 10892.292001019909, 11058.22592418523], "value": 7405.923756174903, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 117.0, 118.0, 118.0, 116.0, 119.0, 117.0, 117.0, 118.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 115.0, 145.0, 131.0, 114.0, 115.0, 111.0, 130.0, 114.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 191.0, 195.0, 205.0, 194.0, 196.0, 196.0, 196.0, 197.0], "value": 196.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 88.0, 61.0, 61.0, 60.0, 60.0, 72.0, 60.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 86.0, 73.0, 70.0, 72.0, 85.0, 58.0, 91.0, 112.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 88.0, 90.0, 86.0, 91.0, 102.0, 89.0, 132.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 129.0, 101.0, 101.0, 140.0, 173.0, 135.0, 106.0, 100.0, 106.0], "value": 119.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 127.0, 125.0, 131.0, 75.0, 149.0, 90.0, 146.0, 78.0, 75.0], "value": 108.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 71.0, 98.0, 95.0, 98.0, 64.0, 63.0, 62.0], "value": 73.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 71.0, 75.0, 74.0, 69.0, 71.0, 72.0, 70.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 84.0, 87.0, 106.0, 78.0, 100.0, 104.0, 71.0, 97.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 127.0, 142.0, 142.0, 151.0, 161.0, 159.0, 141.0, 240.0, 139.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 168.0, 171.0, 170.0, 173.0, 167.0, 170.0, 169.0, 169.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 68.0, 66.0, 70.0, 65.0, 67.0, 67.0, 67.0, 65.0], "value": 66.8, "unit": "score"}]}]} 09:58:33 INFO - Return code: 0 09:58:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:33 ERROR - # TBPL SUCCESS # 09:58:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:33 INFO - 2017-03-01 09:58:33.353 screenresolution[1658:11042] starting screenresolution argv=screenresolution get 09:58:33 INFO - 2017-03-01 09:58:33.356 screenresolution[1658:11042] Display 0: 1600x1200x32@60 09:58:33 INFO - 2017-03-01 09:58:33.362 screenresolution[1659:11045] starting screenresolution argv=screenresolution list 09:58:33 INFO - Available Modes on Display 0 09:58:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:33 INFO - Intel Iris: 09:58:33 INFO - Chipset Model: Intel Iris 09:58:33 INFO - Type: GPU 09:58:33 INFO - Bus: Built-In 09:58:33 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:33 INFO - Vendor: Intel (0x8086) 09:58:33 INFO - Device ID: 0x0a2e 09:58:33 INFO - Revision ID: 0x0009 09:58:33 INFO - Displays: 09:58:33 INFO - Display: 09:58:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:33 INFO - Display Serial Number: 1600x1200 60 09:58:33 INFO - Main Display: Yes 09:58:33 INFO - Mirror: Off 09:58:33 INFO - Online: Yes 09:58:33 INFO - Rotation: Supported 09:58:33 INFO - Return code: 0 09:58:33 INFO - Running post-action listener: _resource_record_post_action 09:58:33 INFO - [mozharness: 2017-03-01 17:58:33.605120Z] Finished run-tests step (success) 09:58:33 INFO - Running post-run listener: _resource_record_post_run 09:58:33 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 33423872; Write bytes: 511363072; Read time: 198; Write time: 1616 09:58:33 INFO - TinderboxPrint: CPU usage
54.2% 09:58:33 INFO - TinderboxPrint: I/O read bytes / time
33,423,872 / 198 09:58:33 INFO - TinderboxPrint: I/O write bytes / time
511,363,072 / 1,616 09:58:33 INFO - TinderboxPrint: CPU idle
2,195.8 (45.8%) 09:58:33 INFO - TinderboxPrint: CPU system
956.0 (20.0%) 09:58:33 INFO - TinderboxPrint: CPU user
1,637.8 (34.2%) 09:58:33 INFO - TinderboxPrint: Swap in / out
237,821,952 / 0 09:58:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201380352; Write bytes: 199008256; Read time: 15706; Write time: 421 09:58:33 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31638016; Write bytes: 301664256; Read time: 184; Write time: 1160 09:58:34 INFO - Running post-run listener: _upload_blobber_files 09:58:34 INFO - Blob upload gear active. 09:58:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:34 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 09:58:34 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:34 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:34 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:58:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:58:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:35 INFO - (blobuploader) - INFO - Done attempting. 09:58:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:58:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:58:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:36 INFO - (blobuploader) - INFO - Done attempting. 09:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:37 INFO - (blobuploader) - INFO - Done attempting. 09:58:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:40 INFO - (blobuploader) - INFO - Done attempting. 09:58:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:40 INFO - Return code: 0 09:58:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a49a13a088a795e09746c3be5ae07b87de64c6f01e698c655c73ecc673c3d13bcdadc9dbcaac0e25759872e2627b1ebcca6c5f98bffabcbc52037806526039e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/900185807a61b570ba9b210be54bff7e9e3b543134b94201a14fca898ca9e2bd57cc81206891f7ffe41c205f0af9c92668d82eba31085ed5eed4cd5e6d4d0a2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fbfb8940c1c2a018180b0496192fb4b43ccc7b21997731b4f8e5f5aaff4d7f315055df9d83fa41744865058491bbadc144f0a7963c94124fc2be5bae1eb0cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3d8dacf9223c683c0608173773386aaaa54895841fbd85fce96c162f1cbc491bf6bf1eb8c97342f5d74dfe5baf32cb3e91dad538f761ed8b141fa9d5fbb1bac"} 09:58:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:40 INFO - Contents: 09:58:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a49a13a088a795e09746c3be5ae07b87de64c6f01e698c655c73ecc673c3d13bcdadc9dbcaac0e25759872e2627b1ebcca6c5f98bffabcbc52037806526039e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/900185807a61b570ba9b210be54bff7e9e3b543134b94201a14fca898ca9e2bd57cc81206891f7ffe41c205f0af9c92668d82eba31085ed5eed4cd5e6d4d0a2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fbfb8940c1c2a018180b0496192fb4b43ccc7b21997731b4f8e5f5aaff4d7f315055df9d83fa41744865058491bbadc144f0a7963c94124fc2be5bae1eb0cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3d8dacf9223c683c0608173773386aaaa54895841fbd85fce96c162f1cbc491bf6bf1eb8c97342f5d74dfe5baf32cb3e91dad538f761ed8b141fa9d5fbb1bac"} 09:58:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:40 INFO - Copying logs to upload dir... 09:58:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.929539 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-01 09:58:40.732429) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 09:58:40.735977) ========= 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.uyVjPO0jGw/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.agDOlZ0URa/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-Aurora/sha512/8a49a13a088a795e09746c3be5ae07b87de64c6f01e698c655c73ecc673c3d13bcdadc9dbcaac0e25759872e2627b1ebcca6c5f98bffabcbc52037806526039e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/900185807a61b570ba9b210be54bff7e9e3b543134b94201a14fca898ca9e2bd57cc81206891f7ffe41c205f0af9c92668d82eba31085ed5eed4cd5e6d4d0a2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fbfb8940c1c2a018180b0496192fb4b43ccc7b21997731b4f8e5f5aaff4d7f315055df9d83fa41744865058491bbadc144f0a7963c94124fc2be5bae1eb0cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3d8dacf9223c683c0608173773386aaaa54895841fbd85fce96c162f1cbc491bf6bf1eb8c97342f5d74dfe5baf32cb3e91dad538f761ed8b141fa9d5fbb1bac"} build_url:https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011676 build_url: 'https://queue.taskcluster.net/v1/task/Bt8YpREfSCiqbrrFEcIgoQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a49a13a088a795e09746c3be5ae07b87de64c6f01e698c655c73ecc673c3d13bcdadc9dbcaac0e25759872e2627b1ebcca6c5f98bffabcbc52037806526039e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/900185807a61b570ba9b210be54bff7e9e3b543134b94201a14fca898ca9e2bd57cc81206891f7ffe41c205f0af9c92668d82eba31085ed5eed4cd5e6d4d0a2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fbfb8940c1c2a018180b0496192fb4b43ccc7b21997731b4f8e5f5aaff4d7f315055df9d83fa41744865058491bbadc144f0a7963c94124fc2be5bae1eb0cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3d8dacf9223c683c0608173773386aaaa54895841fbd85fce96c162f1cbc491bf6bf1eb8c97342f5d74dfe5baf32cb3e91dad538f761ed8b141fa9d5fbb1bac"}' ========= master_lag: 0.74 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 09:58:41.483243) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 09:58:41.483752) ========= 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.uyVjPO0jGw/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.agDOlZ0URa/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.005228 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 09:58:41.525066) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 09:58:41.525471) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 09:58:41.527138) ========= ========= Total master_lag: 1.16 =========