builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1490271911.15 results: success (0) buildid: 20170323035458 builduid: 0a82eed6b0bf44d2843339ecc1b2c889 revision: 2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.148049) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.148509) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.148782) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.175754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.176160) ========= 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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-23 05:25:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2017-03-23 05:25:11 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.328217) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.328532) ========= 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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.028932 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.378900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:11.379254) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-23 05:25:11,480 truncating revision to first 12 chars 2017-03-23 05:25:11,480 Setting DEBUG logging. 2017-03-23 05:25:11,480 attempt 1/10 2017-03-23 05:25:11,480 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2f76881b7ba2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 05:25:12,095 unpacking tar archive at: mozilla-beta-2f76881b7ba2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.887751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:12.290417) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:12.290747) ========= script_repo_revision: 2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:12.291152) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:12.291428) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 05:25:12.314733) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-03-23 05:25:12.315323) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:25:12 INFO - MultiFileLogger online at 20170323 05:25:12 in /builds/slave/test 05:25:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:25:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:25:12 INFO - {'append_to_log': False, 05:25:12 INFO - 'base_work_dir': '/builds/slave/test', 05:25:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:25:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:25:12 INFO - 'branch': 'Mozilla-Beta', 05:25:12 INFO - 'buildbot_json_path': 'buildprops.json', 05:25:12 INFO - 'config_files': ('talos/mac_config.py',), 05:25:12 INFO - 'default_actions': ('clobber', 05:25:12 INFO - 'read-buildbot-config', 05:25:12 INFO - 'download-and-extract', 05:25:12 INFO - 'populate-webroot', 05:25:12 INFO - 'create-virtualenv', 05:25:12 INFO - 'install', 05:25:12 INFO - 'run-tests'), 05:25:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:25:12 INFO - 'download_minidump_stackwalk': True, 05:25:12 INFO - 'download_symbols': 'ondemand', 05:25:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:25:12 INFO - 'tooltool.py': '/tools/tooltool.py', 05:25:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:25:12 INFO - '/tools/misc-python/virtualenv.py')}, 05:25:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:25:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:25:12 INFO - 'installer_path': 'installer.exe', 05:25:12 INFO - 'log_level': 'info', 05:25:12 INFO - 'log_name': 'talos', 05:25:12 INFO - 'log_to_console': True, 05:25:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:25:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:25:12 INFO - 'opt_config_files': (), 05:25:12 INFO - 'pip_index': False, 05:25:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:25:12 INFO - 'cmd': ('bash', 05:25:12 INFO - '-c', 05:25:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:25:12 INFO - 'enabled': True, 05:25:12 INFO - 'halt_on_failure': False, 05:25:12 INFO - 'name': 'check_screen_resolution'},), 05:25:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:25:12 INFO - 'cmd': ('bash', 05:25:12 INFO - '-c', 05:25:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:25:12 INFO - 'enabled': True, 05:25:12 INFO - 'halt_on_failure': False, 05:25:12 INFO - 'name': 'check_screen_resolution'},), 05:25:12 INFO - 'run_cmd_checks_enabled': True, 05:25:12 INFO - 'sps_profile': False, 05:25:12 INFO - 'sps_profile_interval': 0, 05:25:12 INFO - 'suite': 'dromaeojs-e10s', 05:25:12 INFO - 'system_bits': '32', 05:25:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:25:12 INFO - 'title': 't-yosemite-r7-0098', 05:25:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:25:12 INFO - 'use_talos_json': True, 05:25:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:25:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:25:12 INFO - 'work_dir': 'build'} 05:25:12 INFO - [mozharness: 2017-03-23 12:25:12.529766Z] Running clobber step. 05:25:12 INFO - Running pre-action listener: _resource_record_pre_action 05:25:12 INFO - Running main action method: clobber 05:25:12 INFO - rmtree: /builds/slave/test/build 05:25:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:25:15 INFO - Running post-action listener: _resource_record_post_action 05:25:15 INFO - [mozharness: 2017-03-23 12:25:15.024707Z] Finished clobber step (success) 05:25:15 INFO - [mozharness: 2017-03-23 12:25:15.024851Z] Running read-buildbot-config step. 05:25:15 INFO - Running pre-action listener: _resource_record_pre_action 05:25:15 INFO - Running main action method: read_buildbot_config 05:25:15 INFO - Using buildbot properties: 05:25:15 INFO - { 05:25:15 INFO - "project": "", 05:25:15 INFO - "product": "firefox", 05:25:15 INFO - "script_repo_revision": "production", 05:25:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:25:15 INFO - "repository": "", 05:25:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:25:15 INFO - "buildid": "20170323035458", 05:25:15 INFO - "pgo_build": "False", 05:25:15 INFO - "basedir": "/builds/slave/test", 05:25:15 INFO - "buildnumber": 34, 05:25:15 INFO - "slavename": "t-yosemite-r7-0098", 05:25:15 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:25:15 INFO - "platform": "yosemite_r7", 05:25:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:25:15 INFO - "branch": "mozilla-beta", 05:25:15 INFO - "repo_path": "releases/mozilla-beta", 05:25:15 INFO - "revision": "2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46", 05:25:15 INFO - "stage_platform": "macosx64", 05:25:15 INFO - "builduid": "0a82eed6b0bf44d2843339ecc1b2c889", 05:25:15 INFO - "slavebuilddir": "test" 05:25:15 INFO - } 05:25:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 05:25:15 INFO - Running post-action listener: _resource_record_post_action 05:25:15 INFO - [mozharness: 2017-03-23 12:25:15.028174Z] Finished read-buildbot-config step (success) 05:25:15 INFO - [mozharness: 2017-03-23 12:25:15.028284Z] Running download-and-extract step. 05:25:15 INFO - Running pre-action listener: _resource_record_pre_action 05:25:15 INFO - Running main action method: download_and_extract 05:25:15 INFO - mkdir: /builds/slave/test/build/tests 05:25: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')]} 05:25:15 INFO - trying https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 05:25:15 INFO - Downloading https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 05:25:15 INFO - Downloaded 1165 bytes. 05:25:15 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:25:15 INFO - Using the following test package requirements: 05:25:15 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:25:15 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:15 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 05:25:15 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:15 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 05:25:15 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:25:15 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:15 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 05:25:15 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:25:15 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:16 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 05:25:16 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:16 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 05:25:16 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:16 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 05:25:16 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:25:16 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 05:25:16 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 05:25:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:25:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:25:16 INFO - Fetch https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:25:16 INFO - Expected file size: 16108254 05:25:16 INFO - Obtained file size: 16108254 05:25:23 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:25:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:25:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:25:23 INFO - Fetch https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:25:24 INFO - Expected file size: 16108254 05:25:24 INFO - Obtained file size: 16108254 05:25:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 05:25:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 05:25:30 INFO - Fetch https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 05:25:32 INFO - Expected file size: 14403663 05:25:32 INFO - Obtained file size: 14403663 05:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:32 INFO - trying https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:25:32 INFO - Downloading https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 05:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 05:25:34 INFO - Downloaded 58762507 bytes. 05:25:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:25:34 INFO - mkdir: /builds/slave/test/properties 05:25:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:25:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:25:34 INFO - Contents: 05:25:34 INFO - build_url:https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:25:35 INFO - Running post-action listener: _resource_record_post_action 05:25:35 INFO - Running post-action listener: set_extra_try_arguments 05:25:35 INFO - [mozharness: 2017-03-23 12:25:35.262634Z] Finished download-and-extract step (success) 05:25:35 INFO - [mozharness: 2017-03-23 12:25:35.262769Z] Running populate-webroot step. 05:25:35 INFO - Running pre-action listener: _resource_record_pre_action 05:25:35 INFO - Running main action method: populate_webroot 05:25:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:25:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:25:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:25:35 INFO - '--tppagecycles', 05:25:35 INFO - '7'], 05:25:35 INFO - 'tests': ['tsvgm']}}, 05:25:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:25:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:25:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:25:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:25:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['damp', 'tps']}, 05:25:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'tests': ['damp', 'tps']}, 05:25:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['dromaeo_dom']}, 05:25:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:25:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['basic_compositor_video']}, 05:25:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:25:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['a11yr', 05:25:35 INFO - 'ts_paint', 05:25:35 INFO - 'tpaint', 05:25:35 INFO - 'sessionrestore', 05:25:35 INFO - 'sessionrestore_no_auto_restore', 05:25:35 INFO - 'tabpaint']}, 05:25:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:25:35 INFO - 'ts_paint', 05:25:35 INFO - 'tpaint', 05:25:35 INFO - 'sessionrestore', 05:25:35 INFO - 'sessionrestore_no_auto_restore', 05:25:35 INFO - 'tabpaint']}, 05:25:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:25:35 INFO - 'ts_paint', 05:25:35 INFO - 'tpaint', 05:25:35 INFO - 'sessionrestore', 05:25:35 INFO - 'sessionrestore_no_auto_restore', 05:25:35 INFO - 'tabpaint']}, 05:25:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:25:35 INFO - 'ts_paint', 05:25:35 INFO - 'tpaint', 05:25:35 INFO - 'sessionrestore', 05:25:35 INFO - 'sessionrestore_no_auto_restore', 05:25:35 INFO - 'tabpaint']}, 05:25:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['a11yr', 05:25:35 INFO - 'ts_paint', 05:25:35 INFO - 'tpaint', 05:25:35 INFO - 'sessionrestore', 05:25:35 INFO - 'sessionrestore_no_auto_restore', 05:25:35 INFO - 'tabpaint']}, 05:25:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['a11yr', 05:25:35 INFO - 'ts_paint', 05:25:35 INFO - 'tpaint', 05:25:35 INFO - 'sessionrestore', 05:25:35 INFO - 'sessionrestore_no_auto_restore', 05:25:35 INFO - 'tabpaint']}, 05:25:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['tsvgx', 05:25:35 INFO - 'tsvgr_opacity', 05:25:35 INFO - 'tart', 05:25:35 INFO - 'tscrollx', 05:25:35 INFO - 'cart', 05:25:35 INFO - 'tsvg_static']}, 05:25:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:25:35 INFO - 'tsvgr_opacity', 05:25:35 INFO - 'tart', 05:25:35 INFO - 'tscrollx', 05:25:35 INFO - 'cart', 05:25:35 INFO - 'tsvg_static']}, 05:25:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'talos_options': ['--disable-e10s'], 05:25:35 INFO - 'tests': ['tp5o']}, 05:25:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:25:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'talos_options': ['--disable-e10s', 05:25:35 INFO - '--xperf_path', 05:25:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:35 INFO - 'tests': ['tp5n']}, 05:25:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:25:35 INFO - 'talos_options': ['--xperf_path', 05:25:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:35 INFO - 'tests': ['tp5n']}}, 05:25:35 INFO - 'talos.zip': {'path': '', 05:25:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:25:35 INFO - Running post-action listener: _resource_record_post_action 05:25:35 INFO - [mozharness: 2017-03-23 12:25:35.281641Z] Finished populate-webroot step (success) 05:25:35 INFO - [mozharness: 2017-03-23 12:25:35.281771Z] Running create-virtualenv step. 05:25:35 INFO - Running pre-action listener: _resource_record_pre_action 05:25:35 INFO - Running main action method: create_virtualenv 05:25:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:25:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:25:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:25:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:25:35 INFO - Using real prefix '/tools/python27' 05:25:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:25:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:25:38 INFO - Installing pip.................done. 05:25:39 INFO - Return code: 0 05:25:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:25:39 INFO - Reading from file tmpfile_stdout 05:25:39 INFO - Output received: 05:25:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:25:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:39 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:39 INFO - 'HOME': '/Users/cltbld', 05:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:39 INFO - 'LOGNAME': 'cltbld', 05:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:39 INFO - 'MOZ_NO_REMOTE': '1', 05:25:39 INFO - 'NO_EM_RESTART': '1', 05:25:39 INFO - 'PAGER': '/bin/cat', 05:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:39 INFO - 'PWD': '/builds/slave/test', 05:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:39 INFO - 'SHELL': '/bin/bash', 05:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:39 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:39 INFO - 'USER': 'cltbld', 05:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:39 INFO - 'XPC_FLAGS': '0x0', 05:25:39 INFO - 'XPC_SERVICE_NAME': '0', 05:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:25:39 INFO - Cleaning up... 05:25:39 INFO - Return code: 0 05:25:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:39 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:25:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:39 INFO - 'HOME': '/Users/cltbld', 05:25:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:39 INFO - 'LOGNAME': 'cltbld', 05:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:39 INFO - 'MOZ_NO_REMOTE': '1', 05:25:39 INFO - 'NO_EM_RESTART': '1', 05:25:39 INFO - 'PAGER': '/bin/cat', 05:25:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:39 INFO - 'PWD': '/builds/slave/test', 05:25:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:39 INFO - 'SHELL': '/bin/bash', 05:25:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:39 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:39 INFO - 'USER': 'cltbld', 05:25:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:39 INFO - 'XPC_FLAGS': '0x0', 05:25:39 INFO - 'XPC_SERVICE_NAME': '0', 05:25:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:39 INFO - Downloading/unpacking psutil>=3.1.1 05:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:25:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:43 INFO - Installing collected packages: psutil 05:25:43 INFO - Running setup.py install for psutil 05:25:43 INFO - building 'psutil._psutil_osx' extension 05:25:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:25:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:25:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:25:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:25:44 INFO - building 'psutil._psutil_posix' extension 05:25:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:25:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:25:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:25:44 INFO - ^ 05:25:44 INFO - 1 warning generated. 05:25:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:25:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:44 INFO - Successfully installed psutil 05:25:44 INFO - Cleaning up... 05:25:44 INFO - Return code: 0 05:25:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:44 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:25:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:44 INFO - 'HOME': '/Users/cltbld', 05:25:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:44 INFO - 'LOGNAME': 'cltbld', 05:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:44 INFO - 'MOZ_NO_REMOTE': '1', 05:25:44 INFO - 'NO_EM_RESTART': '1', 05:25:44 INFO - 'PAGER': '/bin/cat', 05:25:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:44 INFO - 'PWD': '/builds/slave/test', 05:25:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:44 INFO - 'SHELL': '/bin/bash', 05:25:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:44 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:44 INFO - 'USER': 'cltbld', 05:25:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:44 INFO - 'XPC_FLAGS': '0x0', 05:25:44 INFO - 'XPC_SERVICE_NAME': '0', 05:25:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:25:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:25:47 INFO - Installing collected packages: mozsystemmonitor 05:25:47 INFO - Running setup.py install for mozsystemmonitor 05:25:47 INFO - Successfully installed mozsystemmonitor 05:25:47 INFO - Cleaning up... 05:25:47 INFO - Return code: 0 05:25:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:47 INFO - 'HOME': '/Users/cltbld', 05:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:47 INFO - 'LOGNAME': 'cltbld', 05:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:47 INFO - 'MOZ_NO_REMOTE': '1', 05:25:47 INFO - 'NO_EM_RESTART': '1', 05:25:47 INFO - 'PAGER': '/bin/cat', 05:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:47 INFO - 'PWD': '/builds/slave/test', 05:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:47 INFO - 'SHELL': '/bin/bash', 05:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:47 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:47 INFO - 'USER': 'cltbld', 05:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:47 INFO - 'XPC_FLAGS': '0x0', 05:25:47 INFO - 'XPC_SERVICE_NAME': '0', 05:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:48 INFO - Downloading/unpacking jsonschema==2.5.1 05:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:25:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:25:51 INFO - warning: no files found matching '*.txt' 05:25:51 INFO - no previously-included directories found matching 'build' 05:25:51 INFO - no previously-included directories found matching 'dist' 05:25:51 INFO - no previously-included directories found matching '.git*' 05:25:51 INFO - Installing collected packages: jsonschema, functools32 05:25:51 INFO - Running setup.py install for functools32 05:25:51 INFO - warning: no files found matching '*.txt' 05:25:51 INFO - no previously-included directories found matching 'build' 05:25:51 INFO - no previously-included directories found matching 'dist' 05:25:51 INFO - no previously-included directories found matching '.git*' 05:25:51 INFO - Successfully installed jsonschema functools32 05:25:51 INFO - Cleaning up... 05:25:51 INFO - Return code: 0 05:25:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:51 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:51 INFO - 'HOME': '/Users/cltbld', 05:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:51 INFO - 'LOGNAME': 'cltbld', 05:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:51 INFO - 'MOZ_NO_REMOTE': '1', 05:25:51 INFO - 'NO_EM_RESTART': '1', 05:25:51 INFO - 'PAGER': '/bin/cat', 05:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:51 INFO - 'PWD': '/builds/slave/test', 05:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:51 INFO - 'SHELL': '/bin/bash', 05:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:51 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:51 INFO - 'USER': 'cltbld', 05:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:51 INFO - 'XPC_FLAGS': '0x0', 05:25:51 INFO - 'XPC_SERVICE_NAME': '0', 05:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:25:51 INFO - Cleaning up... 05:25:51 INFO - Return code: 0 05:25:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:51 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:51 INFO - 'HOME': '/Users/cltbld', 05:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:51 INFO - 'LOGNAME': 'cltbld', 05:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:51 INFO - 'MOZ_NO_REMOTE': '1', 05:25:51 INFO - 'NO_EM_RESTART': '1', 05:25:51 INFO - 'PAGER': '/bin/cat', 05:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:51 INFO - 'PWD': '/builds/slave/test', 05:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:51 INFO - 'SHELL': '/bin/bash', 05:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:51 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:51 INFO - 'USER': 'cltbld', 05:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:51 INFO - 'XPC_FLAGS': '0x0', 05:25:51 INFO - 'XPC_SERVICE_NAME': '0', 05:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:51 INFO - Downloading/unpacking blobuploader==1.2.4 05:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:25:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:25:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:55 INFO - Downloading docopt-0.6.1.tar.gz 05:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:25:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:25:55 INFO - Running setup.py install for blobuploader 05:25:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:25:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:25:56 INFO - Running setup.py install for requests 05:25:56 INFO - Running setup.py install for docopt 05:25:56 INFO - Successfully installed blobuploader requests docopt 05:25:56 INFO - Cleaning up... 05:25:56 INFO - Return code: 0 05:25:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:56 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:25:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:25:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:56 INFO - 'HOME': '/Users/cltbld', 05:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:56 INFO - 'LOGNAME': 'cltbld', 05:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:56 INFO - 'MOZ_NO_REMOTE': '1', 05:25:56 INFO - 'NO_EM_RESTART': '1', 05:25:56 INFO - 'PAGER': '/bin/cat', 05:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:56 INFO - 'PWD': '/builds/slave/test', 05:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:56 INFO - 'SHELL': '/bin/bash', 05:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:25:56 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:56 INFO - 'USER': 'cltbld', 05:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:56 INFO - 'XPC_FLAGS': '0x0', 05:25:56 INFO - 'XPC_SERVICE_NAME': '0', 05:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-q1JuIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-RrVsiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-mcgouK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-SD3NmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-AQnJHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Du1NyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-mldwrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-QDzyKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:57 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-_7enGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-i35L0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KBels4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Cb2Cuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-zzwXh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-97k43u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-f796d0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-3vsfu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:58 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-kAULDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:25:58 INFO - Running setup.py install for manifestparser 05:25:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:25:59 INFO - Running setup.py install for mozcrash 05:25:59 INFO - Running setup.py install for mozdebug 05:25:59 INFO - Running setup.py install for mozdevice 05:25:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:25:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:25:59 INFO - Running setup.py install for mozfile 05:25:59 INFO - Running setup.py install for mozhttpd 05:25:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:25:59 INFO - Running setup.py install for mozinfo 05:25:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:25:59 INFO - Running setup.py install for mozInstall 05:26:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Running setup.py install for mozleak 05:26:00 INFO - Running setup.py install for mozlog 05:26:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Running setup.py install for moznetwork 05:26:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Running setup.py install for mozprocess 05:26:00 INFO - Running setup.py install for mozprofile 05:26:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:26:00 INFO - Running setup.py install for mozrunner 05:26:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:26:01 INFO - Running setup.py install for mozscreenshot 05:26:01 INFO - Running setup.py install for moztest 05:26:01 INFO - Running setup.py install for mozversion 05:26:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:26:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:26:01 INFO - Cleaning up... 05:26:01 INFO - Return code: 0 05:26:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:01 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:26:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:26:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:01 INFO - 'HOME': '/Users/cltbld', 05:26:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:01 INFO - 'LOGNAME': 'cltbld', 05:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:01 INFO - 'MOZ_NO_REMOTE': '1', 05:26:01 INFO - 'NO_EM_RESTART': '1', 05:26:01 INFO - 'PAGER': '/bin/cat', 05:26:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:01 INFO - 'PWD': '/builds/slave/test', 05:26:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:01 INFO - 'SHELL': '/bin/bash', 05:26:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:26:01 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:26:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:01 INFO - 'USER': 'cltbld', 05:26:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:01 INFO - 'XPC_FLAGS': '0x0', 05:26:01 INFO - 'XPC_SERVICE_NAME': '0', 05:26:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:26:01 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-3s50oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:26:01 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Gtr5Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:26:01 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-o0UxTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9uyAbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-FYstkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-RRGarr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-igzJwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9x29Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-n442dP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-3aM1bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-et1zuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:26:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:26:02 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-lOnb3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:26:03 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-lcZPTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:26:03 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-NfDsKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:03 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ramDui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:26:03 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9o0IiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:26:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:26:03 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-2sYLVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:26:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:06 INFO - Downloading blessings-1.6.tar.gz 05:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:26:06 INFO - Installing collected packages: blessings 05:26:06 INFO - Running setup.py install for blessings 05:26:06 INFO - Successfully installed blessings 05:26:06 INFO - Cleaning up... 05:26:06 INFO - Return code: 0 05:26:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:06 INFO - Reading from file tmpfile_stdout 05:26:06 INFO - Current package versions: 05:26:06 INFO - blessings == 1.6 05:26:06 INFO - blobuploader == 1.2.4 05:26:06 INFO - docopt == 0.6.1 05:26:06 INFO - functools32 == 3.2.3-2 05:26:06 INFO - jsonschema == 2.5.1 05:26:06 INFO - manifestparser == 1.1 05:26:06 INFO - mozInstall == 1.12 05:26:06 INFO - mozcrash == 1.0 05:26:06 INFO - mozdebug == 0.1 05:26:06 INFO - mozdevice == 0.49 05:26:06 INFO - mozfile == 1.2 05:26:06 INFO - mozhttpd == 0.7 05:26:06 INFO - mozinfo == 0.9 05:26:06 INFO - mozleak == 0.1 05:26:06 INFO - mozlog == 3.4 05:26:06 INFO - moznetwork == 0.27 05:26:06 INFO - mozprocess == 0.24 05:26:06 INFO - mozprofile == 0.28 05:26:06 INFO - mozrunner == 6.13 05:26:06 INFO - mozscreenshot == 0.1 05:26:06 INFO - mozsystemmonitor == 0.3 05:26:06 INFO - moztest == 0.8 05:26:06 INFO - mozversion == 1.4 05:26:06 INFO - psutil == 3.1.1 05:26:06 INFO - requests == 1.2.3 05:26:06 INFO - wsgiref == 0.1.2 05:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:07 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:07 INFO - 'HOME': '/Users/cltbld', 05:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:07 INFO - 'LOGNAME': 'cltbld', 05:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:07 INFO - 'MOZ_NO_REMOTE': '1', 05:26:07 INFO - 'NO_EM_RESTART': '1', 05:26:07 INFO - 'PAGER': '/bin/cat', 05:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:07 INFO - 'PWD': '/builds/slave/test', 05:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:07 INFO - 'SHELL': '/bin/bash', 05:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:26:07 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:07 INFO - 'USER': 'cltbld', 05:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:07 INFO - 'XPC_FLAGS': '0x0', 05:26:07 INFO - 'XPC_SERVICE_NAME': '0', 05:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:26:07 INFO - Cleaning up... 05:26:07 INFO - Return code: 0 05:26:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:07 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 0x10df9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca59dc8490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, '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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:07 INFO - 'HOME': '/Users/cltbld', 05:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:07 INFO - 'LOGNAME': 'cltbld', 05:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:07 INFO - 'MOZ_NO_REMOTE': '1', 05:26:07 INFO - 'NO_EM_RESTART': '1', 05:26:07 INFO - 'PAGER': '/bin/cat', 05:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:07 INFO - 'PWD': '/builds/slave/test', 05:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:07 INFO - 'SHELL': '/bin/bash', 05:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:26:07 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:07 INFO - 'USER': 'cltbld', 05:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:07 INFO - 'XPC_FLAGS': '0x0', 05:26:07 INFO - 'XPC_SERVICE_NAME': '0', 05:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:26:08 INFO - Cleaning up... 05:26:08 INFO - Return code: 0 05:26:08 INFO - Running post-action listener: _resource_record_post_action 05:26:08 INFO - Running post-action listener: _start_resource_monitoring 05:26:08 INFO - Starting resource monitoring. 05:26:08 INFO - [mozharness: 2017-03-23 12:26:08.146521Z] Finished create-virtualenv step (success) 05:26:08 INFO - [mozharness: 2017-03-23 12:26:08.147440Z] Running install step. 05:26:08 INFO - Running pre-action listener: _resource_record_pre_action 05:26:08 INFO - Running main action method: install 05:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:08 INFO - Reading from file tmpfile_stdout 05:26:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:26:08 INFO - Reading from file tmpfile_stdout 05:26:08 INFO - Output received: 05:26:08 INFO - Usage: mozinstall [options] installer 05:26:08 INFO - Options: 05:26:08 INFO - -h, --help show this help message and exit 05:26:08 INFO - -d DEST, --destination=DEST 05:26:08 INFO - Directory to install application into. [default: 05:26:08 INFO - "/builds/slave/test"] 05:26:08 INFO - --app=APP Application being installed. [default: firefox] 05:26:08 INFO - mkdir: /builds/slave/test/build/application 05:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:26:23 INFO - Reading from file tmpfile_stdout 05:26:23 INFO - Output received: 05:26:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:26:23 INFO - Running post-action listener: _resource_record_post_action 05:26:23 INFO - [mozharness: 2017-03-23 12:26:23.251686Z] Finished install step (success) 05:26:23 INFO - [mozharness: 2017-03-23 12:26:23.251837Z] Running run-tests step. 05:26:23 INFO - Running pre-action listener: _resource_record_pre_action 05:26:23 INFO - Running main action method: run_tests 05:26:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:23 INFO - 2017-03-23 05:26:23.266 screenresolution[1623:7470] starting screenresolution argv=screenresolution get 05:26:23 INFO - 2017-03-23 05:26:23.269 screenresolution[1623:7470] Display 0: 1600x1200x32@60 05:26:23 INFO - 2017-03-23 05:26:23.274 screenresolution[1624:7472] starting screenresolution argv=screenresolution list 05:26:23 INFO - Available Modes on Display 0 05:26:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:23 INFO - Intel Iris: 05:26:23 INFO - Chipset Model: Intel Iris 05:26:23 INFO - Type: GPU 05:26:23 INFO - Bus: Built-In 05:26:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:23 INFO - Vendor: Intel (0x8086) 05:26:23 INFO - Device ID: 0x0a2e 05:26:23 INFO - Revision ID: 0x0009 05:26:23 INFO - Displays: 05:26:23 INFO - Display: 05:26:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:23 INFO - Display Serial Number: 1600x1200 60 05:26:23 INFO - Main Display: Yes 05:26:23 INFO - Mirror: Off 05:26:23 INFO - Online: Yes 05:26:23 INFO - Rotation: Supported 05:26:23 INFO - Return code: 0 05:26:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:26:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:26:23 INFO - Python 2.7.3 05:26:23 INFO - Return code: 0 05:26:23 INFO - grabbing minidump binary from tooltool 05:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:23 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 0x10dd78d30>, '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 0x10dc9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd6dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:26:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:26:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:26:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:26:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:26:23 INFO - Return code: 0 05:26:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:26:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:26:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:26:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:26:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:26:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:26:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.72aV4dxVU1/Render', 05:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:23 INFO - 'HOME': '/Users/cltbld', 05:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:23 INFO - 'LOGNAME': 'cltbld', 05:26:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:23 INFO - 'MOZ_NO_REMOTE': '1', 05:26:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:23 INFO - 'NO_EM_RESTART': '1', 05:26:23 INFO - 'PAGER': '/bin/cat', 05:26:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:23 INFO - 'PWD': '/builds/slave/test', 05:26:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:23 INFO - 'SHELL': '/bin/bash', 05:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z0Bq0LWN6A/Listeners', 05:26:23 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:23 INFO - 'USER': 'cltbld', 05:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:23 INFO - 'XPC_FLAGS': '0x0', 05:26:23 INFO - 'XPC_SERVICE_NAME': '0', 05:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:26:23 INFO - mozversion application_buildid: 20170323035458 05:26:23 INFO - mozversion application_changeset: 2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46 05:26:23 INFO - mozversion application_display_name: Firefox 05:26:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:26:23 INFO - mozversion application_name: Firefox 05:26:23 INFO - mozversion application_remotingname: firefox 05:26:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:26:23 INFO - mozversion application_vendor: Mozilla 05:26:23 INFO - mozversion application_version: 53.0 05:26:23 INFO - mozversion platform_buildid: 20170323035458 05:26:23 INFO - mozversion platform_changeset: 2f76881b7ba245bb4cfaa4e2c63fa2a040ccce46 05:26:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:26:23 INFO - mozversion platform_version: 53.0 05:26:23 INFO - using testdate: 1490271983 05:26:23 INFO - actual date: 1490271983 05:26:23 INFO - starting webserver on 'localhost:49246' 05:26:23 INFO - SUITE-START | Running 2 tests 05:26:23 INFO - TEST-START | dromaeo_css 05:26:23 INFO - operating with platform_type : mac_ 05:26:23 INFO - Initialising browser for dromaeo_css test... 05:26:23 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpcsgef4/profile http://localhost:49246/getInfo.html) 05:26:26 INFO - PROCESS | 1634 | 2017-03-23 05:26:26.702 plugin-container[1637:7617] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 05:26:26 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:26 INFO - PROCESS | 1634 | 2017-03-23 05:26:26.703 plugin-container[1637:7617] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911f, name = 'com.apple.CFPasteboardClient' 05:26:26 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:26 INFO - PROCESS | 1634 | 2017-03-23 05:26:26.703 plugin-container[1637:7617] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:26 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 05:26:26 INFO - PROCESS | 1634 | colorDepth:24 05:26:26 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 05:26:26 INFO - PROCESS | 1634 | __metrics 05:26:26 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:26:27 INFO - TEST-INFO | 1634: exit 0 05:26:27 INFO - Browser initialized. 05:26:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:26:27 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpcsgef4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:29 INFO - PROCESS | 1638 | 2017-03-23 05:26:29.761 plugin-container[1639:7744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 05:26:29 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:29 INFO - PROCESS | 1638 | 2017-03-23 05:26:29.762 plugin-container[1639:7744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 05:26:29 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:29 INFO - PROCESS | 1638 | 2017-03-23 05:26:29.762 plugin-container[1639:7744] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:39 INFO - PROCESS | 1638 | RSS: Main: 173027328 05:26:39 INFO - PROCESS | 1638 | 05:29:40 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 05:29:42 INFO - PROCESS | 1638 | RSS: Main: 192090112 05:29:42 INFO - PROCESS | 1638 | 05:32:45 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 05:32:45 INFO - PROCESS | 1638 | RSS: Main: 203128832 05:32:45 INFO - PROCESS | 1638 | 05:35:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 05:35:48 INFO - PROCESS | 1638 | RSS: Main: 205955072 05:35:48 INFO - PROCESS | 1638 | 05:38:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 05:38:50 INFO - PROCESS | 1638 | RSS: Main: 209014784 05:38:50 INFO - PROCESS | 1638 | 05:41:53 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 05:41:54 INFO - PROCESS | 1638 | RSS: Main: 211079168 05:41:54 INFO - PROCESS | 1638 | 05:44:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 05:44:57 INFO - PROCESS | 1638 | RSS: Main: 211664896 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | __start_tp_report 05:44:57 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 05:44:57 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 05:44:57 INFO - PROCESS | 1638 | |i|pagename|runs| 05:44:57 INFO - PROCESS | 1638 | |0;dojo.html;3437.467192585149;3542.167590616203;3557.039599308186;3561.839717212725;3576.9284614307717;5949.3455719870835;5984.521238300937;6009.57932944694;6013.729382177801;6069.939300606992;6011.879762404749;6097.085437184424;6100.969495152529;6117.969410152943;6141.60079594826;5342.358916929964;5448;5459.535939445152;5460.180972854064;5514.310711161105;5494.1758736189695;5498.532624726903;5526.806561770339;5540.667559946396;5599.272094627713;3839.867239164453;4581.450225972888;5390.703511306877;5498.092814685572;5517.393086760453;4395.296752519614;5399.298091248133;5420.783168673268;5451.591130665216;5567.192757050205;5329.706866122362;5440.809571665012;5453.400125986138;5458.263134476834;5467.480589344006;5852.531797456236;5993.970030149865;6030.4572588467245;6078.361772013919;6110.63336199825;6180.351063138396;6203.534734894901;6221.751129954796;6264.749410023639;6328.778492752732;6427.935720642828;6434.646094464802;6435;6442.774502892423;6449.484041276686;6136.110264011693;6154.138420621117;6159.23009623797;6170.537209709245;6179.443850053517;6293.811185664437;6345.777897773556;6412.6793660317735;6426.518011149183;6441.291457939623;6216.751329946842;6261.78083767066;6261.9373806262265;6285.811425657238;6288.434040936338;6391.872162556723;6450.193725784277;6459.806205813833;6460.386263305072;6462.159919210472;6184.567080304426;6190;6220.098085777537;6232.906506402408;6247.187865577446;6289.245290565162;6303.873922521524;6304.590201636879;6312.337204593543;6333.524985626096;5143.897122057538;5158.97420512895;5162.5611822994715;5170.560502357266;5187.584993200535;4520.4575450946095;4526.7510286933875;4588.311753237042;4634.37435946143;4642.141203877293;6133.355997620275;6162.845928851814;6192.721327540272;6225.035119556476;6250.968745156245;6424.293327733945;6434.227892652912;6440.162778838721;6441.613503189824;6463.289038205794;6084.452399284086;6107.85643655375;6127.953177891068;6147.678249176449;6174.012158054689;4831.613470922318;5416.647917885325;5648.6610803351105;5684.289463817023;5728.570357223225;6670.299618540081;6675.933240667531;6679.833004174838;6690.264070952191;6706.061151438705;6166.044263139221;6244;6350.523710721622;6371.553991220755;6413.486921046212;6657.866842663217;6669.399754022162;6682.766103186365;6686.297938716365;6707.228668703162;6310.2743184534365;6319.6840157991;6332.0818481320875;6346.07981842621;6361.650109244036;6232.719527621359;6250.687465626609;6262.34245404226;6368.267649220399;6387.425131738166;6152.630842149485;6224.782132625336;6265.216847894013;6271.24745030581;6288.434040936155;6269.216347956505;6278.686065696788;6286.371362863677;6288.151099601495;6306.716197771019;5333.466653334635;6013.849653758691;6142.9078563820685;6222.315545290105;6295.779647712308;6140.596275277727;6152.200213972155;6154.630722156685;6219.968900155471;6271.310155882758;6188.597741146811;6198.659073750987;6206.72069756871;6209.627422354673;6212.254529456494 05:44:57 INFO - PROCESS | 1638 | |1;ext.html;470.0645715027095;489.652346833748;503.5392615756583;523.3980921939768;536.4822945857248;32804.507932381035;43864;44009.91930564862;44015.899602509955;44071.77964110183;45024.54975450245;46375;46845;46961;47024.529754702446;8168.815521749328;8948.910510894906;9757.316987810857;9765.90234097657;9768.267379946497;8828.690995815148;11918.28490290581;12211.145219834616;12229.816552751716;12372.876271237263;10139.290249682525;11797.351145686984;12010.459529321159;12030.51877924882;12087.818682719766;11974.461149248262;11984;12052.27686338818;12059.939700301442;12081.697957551043;11804.940975295069;11921.927026567651;11966.22219555726;11982.760344793056;12026.879731202751;43129.35305970413;43390.56609433897;43719.78140109311;44071;44124.77937610292;3637.88542304992;4089.5501494835544;4097.44684467596;4101.46680931477;4200.558941311149;30786.53820192699;30837.691623083705;30960.8451957741;31749.523757143663;32462.701491940294;3227.177069847174;3472.722182225441;3504.26410453805;3510.140015696171;3510.4207805712026;518472;520104;521369;526240;526297.3685131588;65537.34462655312;65765.67117164479;66007.33992660108;66042.33957660459;66312.6684366575;67156.66421667958;67627;67718.32281677218;67794.32205677978;67876.64246715038;14782.03916745408;17414.825851741574;17584.384546541067;17796.822031779775;17801.643967120843;12478.937605311916;12812.55156069533;13544.864551354558;13644.181349119084;13764.65588360299;13900;17258.654826903392;17297.30810767555;17383.65232695339;17399.47801565955;6273.215848018997;6390.265119511316;6414.94721689055;6422.261439934374;6458.838529036718;886.3972498700816;889.902110767815;891.2602539891874;924.9537523123817;929.4144688846119;30965.690343096732;31140.442977851017;31394.529082063786;31671;32301.51547726786;972.5040229482873;1045.2787576572293;1082.2640604389092;1106.81737513311;1123.1015187849687;15755.291011904492;16018.919905400631;16023.519294421187;16461.506154815375;16630.6673866522;640262;655542;659420;661400;671240;48027.55917322416;57719.42280577224;57914.4208557909;57981;58223.70888145532;1422.8719415252676;1452.2738630684657;1483.562349107016;1487.0854424529052;1487.390170030304;26633;27649.447011060067;29464.116076517737;30106.096817095524;30214.69785302119;47549;48319;54147.458525414244;54534.18198726943;59460.810783784946;526494;589993;593535;598500.0074999597;599358;25805.3548661285;26131.477370452103;26257.606135908358;26268.817903194784;26312.736872631027;38341.84974450771;44641.77679111584;45021.54978450174;47165.764171178926;47721.52278477171;32748.672513275516;36905.26189476142;38366;39147.41278880762;39326.60673393229;37214.6278537222;37398.69104581326;38303.04242393962;38558.42162367511;38732;36405.27189456143;37279.81360093182;37305.44041839429;37438.251234975694;37871;29637.5554366689;32495.837520812245;32594.83702581472;32653.510197347536;32841.67158328386;22082.558348832612;22267.84492249059;23444.062237510992;24901.501969960136;24942.69462602946 05:44:57 INFO - PROCESS | 1638 | |2;jquery.html;396.03960396039616;401.2877143071047;408.87120557024537;413.1612825963294;430.3544682975537;20885.060172292226;21206.787932120693;21637.45906352342;21886.343409697736;21984.34046978591;22536.43658908524;22603.982820773064;22610.66084008741;22670.979805908733;23270.41823954402;5661.943380566183;5932.970335148319;5940.316863560681;6000.069989151689;6006.189164462785;5123;5584.441555844403;5642.1254705520505;5686.829395118153;5950.04799232123;5830.591858569881;5972.372900845414;5973.850653733648;5979.431953964367;5996.400359964013;5064.392272927235;5759.856003599902;5781.364049954502;5804.738786754585;5869.090291004901;5103.54068133866;5202.375714914197;5209.479052094798;5210.713410762407;5219.938124440825;14277.500287489887;14416.495422660157;22056.33830985073;26603.068892588668;27787.74955127004;2957.748591369736;2985.8208507489403;3069.7390721788674;3082.8150310981414;3099.876004959799;10288.948555257251;10366.792664146751;10471.424071676054;10816.72958176044;10946.179036572288;1870.6539290231215;1921.1354890299422;1921.1546919355578;1925.3261358524544;1926.6050459655878;111630.44184779024;134484;141154;147046;147308;11911.344876031728;11925.522979080739;12181.147319687714;12259.938700306442;12392.132550721362;15911.283992220378;16061.437849675205;16290.83709162917;16316.592085197964;16362.590935226715;8983.730488085368;9171.35800493959;9171.58727857248;9198.724038278862;9304.534773261445;8925.955370223108;8939.597718102701;8952.418092823946;9003.054679258714;9117.042710515432;4436.713353127557;4477.6417886569;4977.402711674622;4995.100881841231;5004.374453193351;2046.4270004398793;2085.436932028344;2093.2464312847364;2101.8633788803677;2109.820665243441;921.6728061538191;929.163752622645;986.4623780039896;993.7316924430363;1031.417249254169;20344.593108137753;21690.24084157047;21780.455488612595;21803.345899623037;21857.453563660718;875.5315905990265;896.6771962093641;993.7018894331671;1032.1536340200962;1045.163868904873;10528.420936848546;10595.205359598061;10983.231173817756;11214.271072380268;11283.830742538868;758882;810348;825886;828610;831344;147635;151925;153123.46876531316;159076.40923590615;168474;1028.7428142964259;1035.2390992620417;1060.931039482431;1068.3376306689747;1069.7486090768664;19681.015949202196;21069.89465052665;22659.20692775745;22671.319860404215;23067.19264825723;66635.33364666291;67308.3269167302;67399.32600674126;67796.6610166946;69315.30684693088;68564.6571767138;94071;94614;96538.51730741582;97299;8703.260222881127;8828.24959878392;8904.24313933297;9019.962704289092;9140.451572905647;9710.660126895526;13642.658933526742;13657.385417656427;14276.928615356857;14336.49822256216;11399.772004560029;11418;11576.131790115607;11827.586034488751;11914.642560723192;11309.660710178709;11406.087512999105;11621.012213961907;11729.824052639047;11918.106142039209;9631.614735410785;9816.607335706492;11869.940650296694;12011.099167562294;12169.2698438094;8673.739787806377;8738.781530461789;9020.007799141958;9041.457512549268;9147.222486088756;11087.611933582286;11703.473343699383;11982.64052078439;12044;12051.57819476314 05:44:57 INFO - PROCESS | 1638 | |3;mootools.html;1445.6819499710064;1457.3369117051743;1479;1487.2563718140923;1487.9702405951882;7718.498297610651;7988.760337189894;8287.627056782445;8706.259967902728;8738.257248133908;8151.796205094875;8437.15628437156;8437.945256842895;8529.445586036903;8541.743747687578;2054.4966483211633;2417.057347634424;2464.8767561621935;2465.6917885264343;2467.099508679337;2427.2111563741764;2433.5741245282093;2455.914043008495;2456.7666071723156;2470.3700556358103;2393.102586530051;2400.72391674957;2413.1312727418117;2427.174760581402;2443.0960544598524;2384.7257565380028;2391.0316321889663;2406.542756876188;2414.3239892829947;2414.782669559739;2411.3368823573483;2435.8173137014796;2437.8171637127107;2475.232677869866;2477.0587176872855;8556.914430855737;8557.700480483154;8640.351973602004;8641.222289993868;8659.696910608161;5238.449962753933;5371.328583927009;5401.756920938567;5410.783568657249;5418.945810541923;3459;3540.327337805809;3581.767185132958;3589.766665166756;3592.5688917329826;1224.4612370556927;1225.6200577820905;1264.4942023190706;1282.923024618526;1301.1217428235902;10992.670219893416;11018.11855051594;11020.063294619968;11042.668719938414;11076.224664273504;4640.6918041498975;4715.976420117878;4804.0872234275375;4846.691393323783;4859.708417494961;9706.126448619658;9724.124828765445;9724.465154416575;9733;9733.513324333755;5407.0267351876255;5407.215953686693;5422.864428389322;5430.42980487051;5437.374701909252;4117.75293482392;4451.221036318631;4496.550344965477;4981.651284410129;5008.549230569193;3606.495090687306;3939.955911683349;3979.303621866162;4059.91880162401;4078.53096893861;1316.8551459339465;1323.087069921751;1329.6808765896114;1337.8929685625126;1338.1703343926827;735.3896266099125;860.6643409070467;864.1833467373398;889.7108439757105;938.3525367496405;5638.464345887134;5646.153077038477;5765.135229715493;5928.170056192136;6005.249343832021;1005;1009.3741880034426;1031.5254982708038;1041.7916416716687;1067.2742535076068;3783.5270591176104;3812.8284227209842;3924.7252692311836;4057.39139129133;4063.349864021742;9941.254405919584;10187.7453063674;10211.795764084825;10264.692059238234;10417.270791044706;9960.80078398428;9987.900120998696;10151.847722284172;10242.692719218436;10337.534810933526;8002.9599852000365;8163.469374490639;8179.71371002024;8186.590670466453;8193.631286592117;5733.827985160452;5806.477417032487;5894.469497745224;5899.4985426239255;6023.096535519532;9121.817563648823;9269.165775080006;9345.018773028734;9361.391509551857;9567.091126342986;9615.855762163714;9624.807503849745;9633.229341652792;9644.855327169958;9681.951590242004;5956.910646340395;6833.760818371532;7014.263502332182;7347.595882226529;7499.66251518672;8015.47899386538;8180.141085186207;8297.419180657296;8299.502029878227;8309.83380332378;7305.452091093083;7378.926210738038;7402.1117465904435;7509.324160825552;7537.170911199654;7211.711531538889;7285.599292038989;7325;7326.633668316669;7453.962730186315;5424.701641409602;6550.7707230246715;6561.540692151503;6600.735970561123;6608.966955165193;5026.44709082008;5027.170516864822;5042.250444349434;5114.616403769658;5249.553787928069;5105.591552675703;5122.541180233435;5198.090334191577;5251.921221181762;5284.313039304866 05:44:57 INFO - PROCESS | 1638 | |4;prototype.html;305.46391083648194;373.5946498049616;375.84427884255905;380.74870585413635;382.5409508589693;4964.4787297333805;5359.249705041292;5624.521915637166;5684.45997630225;5734.971325143369;4462.642988560915;5559.277293951791;5569.721513924308;5610;5690.487856092951;3367.545381373514;4206.242876282268;4266.2320782259185;4272;4293.914121717564;4223.3031549794405;4262.360645903108;4288;4312.576849639627;4328.610425061744;3304.933901321972;4192.49690037196;4261.190373828978;4273.316269396897;4287.785610719451;3287.983560082208;3689.6863766579872;3981.7855554056;4229.7885105744745;4249.128928569636;3181.8886338978255;3342.29811739535;3448.74134439918;3602.7117830573493;3627.6916462100753;4407.823687052514;4802.615790736732;5514.531264842493;5545.390007099216;5546.195801608785;3952.426898099788;4299.656027517823;4358.782060896974;4497.730136191839;4692.906141877143;5094.949050509485;5310.654807437504;5624.5781566382275;5919.970400148015;5950.256217972754;3430.382531144393;3997.5802540733384;4032.2338755636333;4033.7983100845136;4038.838446462138;5381.461853814626;5484.725763711838;6055.939440605582;6226.630141368892;6315.273743519462;3312.817795021297;3751.7936513491472;3985.3822657488136;4071.796410179479;4079.5308539518337;3641.4901913732097;3925.4896863408144;4083.4691490106097;4138.979305103455;4152.356384760367;4277.481494069622;4620.514845941195;4869.245266983623;4872.805087796449;4874.488178741252;5850.824475265749;5937.406259374028;6002.399760023963;6016.2479690038745;6083.452489275986;1153.2734377342222;1337.625464869838;1347.1311004402105;1347.5418357758213;1348.366267854107;746.6485660953691;786.3944762532818;883.0507204754914;893.6068130022769;901.8421776189272;4743.4385431867095;4965.379327584052;5440.646357986719;5455.563554915597;5501.037318469252;5803.622764520377;5807.274090738658;5830.621009634445;5930.584859059911;5943;4320.762358070274;4637.976810115928;4834.033193361342;5388.110961691355;5490.780368785284;2775.6391669083296;2857.842818644953;2945.70542945708;3046.497327940909;3052.6184226971627;6096.939030609726;6134.803713275957;6145.907811382833;6150.569460137747;6226.315105338409;4943.975280123648;5713.371529131792;5982.760689572368;6020.344405288581;6051.636901785819;5108.821191258288;6158.428111695206;6182.845428864224;6186.412290832363;6206.944819380716;5112.185174263083;5166.928476268084;5916.792912248051;5947.632044629673;5965.970170149121;5333.013632253855;5589.80435684749;5803.39064398249;5843.766249349978;5891.646501209942;5056.241563765465;5738.741756620879;6108.389161083856;6135.778716174044;6175.84560385994;3166.0660105269462;3513.3675938331344;3788.2234142001375;4030.858919937788;4062.289099407533;5346.866328341823;5644.774209031592;6028.629243798211;6055.939440605537;6160.291566469913;4735.621150308036;4940.456549779593;4961.429435614806;5879.265091863517;5978.252718410199;5391.9460805391445;5407.891382266705;5574.136008918624;5912.290525136839;5966.134910438049;5359.544438722753;5596.300462442195;5727.713614319351;5858.619189752652;5873.9412605873395;5615.7083870710785;5967.7467731776815;5975.820725378246;5988.3889405156915;6020.217371741645;5108.591312694901;5574.637648552994;5577.609567330248;5697.202391665087;5761.769529218952 05:44:57 INFO - PROCESS | 1638 | |5;yui.html;745.3925051083363;747.4244831479757;789.2935822438917;859.7893516088558;862.9266512346449;15033.548993530198;15778.526644200663;16257;16526.42157524487;16530.256138473767;14769.704605907875;15018.474353397633;15209.315580798862;15247.237638118077;15314.846851531482;893.2987604730288;896.3456676626034;902.368342160487;905.529124855075;909.8225845960008;718;738.1548127394158;780.4263866058443;898.7213963671251;907.0747837206046;964.9903500965006;965.971020869375;968.0029569543382;968.8740463739704;969.7721035556639;942.4392486470539;955.1403736637013;956.7225504603657;956.8086382723449;963.6530848894392;706.1138271469324;712.1383126417041;717.7882524655239;719.5215181904027;719.9172095209013;13679.931600342034;14959.476418325416;15267.923660381739;15720.135392553406;15750.13374264415;14374.92812535941;14598.051126676732;14998.175100369475;15003.32485038176;15005.099694018285;11845.17083804134;11845.881541184564;11898;11903.226290291102;12095.455704493319;3710.109573702319;3818.8281527331337;3825.966988913006;3848.28806670764;3858.6913046956456;92317.5384123075;114217.99999999917;114769.42615286954;115779.42110289479;118516;11106.833397499044;11469.54121835134;11479.13906457019;11607.941960290145;11641.243319184226;23664.526709465714;23708.288751337488;23884.52230955406;23917.7608223919;23996.28011159668;6564.921156340568;8080.353571714249;8186.140455252232;8201.917980820115;8326.12575679557;4465.017696106851;4549.863504094882;4581.289900065495;4592.334111553872;4603.2062132828705;3693.3351996640326;3730.925381492355;3731.589525152231;3766.0208345830283;3778.962210377916;644.5391545045316;646.5603389694959;652.7193306877994;655.5312951239791;659.1859054068221;679.2087218390519;697.5256825358805;719.4675939804612;723.5513981331503;746.8096727742213;10413.843792343121;10661.413622250695;10791.136709063256;11053.060489858452;11191.32852028881;581.9650820950757;628.1927722876082;631.6210273835753;645.0485533837552;745.9403247740167;4281.314989601648;4571.885702857455;4617.007343421181;5162.586993040548;5237.659552129099;97431.05137897344;98193;98528.01471985332;99351;99770;77179;78537.42925141465;80700.59649701713;80842.59578702069;82785.58607206926;71280;74166.25833741702;77391;77734.83397749039;78774.606126969;10865.18511111654;11145.609903653332;11283;11295.983361497176;11327.32036077838;40962.77111686654;48452.5154748448;50324.24513632225;52510.73744631252;52837.47162528326;62064;62459.687701561204;64916.35083649292;66310;67870.66064669644;13385.397657105259;13713.657158571115;14121.505747298796;14158.050992853872;14198.787018194942;15359.07845529227;15364.462243821412;15619.062856228662;16162.67674646477;16260.39833626614;14111.2944352784;14543.490977815725;14556.781648275073;14641.412853850874;14733;13730;14411.351489183222;14438.350274237475;14445.49440769568;15208.935374523677;13882.51411200603;14388.424463021362;14409.351579179172;14429.20639364845;14469;10916.50875710579;11498.94250528742;11502.19484636101;11530.711732206762;11842.971484009453;8734.519601421984;9741.9025809741;11260.831087533857;11581.942090289494;11629.185956983269 05:44:57 INFO - PROCESS | 1638 | __end_tp_report 05:44:57 INFO - PROCESS | 1638 | __start_cc_report 05:44:57 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,382 05:44:57 INFO - PROCESS | 1638 | __end_cc_report 05:44:57 INFO - PROCESS | 1638 | __startTimestamp1490273097012__endTimestamp 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | ------- Summary: start ------- 05:44:57 INFO - PROCESS | 1638 | Number of tests: 6 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5936.92 Median:6188.60 stddev:673.32 (10.9%) stddev-sans-first:646.21 05:44:57 INFO - PROCESS | 1638 | Values: 3437.5 3542.2 3557.0 3561.8 3576.9 5949.3 5984.5 6009.6 6013.7 6069.9 6011.9 6097.1 6101.0 6118.0 6141.6 5342.4 5448.0 5459.5 5460.2 5514.3 5494.2 5498.5 5526.8 5540.7 5599.3 3839.9 4581.5 5390.7 5498.1 5517.4 4395.3 5399.3 5420.8 5451.6 5567.2 5329.7 5440.8 5453.4 5458.3 5467.5 5852.5 5994.0 6030.5 6078.4 6110.6 6180.4 6203.5 6221.8 6264.7 6328.8 6427.9 6434.6 6435.0 6442.8 6449.5 6136.1 6154.1 6159.2 6170.5 6179.4 6293.8 6345.8 6412.7 6426.5 6441.3 6216.8 6261.8 6261.9 6285.8 6288.4 6391.9 6450.2 6459.8 6460.4 6462.2 6184.6 6190.0 6220.1 6232.9 6247.2 6289.2 6303.9 6304.6 6312.3 6333.5 5143.9 5159.0 5162.6 5170.6 5187.6 4520.5 4526.8 4588.3 4634.4 4642.1 6133.4 6162.8 6192.7 6225.0 6251.0 6424.3 6434.2 6440.2 6441.6 6463.3 6084.5 6107.9 6128.0 6147.7 6174.0 4831.6 5416.6 5648.7 5684.3 5728.6 6670.3 6675.9 6679.8 6690.3 6706.1 6166.0 6244.0 6350.5 6371.6 6413.5 6657.9 6669.4 6682.8 6686.3 6707.2 6310.3 6319.7 6332.1 6346.1 6361.7 6232.7 6250.7 6262.3 6368.3 6387.4 6152.6 6224.8 6265.2 6271.2 6288.4 6269.2 6278.7 6286.4 6288.2 6306.7 5333.5 6013.8 6142.9 6222.3 6295.8 6140.6 6152.2 6154.6 6220.0 6271.3 6188.6 6198.7 6206.7 6209.6 6212.3 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:72327.25 Median:37352.07 stddev:157665.82 (422.1%) stddev-sans-first:158015.81 05:44:57 INFO - PROCESS | 1638 | Values: 470.1 489.7 503.5 523.4 536.5 32804.5 43864.0 44009.9 44015.9 44071.8 45024.5 46375.0 46845.0 46961.0 47024.5 8168.8 8948.9 9757.3 9765.9 9768.3 8828.7 11918.3 12211.1 12229.8 12372.9 10139.3 11797.4 12010.5 12030.5 12087.8 11974.5 11984.0 12052.3 12059.9 12081.7 11804.9 11921.9 11966.2 11982.8 12026.9 43129.4 43390.6 43719.8 44071.0 44124.8 3637.9 4089.6 4097.4 4101.5 4200.6 30786.5 30837.7 30960.8 31749.5 32462.7 3227.2 3472.7 3504.3 3510.1 3510.4 518472.0 520104.0 521369.0 526240.0 526297.4 65537.3 65765.7 66007.3 66042.3 66312.7 67156.7 67627.0 67718.3 67794.3 67876.6 14782.0 17414.8 17584.4 17796.8 17801.6 12478.9 12812.6 13544.9 13644.2 13764.7 13900.0 17258.7 17297.3 17383.7 17399.5 6273.2 6390.3 6414.9 6422.3 6458.8 886.4 889.9 891.3 925.0 929.4 30965.7 31140.4 31394.5 31671.0 32301.5 972.5 1045.3 1082.3 1106.8 1123.1 15755.3 16018.9 16023.5 16461.5 16630.7 640262.0 655542.0 659420.0 661400.0 671240.0 48027.6 57719.4 57914.4 57981.0 58223.7 1422.9 1452.3 1483.6 1487.1 1487.4 26633.0 27649.4 29464.1 30106.1 30214.7 47549.0 48319.0 54147.5 54534.2 59460.8 526494.0 589993.0 593535.0 598500.0 599358.0 25805.4 26131.5 26257.6 26268.8 26312.7 38341.8 44641.8 45021.5 47165.8 47721.5 32748.7 36905.3 38366.0 39147.4 39326.6 37214.6 37398.7 38303.0 38558.4 38732.0 36405.3 37279.8 37305.4 37438.3 37871.0 29637.6 32495.8 32594.8 32653.5 32841.7 22082.6 22267.8 23444.1 24901.5 24942.7 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43290.81 Median:22869.26 stddev:134878.85 (589.8%) stddev-sans-first:135218.76 05:44:57 INFO - PROCESS | 1638 | Values: 396.0 401.3 408.9 413.2 430.4 20885.1 21206.8 21637.5 21886.3 21984.3 22536.4 22604.0 22610.7 22671.0 23270.4 5661.9 5933.0 5940.3 6000.1 6006.2 5123.0 5584.4 5642.1 5686.8 5950.0 5830.6 5972.4 5973.9 5979.4 5996.4 5064.4 5759.9 5781.4 5804.7 5869.1 5103.5 5202.4 5209.5 5210.7 5219.9 14277.5 14416.5 22056.3 26603.1 27787.7 2957.7 2985.8 3069.7 3082.8 3099.9 10288.9 10366.8 10471.4 10816.7 10946.2 1870.7 1921.1 1921.2 1925.3 1926.6 111630.4 134484.0 141154.0 147046.0 147308.0 11911.3 11925.5 12181.1 12259.9 12392.1 15911.3 16061.4 16290.8 16316.6 16362.6 8983.7 9171.4 9171.6 9198.7 9304.5 8926.0 8939.6 8952.4 9003.1 9117.0 4436.7 4477.6 4977.4 4995.1 5004.4 2046.4 2085.4 2093.2 2101.9 2109.8 921.7 929.2 986.5 993.7 1031.4 20344.6 21690.2 21780.5 21803.3 21857.5 875.5 896.7 993.7 1032.2 1045.2 10528.4 10595.2 10983.2 11214.3 11283.8 758882.0 810348.0 825886.0 828610.0 831344.0 147635.0 151925.0 153123.5 159076.4 168474.0 1028.7 1035.2 1060.9 1068.3 1069.7 19681.0 21069.9 22659.2 22671.3 23067.2 66635.3 67308.3 67399.3 67796.7 69315.3 68564.7 94071.0 94614.0 96538.5 97299.0 8703.3 8828.2 8904.2 9020.0 9140.5 9710.7 13642.7 13657.4 14276.9 14336.5 11399.8 11418.0 11576.1 11827.6 11914.6 11309.7 11406.1 11621.0 11729.8 11918.1 9631.6 9816.6 11869.9 12011.1 12169.3 8673.7 8738.8 9020.0 9041.5 9147.2 11087.6 11703.5 11982.6 12044.0 12051.6 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5593.23 Median:5250.74 stddev:3017.45 (57.5%) stddev-sans-first:3009.82 05:44:57 INFO - PROCESS | 1638 | Values: 1445.7 1457.3 1479.0 1487.3 1488.0 7718.5 7988.8 8287.6 8706.3 8738.3 8151.8 8437.2 8437.9 8529.4 8541.7 2054.5 2417.1 2464.9 2465.7 2467.1 2427.2 2433.6 2455.9 2456.8 2470.4 2393.1 2400.7 2413.1 2427.2 2443.1 2384.7 2391.0 2406.5 2414.3 2414.8 2411.3 2435.8 2437.8 2475.2 2477.1 8556.9 8557.7 8640.4 8641.2 8659.7 5238.4 5371.3 5401.8 5410.8 5418.9 3459.0 3540.3 3581.8 3589.8 3592.6 1224.5 1225.6 1264.5 1282.9 1301.1 10992.7 11018.1 11020.1 11042.7 11076.2 4640.7 4716.0 4804.1 4846.7 4859.7 9706.1 9724.1 9724.5 9733.0 9733.5 5407.0 5407.2 5422.9 5430.4 5437.4 4117.8 4451.2 4496.6 4981.7 5008.5 3606.5 3940.0 3979.3 4059.9 4078.5 1316.9 1323.1 1329.7 1337.9 1338.2 735.4 860.7 864.2 889.7 938.4 5638.5 5646.2 5765.1 5928.2 6005.2 1005.0 1009.4 1031.5 1041.8 1067.3 3783.5 3812.8 3924.7 4057.4 4063.3 9941.3 10187.7 10211.8 10264.7 10417.3 9960.8 9987.9 10151.8 10242.7 10337.5 8003.0 8163.5 8179.7 8186.6 8193.6 5733.8 5806.5 5894.5 5899.5 6023.1 9121.8 9269.2 9345.0 9361.4 9567.1 9615.9 9624.8 9633.2 9644.9 9682.0 5956.9 6833.8 7014.3 7347.6 7499.7 8015.5 8180.1 8297.4 8299.5 8309.8 7305.5 7378.9 7402.1 7509.3 7537.2 7211.7 7285.6 7325.0 7326.6 7454.0 5424.7 6550.8 6561.5 6600.7 6609.0 5026.4 5027.2 5042.3 5114.6 5249.6 5105.6 5122.5 5198.1 5251.9 5284.3 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4671.03 Median:5339.94 stddev:1477.28 (27.7%) stddev-sans-first:1444.63 05:44:57 INFO - PROCESS | 1638 | Values: 305.5 373.6 375.8 380.7 382.5 4964.5 5359.2 5624.5 5684.5 5735.0 4462.6 5559.3 5569.7 5610.0 5690.5 3367.5 4206.2 4266.2 4272.0 4293.9 4223.3 4262.4 4288.0 4312.6 4328.6 3304.9 4192.5 4261.2 4273.3 4287.8 3288.0 3689.7 3981.8 4229.8 4249.1 3181.9 3342.3 3448.7 3602.7 3627.7 4407.8 4802.6 5514.5 5545.4 5546.2 3952.4 4299.7 4358.8 4497.7 4692.9 5094.9 5310.7 5624.6 5920.0 5950.3 3430.4 3997.6 4032.2 4033.8 4038.8 5381.5 5484.7 6055.9 6226.6 6315.3 3312.8 3751.8 3985.4 4071.8 4079.5 3641.5 3925.5 4083.5 4139.0 4152.4 4277.5 4620.5 4869.2 4872.8 4874.5 5850.8 5937.4 6002.4 6016.2 6083.5 1153.3 1337.6 1347.1 1347.5 1348.4 746.6 786.4 883.1 893.6 901.8 4743.4 4965.4 5440.6 5455.6 5501.0 5803.6 5807.3 5830.6 5930.6 5943.0 4320.8 4638.0 4834.0 5388.1 5490.8 2775.6 2857.8 2945.7 3046.5 3052.6 6096.9 6134.8 6145.9 6150.6 6226.3 4944.0 5713.4 5982.8 6020.3 6051.6 5108.8 6158.4 6182.8 6186.4 6206.9 5112.2 5166.9 5916.8 5947.6 5966.0 5333.0 5589.8 5803.4 5843.8 5891.6 5056.2 5738.7 6108.4 6135.8 6175.8 3166.1 3513.4 3788.2 4030.9 4062.3 5346.9 5644.8 6028.6 6055.9 6160.3 4735.6 4940.5 4961.4 5879.3 5978.3 5391.9 5407.9 5574.1 5912.3 5966.1 5359.5 5596.3 5727.7 5858.6 5873.9 5615.7 5967.7 5975.8 5988.4 6020.2 5108.6 5574.6 5577.6 5697.2 5761.8 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20459.30 Median:3853.49 stddev:28635.45 (743.1%) stddev-sans-first:28677.52 05:44:57 INFO - PROCESS | 1638 | Values: 745.4 747.4 789.3 859.8 862.9 15033.5 15778.5 16257.0 16526.4 16530.3 14769.7 15018.5 15209.3 15247.2 15314.8 893.3 896.3 902.4 905.5 909.8 718.0 738.2 780.4 898.7 907.1 965.0 966.0 968.0 968.9 969.8 942.4 955.1 956.7 956.8 963.7 706.1 712.1 717.8 719.5 719.9 13679.9 14959.5 15267.9 15720.1 15750.1 14374.9 14598.1 14998.2 15003.3 15005.1 11845.2 11845.9 11898.0 11903.2 12095.5 3710.1 3818.8 3826.0 3848.3 3858.7 92317.5 114218.0 114769.4 115779.4 118516.0 11106.8 11469.5 11479.1 11607.9 11641.2 23664.5 23708.3 23884.5 23917.8 23996.3 6564.9 8080.4 8186.1 8201.9 8326.1 4465.0 4549.9 4581.3 4592.3 4603.2 3693.3 3730.9 3731.6 3766.0 3779.0 644.5 646.6 652.7 655.5 659.2 679.2 697.5 719.5 723.6 746.8 10413.8 10661.4 10791.1 11053.1 11191.3 582.0 628.2 631.6 645.0 745.9 4281.3 4571.9 4617.0 5162.6 5237.7 97431.1 98193.0 98528.0 99351.0 99770.0 77179.0 78537.4 80700.6 80842.6 82785.6 71280.0 74166.3 77391.0 77734.8 78774.6 10865.2 11145.6 11283.0 11296.0 11327.3 40962.8 48452.5 50324.2 52510.7 52837.5 62064.0 62459.7 64916.4 66310.0 67870.7 13385.4 13713.7 14121.5 14158.1 14198.8 15359.1 15364.5 15619.1 16162.7 16260.4 14111.3 14543.5 14556.8 14641.4 14733.0 13730.0 14411.4 14438.4 14445.5 15208.9 13882.5 14388.4 14409.4 14429.2 14469.0 10916.5 11498.9 11502.2 11530.7 11843.0 8734.5 9741.9 11260.8 11581.9 11629.2 05:44:57 INFO - PROCESS | 1638 | -------- Summary: end -------- 05:44:57 INFO - PROCESS | 1638 | 05:44:57 INFO - TEST-INFO | 1638: exit 0 05:44:57 INFO - TEST-OK | dromaeo_css | took 1113833ms 05:44:57 INFO - TEST-START | kraken 05:44:57 INFO - operating with platform_type : mac_ 05:44:57 INFO - Initialising browser for kraken test... 05:44:57 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpBVYKpj/profile http://localhost:49246/getInfo.html) 05:45:00 INFO - PROCESS | 1650 | 2017-03-23 05:45:00.507 plugin-container[1652:10493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 05:45:00 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:00 INFO - PROCESS | 1650 | 2017-03-23 05:45:00.508 plugin-container[1652:10493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 05:45:00 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:00 INFO - PROCESS | 1650 | 2017-03-23 05:45:00.508 plugin-container[1652:10493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:45:00 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:45:00 INFO - PROCESS | 1650 | colorDepth:24 05:45:00 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:45:00 INFO - PROCESS | 1650 | __metrics 05:45:00 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:45:01 INFO - TEST-INFO | 1650: exit 0 05:45:01 INFO - Browser initialized. 05:45:01 INFO - Running cycle 1/1 for kraken test... 05:45:01 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpBVYKpj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:03 INFO - PROCESS | 1653 | 2017-03-23 05:45:03.598 plugin-container[1654:10624] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 05:45:03 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:03 INFO - PROCESS | 1653 | 2017-03-23 05:45:03.599 plugin-container[1654:10624] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450b, name = 'com.apple.CFPasteboardClient' 05:45:03 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:45:03 INFO - PROCESS | 1653 | 2017-03-23 05:45:03.599 plugin-container[1654:10624] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:45:13 INFO - PROCESS | 1653 | RSS: Main: 172855296 05:45:13 INFO - PROCESS | 1653 | 05:45:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 05:45:17 INFO - PROCESS | 1653 | RSS: Main: 186093568 05:45:17 INFO - PROCESS | 1653 | 05:45:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 05:45:20 INFO - PROCESS | 1653 | RSS: Main: 194125824 05:45:20 INFO - PROCESS | 1653 | 05:45:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 05:45:24 INFO - PROCESS | 1653 | RSS: Main: 197890048 05:45:24 INFO - PROCESS | 1653 | 05:45:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 05:45:26 INFO - PROCESS | 1653 | RSS: Main: 199229440 05:45:26 INFO - PROCESS | 1653 | 05:45:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:45:29 INFO - PROCESS | 1653 | RSS: Main: 199606272 05:45:29 INFO - PROCESS | 1653 | 05:45:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 05:45:32 INFO - PROCESS | 1653 | RSS: Main: 203698176 05:45:32 INFO - PROCESS | 1653 | 05:45:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 05:45:36 INFO - PROCESS | 1653 | RSS: Main: 203743232 05:45:36 INFO - PROCESS | 1653 | 05:45:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 05:45:39 INFO - PROCESS | 1653 | RSS: Main: 203210752 05:45:39 INFO - PROCESS | 1653 | 05:45:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:45:42 INFO - PROCESS | 1653 | RSS: Main: 202186752 05:45:42 INFO - PROCESS | 1653 | 05:45:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:45:44 INFO - PROCESS | 1653 | RSS: Main: 202215424 05:45:44 INFO - PROCESS | 1653 | 05:45:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:45:47 INFO - PROCESS | 1653 | RSS: Main: 202231808 05:45:47 INFO - PROCESS | 1653 | 05:45:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:45:51 INFO - PROCESS | 1653 | RSS: Main: 202321920 05:45:51 INFO - PROCESS | 1653 | 05:45:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:45:54 INFO - PROCESS | 1653 | RSS: Main: 202747904 05:45:54 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 05:45:56 INFO - PROCESS | 1653 | RSS: Main: 204763136 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | __start_tp_report 05:45:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 05:45:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 05:45:56 INFO - PROCESS | 1653 | |i|pagename|runs| 05:45:56 INFO - PROCESS | 1653 | |0;ai-astar;106;119;119;120;118;119;120;118;116;116 05:45:56 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;109;108;143;114;113;111;113;130;109 05:45:56 INFO - PROCESS | 1653 | |2;audio-dft;193;196;196;194;196;192;198;199;196;200 05:45:56 INFO - PROCESS | 1653 | |3;audio-fft;80;60;65;96;61;59;82;58;72;59 05:45:56 INFO - PROCESS | 1653 | |4;audio-oscillator;85;71;85;74;69;70;81;69;71;82 05:45:56 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;86;106;129;88;85;97;118;103;85 05:45:56 INFO - PROCESS | 1653 | |6;imaging-darkroom;148;118;95;93;95;95;95;103;98;95 05:45:56 INFO - PROCESS | 1653 | |7;imaging-desaturate;108;90;127;136;117;145;91;90;94;93 05:45:56 INFO - PROCESS | 1653 | |8;json-parse-financial;59;58;57;60;61;62;62;62;62;62 05:45:56 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;68;82;97;63;94;97;100;84;71;71 05:45:56 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;83;84;85;83;82;87;78;99;96;117 05:45:56 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;143;138;136;134;134;133;131;139;151;129 05:45:56 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;163;164;166;165;165;165;162;164;168;237 05:45:56 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;69;80;84;86;84;85;83;64;65 05:45:56 INFO - PROCESS | 1653 | __end_tp_report 05:45:56 INFO - PROCESS | 1653 | __start_cc_report 05:45:56 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,929 05:45:56 INFO - PROCESS | 1653 | __end_cc_report 05:45:56 INFO - PROCESS | 1653 | __startTimestamp1490273156758__endTimestamp 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | ------- Summary: start ------- 05:45:56 INFO - PROCESS | 1653 | Number of tests: 14 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:117.10 Median:119.00 stddev:4.15 (3.5%) stddev-sans-first:1.50 05:45:56 INFO - PROCESS | 1653 | Values: 106.0 119.0 119.0 120.0 118.0 119.0 120.0 118.0 116.0 116.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:113.50 stddev:13.59 (12.0%) stddev-sans-first:11.88 05:45:56 INFO - PROCESS | 1653 | Values: 141.0 109.0 108.0 143.0 114.0 113.0 111.0 113.0 130.0 109.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.00 Median:196.00 stddev:2.54 (1.3%) stddev-sans-first:2.45 05:45:56 INFO - PROCESS | 1653 | Values: 193.0 196.0 196.0 194.0 196.0 192.0 198.0 199.0 196.0 200.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:69.20 Median:68.50 stddev:12.95 (18.9%) stddev-sans-first:13.13 05:45:56 INFO - PROCESS | 1653 | Values: 80.0 60.0 65.0 96.0 61.0 59.0 82.0 58.0 72.0 59.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:77.50 stddev:6.75 (8.7%) stddev-sans-first:6.26 05:45:56 INFO - PROCESS | 1653 | Values: 85.0 71.0 85.0 74.0 69.0 70.0 81.0 69.0 71.0 82.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:85.50 stddev:15.39 (18.0%) stddev-sans-first:15.84 05:45:56 INFO - PROCESS | 1653 | Values: 88.0 86.0 106.0 129.0 88.0 85.0 97.0 118.0 103.0 85.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:95.00 stddev:17.30 (18.2%) stddev-sans-first:7.84 05:45:56 INFO - PROCESS | 1653 | Values: 148.0 118.0 95.0 93.0 95.0 95.0 95.0 103.0 98.0 95.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:109.10 Median:90.00 stddev:20.93 (23.3%) stddev-sans-first:22.19 05:45:56 INFO - PROCESS | 1653 | Values: 108.0 90.0 127.0 136.0 117.0 145.0 91.0 90.0 94.0 93.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:62.00 stddev:1.90 (3.1%) stddev-sans-first:1.94 05:45:56 INFO - PROCESS | 1653 | Values: 59.0 58.0 57.0 60.0 61.0 62.0 62.0 62.0 62.0 62.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:82.70 Median:83.00 stddev:13.81 (16.6%) stddev-sans-first:13.58 05:45:56 INFO - PROCESS | 1653 | Values: 68.0 82.0 97.0 63.0 94.0 97.0 100.0 84.0 71.0 71.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:84.50 stddev:11.64 (13.8%) stddev-sans-first:12.11 05:45:56 INFO - PROCESS | 1653 | Values: 83.0 84.0 85.0 83.0 82.0 87.0 78.0 99.0 96.0 117.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:137.00 stddev:6.43 (4.7%) stddev-sans-first:6.41 05:45:56 INFO - PROCESS | 1653 | Values: 143.0 138.0 136.0 134.0 134.0 133.0 131.0 139.0 151.0 129.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:165.00 stddev:22.93 (13.9%) stddev-sans-first:24.10 05:45:56 INFO - PROCESS | 1653 | Values: 163.0 164.0 166.0 165.0 165.0 165.0 162.0 164.0 168.0 237.0 05:45:56 INFO - PROCESS | 1653 | 05:45:56 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:83.50 stddev:9.34 (11.2%) stddev-sans-first:9.08 05:45:56 INFO - PROCESS | 1653 | Values: 66.0 69.0 80.0 84.0 86.0 84.0 85.0 83.0 64.0 65.0 05:45:56 INFO - PROCESS | 1653 | -------- Summary: end -------- 05:45:56 INFO - PROCESS | 1653 | 05:45:57 INFO - TEST-INFO | 1653: exit 0 05:45:57 INFO - TEST-OK | kraken | took 59983ms 05:45:57 INFO - SUITE-END | took 1173s 05:45:57 INFO - Completed test suite (00:19:34) 05:45:57 INFO - javascript benchmark 05:45:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7550.912077244785, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3437.467192585149, 3542.167590616203, 3557.039599308186, 3561.839717212725, 3576.9284614307717, 5949.3455719870835, 5984.521238300937, 6009.57932944694, 6013.729382177801, 6069.939300606992, 6011.879762404749, 6097.085437184424, 6100.969495152529, 6117.969410152943, 6141.60079594826, 5342.358916929964, 5448.0, 5459.535939445152, 5460.180972854064, 5514.310711161105, 5494.1758736189695, 5498.532624726903, 5526.806561770339, 5540.667559946396, 5599.272094627713, 3839.867239164453, 4581.450225972888, 5390.703511306877, 5498.092814685572, 5517.393086760453, 4395.296752519614, 5399.298091248133, 5420.783168673268, 5451.591130665216, 5567.192757050205, 5329.706866122362, 5440.809571665012, 5453.400125986138, 5458.263134476834, 5467.480589344006, 5852.531797456236, 5993.970030149865, 6030.4572588467245, 6078.361772013919, 6110.63336199825, 6180.351063138396, 6203.534734894901, 6221.751129954796, 6264.749410023639, 6328.778492752732, 6427.935720642828, 6434.646094464802, 6435.0, 6442.774502892423, 6449.484041276686, 6136.110264011693, 6154.138420621117, 6159.23009623797, 6170.537209709245, 6179.443850053517, 6293.811185664437, 6345.777897773556, 6412.6793660317735, 6426.518011149183, 6441.291457939623, 6216.751329946842, 6261.78083767066, 6261.9373806262265, 6285.811425657238, 6288.434040936338, 6391.872162556723, 6450.193725784277, 6459.806205813833, 6460.386263305072, 6462.159919210472, 6184.567080304426, 6190.0, 6220.098085777537, 6232.906506402408, 6247.187865577446, 6289.245290565162, 6303.873922521524, 6304.590201636879, 6312.337204593543, 6333.524985626096, 5143.897122057538, 5158.97420512895, 5162.5611822994715, 5170.560502357266, 5187.584993200535, 4520.4575450946095, 4526.7510286933875, 4588.311753237042, 4634.37435946143, 4642.141203877293, 6133.355997620275, 6162.845928851814, 6192.721327540272, 6225.035119556476, 6250.968745156245, 6424.293327733945, 6434.227892652912, 6440.162778838721, 6441.613503189824, 6463.289038205794, 6084.452399284086, 6107.85643655375, 6127.953177891068, 6147.678249176449, 6174.012158054689, 4831.613470922318, 5416.647917885325, 5648.6610803351105, 5684.289463817023, 5728.570357223225, 6670.299618540081, 6675.933240667531, 6679.833004174838, 6690.264070952191, 6706.061151438705, 6166.044263139221, 6244.0, 6350.523710721622, 6371.553991220755, 6413.486921046212, 6657.866842663217, 6669.399754022162, 6682.766103186365, 6686.297938716365, 6707.228668703162, 6310.2743184534365, 6319.6840157991, 6332.0818481320875, 6346.07981842621, 6361.650109244036, 6232.719527621359, 6250.687465626609, 6262.34245404226, 6368.267649220399, 6387.425131738166, 6152.630842149485, 6224.782132625336, 6265.216847894013, 6271.24745030581, 6288.434040936155, 6269.216347956505, 6278.686065696788, 6286.371362863677, 6288.151099601495, 6306.716197771019, 5333.466653334635, 6013.849653758691, 6142.9078563820685, 6222.315545290105, 6295.779647712308, 6140.596275277727, 6152.200213972155, 6154.630722156685, 6219.968900155471, 6271.310155882758, 6188.597741146811, 6198.659073750987, 6206.72069756871, 6209.627422354673, 6212.254529456494], "value": 5894.604156766331, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.0645715027095, 489.652346833748, 503.5392615756583, 523.3980921939768, 536.4822945857248, 32804.507932381035, 43864.0, 44009.91930564862, 44015.899602509955, 44071.77964110183, 45024.54975450245, 46375.0, 46845.0, 46961.0, 47024.529754702446, 8168.815521749328, 8948.910510894906, 9757.316987810857, 9765.90234097657, 9768.267379946497, 8828.690995815148, 11918.28490290581, 12211.145219834616, 12229.816552751716, 12372.876271237263, 10139.290249682525, 11797.351145686984, 12010.459529321159, 12030.51877924882, 12087.818682719766, 11974.461149248262, 11984.0, 12052.27686338818, 12059.939700301442, 12081.697957551043, 11804.940975295069, 11921.927026567651, 11966.22219555726, 11982.760344793056, 12026.879731202751, 43129.35305970413, 43390.56609433897, 43719.78140109311, 44071.0, 44124.77937610292, 3637.88542304992, 4089.5501494835544, 4097.44684467596, 4101.46680931477, 4200.558941311149, 30786.53820192699, 30837.691623083705, 30960.8451957741, 31749.523757143663, 32462.701491940294, 3227.177069847174, 3472.722182225441, 3504.26410453805, 3510.140015696171, 3510.4207805712026, 518472.0, 520104.0, 521369.0, 526240.0, 526297.3685131588, 65537.34462655312, 65765.67117164479, 66007.33992660108, 66042.33957660459, 66312.6684366575, 67156.66421667958, 67627.0, 67718.32281677218, 67794.32205677978, 67876.64246715038, 14782.03916745408, 17414.825851741574, 17584.384546541067, 17796.822031779775, 17801.643967120843, 12478.937605311916, 12812.55156069533, 13544.864551354558, 13644.181349119084, 13764.65588360299, 13900.0, 17258.654826903392, 17297.30810767555, 17383.65232695339, 17399.47801565955, 6273.215848018997, 6390.265119511316, 6414.94721689055, 6422.261439934374, 6458.838529036718, 886.3972498700816, 889.902110767815, 891.2602539891874, 924.9537523123817, 929.4144688846119, 30965.690343096732, 31140.442977851017, 31394.529082063786, 31671.0, 32301.51547726786, 972.5040229482873, 1045.2787576572293, 1082.2640604389092, 1106.81737513311, 1123.1015187849687, 15755.291011904492, 16018.919905400631, 16023.519294421187, 16461.506154815375, 16630.6673866522, 640262.0, 655542.0, 659420.0, 661400.0, 671240.0, 48027.55917322416, 57719.42280577224, 57914.4208557909, 57981.0, 58223.70888145532, 1422.8719415252676, 1452.2738630684657, 1483.562349107016, 1487.0854424529052, 1487.390170030304, 26633.0, 27649.447011060067, 29464.116076517737, 30106.096817095524, 30214.69785302119, 47549.0, 48319.0, 54147.458525414244, 54534.18198726943, 59460.810783784946, 526494.0, 589993.0, 593535.0, 598500.0074999597, 599358.0, 25805.3548661285, 26131.477370452103, 26257.606135908358, 26268.817903194784, 26312.736872631027, 38341.84974450771, 44641.77679111584, 45021.54978450174, 47165.764171178926, 47721.52278477171, 32748.672513275516, 36905.26189476142, 38366.0, 39147.41278880762, 39326.60673393229, 37214.6278537222, 37398.69104581326, 38303.04242393962, 38558.42162367511, 38732.0, 36405.27189456143, 37279.81360093182, 37305.44041839429, 37438.251234975694, 37871.0, 29637.5554366689, 32495.837520812245, 32594.83702581472, 32653.510197347536, 32841.67158328386, 22082.558348832612, 22267.84492249059, 23444.062237510992, 24901.501969960136, 24942.69462602946], "value": 20612.821974986764, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.03960396039616, 401.2877143071047, 408.87120557024537, 413.1612825963294, 430.3544682975537, 20885.060172292226, 21206.787932120693, 21637.45906352342, 21886.343409697736, 21984.34046978591, 22536.43658908524, 22603.982820773064, 22610.66084008741, 22670.979805908733, 23270.41823954402, 5661.943380566183, 5932.970335148319, 5940.316863560681, 6000.069989151689, 6006.189164462785, 5123.0, 5584.441555844403, 5642.1254705520505, 5686.829395118153, 5950.04799232123, 5830.591858569881, 5972.372900845414, 5973.850653733648, 5979.431953964367, 5996.400359964013, 5064.392272927235, 5759.856003599902, 5781.364049954502, 5804.738786754585, 5869.090291004901, 5103.54068133866, 5202.375714914197, 5209.479052094798, 5210.713410762407, 5219.938124440825, 14277.500287489887, 14416.495422660157, 22056.33830985073, 26603.068892588668, 27787.74955127004, 2957.748591369736, 2985.8208507489403, 3069.7390721788674, 3082.8150310981414, 3099.876004959799, 10288.948555257251, 10366.792664146751, 10471.424071676054, 10816.72958176044, 10946.179036572288, 1870.6539290231215, 1921.1354890299422, 1921.1546919355578, 1925.3261358524544, 1926.6050459655878, 111630.44184779024, 134484.0, 141154.0, 147046.0, 147308.0, 11911.344876031728, 11925.522979080739, 12181.147319687714, 12259.938700306442, 12392.132550721362, 15911.283992220378, 16061.437849675205, 16290.83709162917, 16316.592085197964, 16362.590935226715, 8983.730488085368, 9171.35800493959, 9171.58727857248, 9198.724038278862, 9304.534773261445, 8925.955370223108, 8939.597718102701, 8952.418092823946, 9003.054679258714, 9117.042710515432, 4436.713353127557, 4477.6417886569, 4977.402711674622, 4995.100881841231, 5004.374453193351, 2046.4270004398793, 2085.436932028344, 2093.2464312847364, 2101.8633788803677, 2109.820665243441, 921.6728061538191, 929.163752622645, 986.4623780039896, 993.7316924430363, 1031.417249254169, 20344.593108137753, 21690.24084157047, 21780.455488612595, 21803.345899623037, 21857.453563660718, 875.5315905990265, 896.6771962093641, 993.7018894331671, 1032.1536340200962, 1045.163868904873, 10528.420936848546, 10595.205359598061, 10983.231173817756, 11214.271072380268, 11283.830742538868, 758882.0, 810348.0, 825886.0, 828610.0, 831344.0, 147635.0, 151925.0, 153123.46876531316, 159076.40923590615, 168474.0, 1028.7428142964259, 1035.2390992620417, 1060.931039482431, 1068.3376306689747, 1069.7486090768664, 19681.015949202196, 21069.89465052665, 22659.20692775745, 22671.319860404215, 23067.19264825723, 66635.33364666291, 67308.3269167302, 67399.32600674126, 67796.6610166946, 69315.30684693088, 68564.6571767138, 94071.0, 94614.0, 96538.51730741582, 97299.0, 8703.260222881127, 8828.24959878392, 8904.24313933297, 9019.962704289092, 9140.451572905647, 9710.660126895526, 13642.658933526742, 13657.385417656427, 14276.928615356857, 14336.49822256216, 11399.772004560029, 11418.0, 11576.131790115607, 11827.586034488751, 11914.642560723192, 11309.660710178709, 11406.087512999105, 11621.012213961907, 11729.824052639047, 11918.106142039209, 9631.614735410785, 9816.607335706492, 11869.940650296694, 12011.099167562294, 12169.2698438094, 8673.739787806377, 8738.781530461789, 9020.007799141958, 9041.457512549268, 9147.222486088756, 11087.611933582286, 11703.473343699383, 11982.64052078439, 12044.0, 12051.57819476314], "value": 10245.844186853383, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.6819499710064, 1457.3369117051743, 1479.0, 1487.2563718140923, 1487.9702405951882, 7718.498297610651, 7988.760337189894, 8287.627056782445, 8706.259967902728, 8738.257248133908, 8151.796205094875, 8437.15628437156, 8437.945256842895, 8529.445586036903, 8541.743747687578, 2054.4966483211633, 2417.057347634424, 2464.8767561621935, 2465.6917885264343, 2467.099508679337, 2427.2111563741764, 2433.5741245282093, 2455.914043008495, 2456.7666071723156, 2470.3700556358103, 2393.102586530051, 2400.72391674957, 2413.1312727418117, 2427.174760581402, 2443.0960544598524, 2384.7257565380028, 2391.0316321889663, 2406.542756876188, 2414.3239892829947, 2414.782669559739, 2411.3368823573483, 2435.8173137014796, 2437.8171637127107, 2475.232677869866, 2477.0587176872855, 8556.914430855737, 8557.700480483154, 8640.351973602004, 8641.222289993868, 8659.696910608161, 5238.449962753933, 5371.328583927009, 5401.756920938567, 5410.783568657249, 5418.945810541923, 3459.0, 3540.327337805809, 3581.767185132958, 3589.766665166756, 3592.5688917329826, 1224.4612370556927, 1225.6200577820905, 1264.4942023190706, 1282.923024618526, 1301.1217428235902, 10992.670219893416, 11018.11855051594, 11020.063294619968, 11042.668719938414, 11076.224664273504, 4640.6918041498975, 4715.976420117878, 4804.0872234275375, 4846.691393323783, 4859.708417494961, 9706.126448619658, 9724.124828765445, 9724.465154416575, 9733.0, 9733.513324333755, 5407.0267351876255, 5407.215953686693, 5422.864428389322, 5430.42980487051, 5437.374701909252, 4117.75293482392, 4451.221036318631, 4496.550344965477, 4981.651284410129, 5008.549230569193, 3606.495090687306, 3939.955911683349, 3979.303621866162, 4059.91880162401, 4078.53096893861, 1316.8551459339465, 1323.087069921751, 1329.6808765896114, 1337.8929685625126, 1338.1703343926827, 735.3896266099125, 860.6643409070467, 864.1833467373398, 889.7108439757105, 938.3525367496405, 5638.464345887134, 5646.153077038477, 5765.135229715493, 5928.170056192136, 6005.249343832021, 1005.0, 1009.3741880034426, 1031.5254982708038, 1041.7916416716687, 1067.2742535076068, 3783.5270591176104, 3812.8284227209842, 3924.7252692311836, 4057.39139129133, 4063.349864021742, 9941.254405919584, 10187.7453063674, 10211.795764084825, 10264.692059238234, 10417.270791044706, 9960.80078398428, 9987.900120998696, 10151.847722284172, 10242.692719218436, 10337.534810933526, 8002.9599852000365, 8163.469374490639, 8179.71371002024, 8186.590670466453, 8193.631286592117, 5733.827985160452, 5806.477417032487, 5894.469497745224, 5899.4985426239255, 6023.096535519532, 9121.817563648823, 9269.165775080006, 9345.018773028734, 9361.391509551857, 9567.091126342986, 9615.855762163714, 9624.807503849745, 9633.229341652792, 9644.855327169958, 9681.951590242004, 5956.910646340395, 6833.760818371532, 7014.263502332182, 7347.595882226529, 7499.66251518672, 8015.47899386538, 8180.141085186207, 8297.419180657296, 8299.502029878227, 8309.83380332378, 7305.452091093083, 7378.926210738038, 7402.1117465904435, 7509.324160825552, 7537.170911199654, 7211.711531538889, 7285.599292038989, 7325.0, 7326.633668316669, 7453.962730186315, 5424.701641409602, 6550.7707230246715, 6561.540692151503, 6600.735970561123, 6608.966955165193, 5026.44709082008, 5027.170516864822, 5042.250444349434, 5114.616403769658, 5249.553787928069, 5105.591552675703, 5122.541180233435, 5198.090334191577, 5251.921221181762, 5284.313039304866], "value": 4564.710496989346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.46391083648194, 373.5946498049616, 375.84427884255905, 380.74870585413635, 382.5409508589693, 4964.4787297333805, 5359.249705041292, 5624.521915637166, 5684.45997630225, 5734.971325143369, 4462.642988560915, 5559.277293951791, 5569.721513924308, 5610.0, 5690.487856092951, 3367.545381373514, 4206.242876282268, 4266.2320782259185, 4272.0, 4293.914121717564, 4223.3031549794405, 4262.360645903108, 4288.0, 4312.576849639627, 4328.610425061744, 3304.933901321972, 4192.49690037196, 4261.190373828978, 4273.316269396897, 4287.785610719451, 3287.983560082208, 3689.6863766579872, 3981.7855554056, 4229.7885105744745, 4249.128928569636, 3181.8886338978255, 3342.29811739535, 3448.74134439918, 3602.7117830573493, 3627.6916462100753, 4407.823687052514, 4802.615790736732, 5514.531264842493, 5545.390007099216, 5546.195801608785, 3952.426898099788, 4299.656027517823, 4358.782060896974, 4497.730136191839, 4692.906141877143, 5094.949050509485, 5310.654807437504, 5624.5781566382275, 5919.970400148015, 5950.256217972754, 3430.382531144393, 3997.5802540733384, 4032.2338755636333, 4033.7983100845136, 4038.838446462138, 5381.461853814626, 5484.725763711838, 6055.939440605582, 6226.630141368892, 6315.273743519462, 3312.817795021297, 3751.7936513491472, 3985.3822657488136, 4071.796410179479, 4079.5308539518337, 3641.4901913732097, 3925.4896863408144, 4083.4691490106097, 4138.979305103455, 4152.356384760367, 4277.481494069622, 4620.514845941195, 4869.245266983623, 4872.805087796449, 4874.488178741252, 5850.824475265749, 5937.406259374028, 6002.399760023963, 6016.2479690038745, 6083.452489275986, 1153.2734377342222, 1337.625464869838, 1347.1311004402105, 1347.5418357758213, 1348.366267854107, 746.6485660953691, 786.3944762532818, 883.0507204754914, 893.6068130022769, 901.8421776189272, 4743.4385431867095, 4965.379327584052, 5440.646357986719, 5455.563554915597, 5501.037318469252, 5803.622764520377, 5807.274090738658, 5830.621009634445, 5930.584859059911, 5943.0, 4320.762358070274, 4637.976810115928, 4834.033193361342, 5388.110961691355, 5490.780368785284, 2775.6391669083296, 2857.842818644953, 2945.70542945708, 3046.497327940909, 3052.6184226971627, 6096.939030609726, 6134.803713275957, 6145.907811382833, 6150.569460137747, 6226.315105338409, 4943.975280123648, 5713.371529131792, 5982.760689572368, 6020.344405288581, 6051.636901785819, 5108.821191258288, 6158.428111695206, 6182.845428864224, 6186.412290832363, 6206.944819380716, 5112.185174263083, 5166.928476268084, 5916.792912248051, 5947.632044629673, 5965.970170149121, 5333.013632253855, 5589.80435684749, 5803.39064398249, 5843.766249349978, 5891.646501209942, 5056.241563765465, 5738.741756620879, 6108.389161083856, 6135.778716174044, 6175.84560385994, 3166.0660105269462, 3513.3675938331344, 3788.2234142001375, 4030.858919937788, 4062.289099407533, 5346.866328341823, 5644.774209031592, 6028.629243798211, 6055.939440605537, 6160.291566469913, 4735.621150308036, 4940.456549779593, 4961.429435614806, 5879.265091863517, 5978.252718410199, 5391.9460805391445, 5407.891382266705, 5574.136008918624, 5912.290525136839, 5966.134910438049, 5359.544438722753, 5596.300462442195, 5727.713614319351, 5858.619189752652, 5873.9412605873395, 5615.7083870710785, 5967.7467731776815, 5975.820725378246, 5988.3889405156915, 6020.217371741645, 5108.591312694901, 5574.637648552994, 5577.609567330248, 5697.202391665087, 5761.769529218952], "value": 4209.239847284131, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.3925051083363, 747.4244831479757, 789.2935822438917, 859.7893516088558, 862.9266512346449, 15033.548993530198, 15778.526644200663, 16257.0, 16526.42157524487, 16530.256138473767, 14769.704605907875, 15018.474353397633, 15209.315580798862, 15247.237638118077, 15314.846851531482, 893.2987604730288, 896.3456676626034, 902.368342160487, 905.529124855075, 909.8225845960008, 718.0, 738.1548127394158, 780.4263866058443, 898.7213963671251, 907.0747837206046, 964.9903500965006, 965.971020869375, 968.0029569543382, 968.8740463739704, 969.7721035556639, 942.4392486470539, 955.1403736637013, 956.7225504603657, 956.8086382723449, 963.6530848894392, 706.1138271469324, 712.1383126417041, 717.7882524655239, 719.5215181904027, 719.9172095209013, 13679.931600342034, 14959.476418325416, 15267.923660381739, 15720.135392553406, 15750.13374264415, 14374.92812535941, 14598.051126676732, 14998.175100369475, 15003.32485038176, 15005.099694018285, 11845.17083804134, 11845.881541184564, 11898.0, 11903.226290291102, 12095.455704493319, 3710.109573702319, 3818.8281527331337, 3825.966988913006, 3848.28806670764, 3858.6913046956456, 92317.5384123075, 114217.99999999917, 114769.42615286954, 115779.42110289479, 118516.0, 11106.833397499044, 11469.54121835134, 11479.13906457019, 11607.941960290145, 11641.243319184226, 23664.526709465714, 23708.288751337488, 23884.52230955406, 23917.7608223919, 23996.28011159668, 6564.921156340568, 8080.353571714249, 8186.140455252232, 8201.917980820115, 8326.12575679557, 4465.017696106851, 4549.863504094882, 4581.289900065495, 4592.334111553872, 4603.2062132828705, 3693.3351996640326, 3730.925381492355, 3731.589525152231, 3766.0208345830283, 3778.962210377916, 644.5391545045316, 646.5603389694959, 652.7193306877994, 655.5312951239791, 659.1859054068221, 679.2087218390519, 697.5256825358805, 719.4675939804612, 723.5513981331503, 746.8096727742213, 10413.843792343121, 10661.413622250695, 10791.136709063256, 11053.060489858452, 11191.32852028881, 581.9650820950757, 628.1927722876082, 631.6210273835753, 645.0485533837552, 745.9403247740167, 4281.314989601648, 4571.885702857455, 4617.007343421181, 5162.586993040548, 5237.659552129099, 97431.05137897344, 98193.0, 98528.01471985332, 99351.0, 99770.0, 77179.0, 78537.42925141465, 80700.59649701713, 80842.59578702069, 82785.58607206926, 71280.0, 74166.25833741702, 77391.0, 77734.83397749039, 78774.606126969, 10865.18511111654, 11145.609903653332, 11283.0, 11295.983361497176, 11327.32036077838, 40962.77111686654, 48452.5154748448, 50324.24513632225, 52510.73744631252, 52837.47162528326, 62064.0, 62459.687701561204, 64916.35083649292, 66310.0, 67870.66064669644, 13385.397657105259, 13713.657158571115, 14121.505747298796, 14158.050992853872, 14198.787018194942, 15359.07845529227, 15364.462243821412, 15619.062856228662, 16162.67674646477, 16260.39833626614, 14111.2944352784, 14543.490977815725, 14556.781648275073, 14641.412853850874, 14733.0, 13730.0, 14411.351489183222, 14438.350274237475, 14445.49440769568, 15208.935374523677, 13882.51411200603, 14388.424463021362, 14409.351579179172, 14429.20639364845, 14469.0, 10916.50875710579, 11498.94250528742, 11502.19484636101, 11530.711732206762, 11842.971484009453, 8734.519601421984, 9741.9025809741, 11260.831087533857, 11581.942090289494, 11629.185956983269], "value": 7748.06623544359, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1506.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 119.0, 120.0, 118.0, 119.0, 120.0, 118.0, 116.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 108.0, 143.0, 114.0, 113.0, 111.0, 113.0, 130.0, 109.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 196.0, 196.0, 194.0, 196.0, 192.0, 198.0, 199.0, 196.0, 200.0], "value": 196.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 65.0, 96.0, 61.0, 59.0, 82.0, 58.0, 72.0, 59.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 85.0, 74.0, 69.0, 70.0, 81.0, 69.0, 71.0, 82.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 106.0, 129.0, 88.0, 85.0, 97.0, 118.0, 103.0, 85.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 95.0, 93.0, 95.0, 95.0, 95.0, 103.0, 98.0, 95.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 90.0, 127.0, 136.0, 117.0, 145.0, 91.0, 90.0, 94.0, 93.0], "value": 109.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 60.0, 61.0, 62.0, 62.0, 62.0, 62.0, 62.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 82.0, 97.0, 63.0, 94.0, 97.0, 100.0, 84.0, 71.0, 71.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 83.0, 82.0, 87.0, 78.0, 99.0, 96.0, 117.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 138.0, 136.0, 134.0, 134.0, 133.0, 131.0, 139.0, 151.0, 129.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 166.0, 165.0, 165.0, 165.0, 162.0, 164.0, 168.0, 237.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 80.0, 84.0, 86.0, 84.0, 85.0, 83.0, 64.0, 65.0], "value": 76.6, "unit": "score"}]}]} 05:45:57 INFO - Return code: 0 05:45:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:45:58 ERROR - # TBPL SUCCESS # 05:45:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:45:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:45:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:45:58 INFO - 2017-03-23 05:45:58.067 screenresolution[1658:11007] starting screenresolution argv=screenresolution get 05:45:58 INFO - 2017-03-23 05:45:58.070 screenresolution[1658:11007] Display 0: 1600x1200x32@60 05:45:58 INFO - 2017-03-23 05:45:58.076 screenresolution[1659:11010] starting screenresolution argv=screenresolution list 05:45:58 INFO - Available Modes on Display 0 05:45:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:45:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:45:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:45:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:45:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:45:58 INFO - Intel Iris: 05:45:58 INFO - Chipset Model: Intel Iris 05:45:58 INFO - Type: GPU 05:45:58 INFO - Bus: Built-In 05:45:58 INFO - VRAM (Dynamic, Max): 1536 MB 05:45:58 INFO - Vendor: Intel (0x8086) 05:45:58 INFO - Device ID: 0x0a2e 05:45:58 INFO - Revision ID: 0x0009 05:45:58 INFO - Displays: 05:45:58 INFO - Display: 05:45:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:45:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:45:58 INFO - Display Serial Number: 1600x1200 60 05:45:58 INFO - Main Display: Yes 05:45:58 INFO - Mirror: Off 05:45:58 INFO - Online: Yes 05:45:58 INFO - Rotation: Supported 05:45:58 INFO - Return code: 0 05:45:58 INFO - Running post-action listener: _resource_record_post_action 05:45:58 INFO - [mozharness: 2017-03-23 12:45:58.313262Z] Finished run-tests step (success) 05:45:58 INFO - Running post-run listener: _resource_record_post_run 05:45:58 INFO - Total resource usage - Wall time: 1189s; CPU: 54.0%; Read bytes: 40601600; Write bytes: 425444864; Read time: 396; Write time: 1461 05:45:58 INFO - TinderboxPrint: CPU usage
54.4% 05:45:58 INFO - TinderboxPrint: I/O read bytes / time
40,601,600 / 396 05:45:58 INFO - TinderboxPrint: I/O write bytes / time
425,444,864 / 1,461 05:45:58 INFO - TinderboxPrint: CPU idle
2,169.3 (45.6%) 05:45:58 INFO - TinderboxPrint: CPU system
958.8 (20.2%) 05:45:58 INFO - TinderboxPrint: CPU user
1,629.7 (34.3%) 05:45:58 INFO - TinderboxPrint: Swap in / out
162,639,872 / 0 05:45:58 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123027968; Write bytes: 124372480; Read time: 11308; Write time: 286 05:45:58 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 35805184; Write bytes: 299696128; Read time: 361; Write time: 1168 05:45:58 INFO - Running post-run listener: _upload_blobber_files 05:45:58 INFO - Blob upload gear active. 05:45:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:45:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:45:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:45:59 INFO - (blobuploader) - INFO - Open directory for files ... 05:45:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:45:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:46:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:00 INFO - (blobuploader) - INFO - Done attempting. 05:46:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:46:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:46:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:01 INFO - (blobuploader) - INFO - Done attempting. 05:46:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:46:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:46:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:02 INFO - (blobuploader) - INFO - Done attempting. 05:46:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:46:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:46:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:04 INFO - (blobuploader) - INFO - Done attempting. 05:46:04 INFO - (blobuploader) - INFO - Iteration through files over. 05:46:04 INFO - Return code: 0 05:46:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:46:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:46:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80326d67c8e02ff89b2ea9dce452213beadc322c451840a20b9ae098aa8eaf21aab0e1227428fbcd81826065eb22bf0b56aa703cc9e93d7f4b348fce9b91cb78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1c585873c5df7369472a55141de48ad0c7465a5acde2f58b654e350dada0bc73d10407725857e415852f5597a1b400b8feb7623de880dab1d8cc0ca5a6c72bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262a505778ac85e2ad5dec09f5ae7ccf04f4cf37d5ec417bae3e17531aac2e6057ac2ae9d7fdb644bb8b436f5aa334e760a44244258facb57e4eadcc69bd78b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/430557973fb9e8477980200203ed10dd016df4fa1d94d780d55e217e5b2c0795e65eaebff239ff97a75b4a6baf9c72ac64cb741921d8a48660912723351c19e6"} 05:46:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:46:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:46:04 INFO - Contents: 05:46:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80326d67c8e02ff89b2ea9dce452213beadc322c451840a20b9ae098aa8eaf21aab0e1227428fbcd81826065eb22bf0b56aa703cc9e93d7f4b348fce9b91cb78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1c585873c5df7369472a55141de48ad0c7465a5acde2f58b654e350dada0bc73d10407725857e415852f5597a1b400b8feb7623de880dab1d8cc0ca5a6c72bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262a505778ac85e2ad5dec09f5ae7ccf04f4cf37d5ec417bae3e17531aac2e6057ac2ae9d7fdb644bb8b436f5aa334e760a44244258facb57e4eadcc69bd78b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/430557973fb9e8477980200203ed10dd016df4fa1d94d780d55e217e5b2c0795e65eaebff239ff97a75b4a6baf9c72ac64cb741921d8a48660912723351c19e6"} 05:46:04 INFO - Running post-run listener: copy_logs_to_upload_dir 05:46:04 INFO - Copying logs to upload dir... 05:46:04 INFO - mkdir: /builds/slave/test/build/upload/logs 05:46:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.863376 ========= master_lag: -0.37 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-03-23 05:46:03.811807) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 05:46:03.815290) ========= 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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80326d67c8e02ff89b2ea9dce452213beadc322c451840a20b9ae098aa8eaf21aab0e1227428fbcd81826065eb22bf0b56aa703cc9e93d7f4b348fce9b91cb78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1c585873c5df7369472a55141de48ad0c7465a5acde2f58b654e350dada0bc73d10407725857e415852f5597a1b400b8feb7623de880dab1d8cc0ca5a6c72bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262a505778ac85e2ad5dec09f5ae7ccf04f4cf37d5ec417bae3e17531aac2e6057ac2ae9d7fdb644bb8b436f5aa334e760a44244258facb57e4eadcc69bd78b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/430557973fb9e8477980200203ed10dd016df4fa1d94d780d55e217e5b2c0795e65eaebff239ff97a75b4a6baf9c72ac64cb741921d8a48660912723351c19e6"} build_url:https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010962 build_url: 'https://queue.taskcluster.net/v1/task/GUpN3vp_R466G4S1iEulyg/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80326d67c8e02ff89b2ea9dce452213beadc322c451840a20b9ae098aa8eaf21aab0e1227428fbcd81826065eb22bf0b56aa703cc9e93d7f4b348fce9b91cb78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1c585873c5df7369472a55141de48ad0c7465a5acde2f58b654e350dada0bc73d10407725857e415852f5597a1b400b8feb7623de880dab1d8cc0ca5a6c72bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3262a505778ac85e2ad5dec09f5ae7ccf04f4cf37d5ec417bae3e17531aac2e6057ac2ae9d7fdb644bb8b436f5aa334e760a44244258facb57e4eadcc69bd78b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/430557973fb9e8477980200203ed10dd016df4fa1d94d780d55e217e5b2c0795e65eaebff239ff97a75b4a6baf9c72ac64cb741921d8a48660912723351c19e6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 05:46:03.848984) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:46:03.849303) ========= 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.72aV4dxVU1/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.Z0Bq0LWN6A/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.005193 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 05:46:03.897759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 05:46:03.898082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 05:46:03.898420) ========= ========= Total master_lag: -0.23 =========