builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0328 starttime: 1486603092.29 results: success (0) buildid: 20170208161121 builduid: 257e40b7ddf44bb1a24b5a756d141450 revision: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.294795) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.295277) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.295555) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.320820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.321199) ========= 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.bLW9ZvohQ6/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.TILnklCsEV/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-02-08 17:18:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.3M=0s 2017-02-08 17:18:12 (53.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085476 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.430549) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.430945) ========= 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.bLW9ZvohQ6/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.TILnklCsEV/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.021366 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:12.479498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 17:18:12.479900) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bLW9ZvohQ6/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.TILnklCsEV/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-02-08 17:18:12,570 truncating revision to first 12 chars 2017-02-08 17:18:12,571 Setting DEBUG logging. 2017-02-08 17:18:12,571 attempt 1/10 2017-02-08 17:18:12,571 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8dcc2332e280?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-08 17:18:14,289 unpacking tar archive at: mozilla-aurora-8dcc2332e280/testing/mozharness/ program finished with exit code 0 elapsedTime=1.971266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 17:18:14.475560) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:14.475976) ========= script_repo_revision: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:14.476451) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:14.476772) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 17:18:14.496566) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-02-08 17:18:14.496960) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bLW9ZvohQ6/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.TILnklCsEV/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:14 INFO - MultiFileLogger online at 20170208 17:18:14 in /builds/slave/test 17:18:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:18:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:18:14 INFO - {'append_to_log': False, 17:18:14 INFO - 'base_work_dir': '/builds/slave/test', 17:18:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:18:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:18:14 INFO - 'branch': 'Mozilla-Aurora', 17:18:14 INFO - 'buildbot_json_path': 'buildprops.json', 17:18:14 INFO - 'config_files': ('talos/mac_config.py',), 17:18:14 INFO - 'default_actions': ('clobber', 17:18:14 INFO - 'read-buildbot-config', 17:18:14 INFO - 'download-and-extract', 17:18:14 INFO - 'populate-webroot', 17:18:14 INFO - 'create-virtualenv', 17:18:14 INFO - 'install', 17:18:14 INFO - 'run-tests'), 17:18:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:18:14 INFO - 'download_minidump_stackwalk': True, 17:18:14 INFO - 'download_symbols': 'ondemand', 17:18:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:18:14 INFO - 'tooltool.py': '/tools/tooltool.py', 17:18:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:18:14 INFO - '/tools/misc-python/virtualenv.py')}, 17:18:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:18:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:18:14 INFO - 'installer_path': 'installer.exe', 17:18:14 INFO - 'log_level': 'info', 17:18:14 INFO - 'log_name': 'talos', 17:18:14 INFO - 'log_to_console': True, 17:18:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:18:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:18:14 INFO - 'opt_config_files': (), 17:18:14 INFO - 'pip_index': False, 17:18:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:18:14 INFO - 'cmd': ('bash', 17:18:14 INFO - '-c', 17:18:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:18:14 INFO - 'enabled': True, 17:18:14 INFO - 'halt_on_failure': False, 17:18:14 INFO - 'name': 'check_screen_resolution'},), 17:18:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:18:14 INFO - 'cmd': ('bash', 17:18:14 INFO - '-c', 17:18:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:18:14 INFO - 'enabled': True, 17:18:14 INFO - 'halt_on_failure': False, 17:18:14 INFO - 'name': 'check_screen_resolution'},), 17:18:14 INFO - 'run_cmd_checks_enabled': True, 17:18:14 INFO - 'sps_profile': False, 17:18:14 INFO - 'sps_profile_interval': 0, 17:18:14 INFO - 'suite': 'dromaeojs', 17:18:14 INFO - 'system_bits': '32', 17:18:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:18:14 INFO - 'title': 't-yosemite-r7-0328', 17:18:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:18:14 INFO - 'use_talos_json': True, 17:18:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:18:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:18:14 INFO - 'work_dir': 'build'} 17:18:14 INFO - [mozharness: 2017-02-09 01:18:14.706042Z] Running clobber step. 17:18:14 INFO - Running pre-action listener: _resource_record_pre_action 17:18:14 INFO - Running main action method: clobber 17:18:14 INFO - rmtree: /builds/slave/test/build 17:18:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:18:16 INFO - Running post-action listener: _resource_record_post_action 17:18:16 INFO - [mozharness: 2017-02-09 01:18:16.208794Z] Finished clobber step (success) 17:18:16 INFO - [mozharness: 2017-02-09 01:18:16.208960Z] Running read-buildbot-config step. 17:18:16 INFO - Running pre-action listener: _resource_record_pre_action 17:18:16 INFO - Running main action method: read_buildbot_config 17:18:16 INFO - Using buildbot properties: 17:18:16 INFO - { 17:18:16 INFO - "project": "", 17:18:16 INFO - "product": "firefox", 17:18:16 INFO - "script_repo_revision": "production", 17:18:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:18:16 INFO - "repository": "", 17:18:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:18:16 INFO - "buildid": "20170208161121", 17:18:16 INFO - "pgo_build": "False", 17:18:16 INFO - "basedir": "/builds/slave/test", 17:18:16 INFO - "buildnumber": 60, 17:18:16 INFO - "slavename": "t-yosemite-r7-0328", 17:18:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:18:16 INFO - "platform": "yosemite_r7", 17:18:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:18:16 INFO - "branch": "mozilla-aurora", 17:18:16 INFO - "repo_path": "releases/mozilla-aurora", 17:18:16 INFO - "revision": "8dcc2332e280ee23815b44ba76f78c9919d9ebd0", 17:18:16 INFO - "stage_platform": "macosx64", 17:18:16 INFO - "builduid": "257e40b7ddf44bb1a24b5a756d141450", 17:18:16 INFO - "slavebuilddir": "test" 17:18:16 INFO - } 17:18:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 17:18:16 INFO - Running post-action listener: _resource_record_post_action 17:18:16 INFO - [mozharness: 2017-02-09 01:18:16.212280Z] Finished read-buildbot-config step (success) 17:18:16 INFO - [mozharness: 2017-02-09 01:18:16.212391Z] Running download-and-extract step. 17:18:16 INFO - Running pre-action listener: _resource_record_pre_action 17:18:16 INFO - Running main action method: download_and_extract 17:18:16 INFO - mkdir: /builds/slave/test/build/tests 17:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:16 INFO - trying https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 17:18:16 INFO - Downloading https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 17:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 17:18:17 INFO - Downloaded 1199 bytes. 17:18:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 17:18:17 INFO - Using the following test package requirements: 17:18:17 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 17:18:17 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 17:18:17 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 17:18:17 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'jsshell-mac.zip'], 17:18:17 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 17:18:17 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 17:18:17 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 17:18:17 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 17:18:17 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 17:18:17 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 17:18:17 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 17:18:17 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:18:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 17:18:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:18:17 INFO - Fetch https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 17:18:18 INFO - Expected file size: 16357852 17:18:18 INFO - Obtained file size: 16357852 17:18:25 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 17:18:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 17:18:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:18:25 INFO - Fetch https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 17:18:26 INFO - Expected file size: 16357852 17:18:26 INFO - Obtained file size: 16357852 17:18:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 17:18:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:18:32 INFO - Fetch https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 17:18:33 INFO - Expected file size: 14403485 17:18:33 INFO - Obtained file size: 14403485 17:18:33 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')]} 17:18:33 INFO - trying https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 17:18:33 INFO - Downloading https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 17:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 17:18:35 INFO - Downloaded 73815431 bytes. 17:18:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 17:18:35 INFO - mkdir: /builds/slave/test/properties 17:18:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:18:35 INFO - Writing to file /builds/slave/test/properties/build_url 17:18:35 INFO - Contents: 17:18:35 INFO - build_url:https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 17:18:36 INFO - Running post-action listener: _resource_record_post_action 17:18:36 INFO - Running post-action listener: set_extra_try_arguments 17:18:36 INFO - [mozharness: 2017-02-09 01:18:36.190167Z] Finished download-and-extract step (success) 17:18:36 INFO - [mozharness: 2017-02-09 01:18:36.190296Z] Running populate-webroot step. 17:18:36 INFO - Running pre-action listener: _resource_record_pre_action 17:18:36 INFO - Running main action method: populate_webroot 17:18:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:18:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:18:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:18:36 INFO - '--tppagecycles', 17:18:36 INFO - '7'], 17:18:36 INFO - 'tests': ['tsvgm']}}, 17:18:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:18:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:18:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:18:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:18:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:18:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:18:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['damp', 'tps']}, 17:18:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'tests': ['damp', 'tps']}, 17:18:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['dromaeo_dom']}, 17:18:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:18:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['basic_compositor_video']}, 17:18:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:18:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['a11yr', 17:18:36 INFO - 'ts_paint', 17:18:36 INFO - 'tpaint', 17:18:36 INFO - 'sessionrestore', 17:18:36 INFO - 'sessionrestore_no_auto_restore', 17:18:36 INFO - 'tabpaint']}, 17:18:36 INFO - 'other-e10s': {'tests': ['a11yr', 17:18:36 INFO - 'ts_paint', 17:18:36 INFO - 'tpaint', 17:18:36 INFO - 'sessionrestore', 17:18:36 INFO - 'sessionrestore_no_auto_restore', 17:18:36 INFO - 'tabpaint']}, 17:18:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:18:36 INFO - 'ts_paint', 17:18:36 INFO - 'tpaint', 17:18:36 INFO - 'sessionrestore', 17:18:36 INFO - 'sessionrestore_no_auto_restore', 17:18:36 INFO - 'tabpaint']}, 17:18:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:18:36 INFO - 'ts_paint', 17:18:36 INFO - 'tpaint', 17:18:36 INFO - 'sessionrestore', 17:18:36 INFO - 'sessionrestore_no_auto_restore', 17:18:36 INFO - 'tabpaint']}, 17:18:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['a11yr', 17:18:36 INFO - 'ts_paint', 17:18:36 INFO - 'tpaint', 17:18:36 INFO - 'sessionrestore', 17:18:36 INFO - 'sessionrestore_no_auto_restore', 17:18:36 INFO - 'tabpaint']}, 17:18:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['a11yr', 17:18:36 INFO - 'ts_paint', 17:18:36 INFO - 'tpaint', 17:18:36 INFO - 'sessionrestore', 17:18:36 INFO - 'sessionrestore_no_auto_restore', 17:18:36 INFO - 'tabpaint']}, 17:18:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['tsvgx', 17:18:36 INFO - 'tsvgr_opacity', 17:18:36 INFO - 'tart', 17:18:36 INFO - 'tscrollx', 17:18:36 INFO - 'cart', 17:18:36 INFO - 'tsvg_static']}, 17:18:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:18:36 INFO - 'tsvgr_opacity', 17:18:36 INFO - 'tart', 17:18:36 INFO - 'tscrollx', 17:18:36 INFO - 'cart', 17:18:36 INFO - 'tsvg_static']}, 17:18:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'talos_options': ['--disable-e10s'], 17:18:36 INFO - 'tests': ['tp5o']}, 17:18:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:18:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'talos_options': ['--disable-e10s', 17:18:36 INFO - '--xperf_path', 17:18:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:18:36 INFO - 'tests': ['tp5n']}, 17:18:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:18:36 INFO - 'talos_options': ['--xperf_path', 17:18:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:18:36 INFO - 'tests': ['tp5n']}}, 17:18:36 INFO - 'talos.zip': {'path': '', 17:18:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:18:36 INFO - Running post-action listener: _resource_record_post_action 17:18:36 INFO - [mozharness: 2017-02-09 01:18:36.208673Z] Finished populate-webroot step (success) 17:18:36 INFO - [mozharness: 2017-02-09 01:18:36.208781Z] Running create-virtualenv step. 17:18:36 INFO - Running pre-action listener: _resource_record_pre_action 17:18:36 INFO - Running main action method: create_virtualenv 17:18:36 INFO - Creating virtualenv /builds/slave/test/build/venv 17:18:36 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 17:18:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:18:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:18:36 INFO - Using real prefix '/tools/python27' 17:18:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:18:37 INFO - Installing distribute.............................................................................................................................................................................................done. 17:18:39 INFO - Installing pip.................done. 17:18:39 INFO - Return code: 0 17:18:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:18:40 INFO - Reading from file tmpfile_stdout 17:18:40 INFO - Output received: 17:18:40 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) 17:18:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:18:40 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')]} 17:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:40 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')]} 17:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:40 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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:40 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 17:18:40 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 17:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:40 INFO - 'HOME': '/Users/cltbld', 17:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:40 INFO - 'LOGNAME': 'cltbld', 17:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:40 INFO - 'MOZ_NO_REMOTE': '1', 17:18:40 INFO - 'NO_EM_RESTART': '1', 17:18:40 INFO - 'PAGER': '/bin/cat', 17:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:40 INFO - 'PWD': '/builds/slave/test', 17:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:40 INFO - 'SHELL': '/bin/bash', 17:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:40 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:40 INFO - 'USER': 'cltbld', 17:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:40 INFO - 'XPC_FLAGS': '0x0', 17:18:40 INFO - 'XPC_SERVICE_NAME': '0', 17:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:40 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 17:18:40 INFO - Cleaning up... 17:18:40 INFO - Return code: 0 17:18:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:18:40 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')]} 17:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:40 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')]} 17:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:40 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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:40 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 17:18:40 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 17:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:40 INFO - 'HOME': '/Users/cltbld', 17:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:40 INFO - 'LOGNAME': 'cltbld', 17:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:40 INFO - 'MOZ_NO_REMOTE': '1', 17:18:40 INFO - 'NO_EM_RESTART': '1', 17:18:40 INFO - 'PAGER': '/bin/cat', 17:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:40 INFO - 'PWD': '/builds/slave/test', 17:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:40 INFO - 'SHELL': '/bin/bash', 17:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:40 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:40 INFO - 'USER': 'cltbld', 17:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:40 INFO - 'XPC_FLAGS': '0x0', 17:18:40 INFO - 'XPC_SERVICE_NAME': '0', 17:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:40 INFO - Downloading/unpacking psutil>=3.1.1 17:18:40 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 17:18:40 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 17:18:40 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 17:18:40 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 17:18:40 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 17:18:40 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 17:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:18:44 INFO - Installing collected packages: psutil 17:18:44 INFO - Running setup.py install for psutil 17:18:44 INFO - building 'psutil._psutil_osx' extension 17:18: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:18: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 17:18: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 17:18: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 17:18:44 INFO - building 'psutil._psutil_posix' extension 17:18: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 17:18:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:18:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:18:44 INFO - ^ 17:18:44 INFO - 1 warning generated. 17:18: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 17:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:18:45 INFO - Successfully installed psutil 17:18:45 INFO - Cleaning up... 17:18:45 INFO - Return code: 0 17:18:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:18:45 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')]} 17:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:45 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')]} 17:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:45 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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:45 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 17:18:45 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 17:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:45 INFO - 'HOME': '/Users/cltbld', 17:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:45 INFO - 'LOGNAME': 'cltbld', 17:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:45 INFO - 'MOZ_NO_REMOTE': '1', 17:18:45 INFO - 'NO_EM_RESTART': '1', 17:18:45 INFO - 'PAGER': '/bin/cat', 17:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:45 INFO - 'PWD': '/builds/slave/test', 17:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:45 INFO - 'SHELL': '/bin/bash', 17:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:45 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:45 INFO - 'USER': 'cltbld', 17:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:45 INFO - 'XPC_FLAGS': '0x0', 17:18:45 INFO - 'XPC_SERVICE_NAME': '0', 17:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:18:45 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 17:18:45 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 17:18:45 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 17:18:45 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 17:18:45 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 17:18:45 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 17:18:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:18:48 INFO - Installing collected packages: mozsystemmonitor 17:18:48 INFO - Running setup.py install for mozsystemmonitor 17:18:48 INFO - Successfully installed mozsystemmonitor 17:18:48 INFO - Cleaning up... 17:18:48 INFO - Return code: 0 17:18:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:48 INFO - 'HOME': '/Users/cltbld', 17:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:48 INFO - 'LOGNAME': 'cltbld', 17:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:48 INFO - 'MOZ_NO_REMOTE': '1', 17:18:48 INFO - 'NO_EM_RESTART': '1', 17:18:48 INFO - 'PAGER': '/bin/cat', 17:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:48 INFO - 'PWD': '/builds/slave/test', 17:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:48 INFO - 'SHELL': '/bin/bash', 17:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:48 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:48 INFO - 'USER': 'cltbld', 17:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:48 INFO - 'XPC_FLAGS': '0x0', 17:18:48 INFO - 'XPC_SERVICE_NAME': '0', 17:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:48 INFO - Downloading/unpacking jsonschema==2.5.1 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:18:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:51 INFO - Downloading functools32-3.2.3-2.tar.gz 17:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:18:51 INFO - warning: no files found matching '*.txt' 17:18:51 INFO - no previously-included directories found matching 'build' 17:18:51 INFO - no previously-included directories found matching 'dist' 17:18:51 INFO - no previously-included directories found matching '.git*' 17:18:51 INFO - Installing collected packages: jsonschema, functools32 17:18:51 INFO - Running setup.py install for functools32 17:18:51 INFO - warning: no files found matching '*.txt' 17:18:51 INFO - no previously-included directories found matching 'build' 17:18:51 INFO - no previously-included directories found matching 'dist' 17:18:51 INFO - no previously-included directories found matching '.git*' 17:18:51 INFO - Successfully installed jsonschema functools32 17:18:51 INFO - Cleaning up... 17:18:51 INFO - Return code: 0 17:18:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:18: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')]} 17:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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')]} 17:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18: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 17:18: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 17:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:51 INFO - 'HOME': '/Users/cltbld', 17:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:51 INFO - 'LOGNAME': 'cltbld', 17:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:51 INFO - 'MOZ_NO_REMOTE': '1', 17:18:51 INFO - 'NO_EM_RESTART': '1', 17:18:51 INFO - 'PAGER': '/bin/cat', 17:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:51 INFO - 'PWD': '/builds/slave/test', 17:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:51 INFO - 'SHELL': '/bin/bash', 17:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:51 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:51 INFO - 'USER': 'cltbld', 17:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:51 INFO - 'XPC_FLAGS': '0x0', 17:18:51 INFO - 'XPC_SERVICE_NAME': '0', 17:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:18:52 INFO - Cleaning up... 17:18:52 INFO - Return code: 0 17:18:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:18:52 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')]} 17:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:52 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')]} 17:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:52 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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:52 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 17:18:52 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 17:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:52 INFO - 'HOME': '/Users/cltbld', 17:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:52 INFO - 'LOGNAME': 'cltbld', 17:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:52 INFO - 'MOZ_NO_REMOTE': '1', 17:18:52 INFO - 'NO_EM_RESTART': '1', 17:18:52 INFO - 'PAGER': '/bin/cat', 17:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:52 INFO - 'PWD': '/builds/slave/test', 17:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:52 INFO - 'SHELL': '/bin/bash', 17:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:52 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:52 INFO - 'USER': 'cltbld', 17:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:52 INFO - 'XPC_FLAGS': '0x0', 17:18:52 INFO - 'XPC_SERVICE_NAME': '0', 17:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:52 INFO - Downloading/unpacking blobuploader==1.2.4 17:18:52 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 17:18:52 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 17:18:52 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 17:18:52 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 17:18:52 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 17:18:52 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 17:18:55 INFO - Downloading blobuploader-1.2.4.tar.gz 17:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:18:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:18:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:56 INFO - Downloading docopt-0.6.1.tar.gz 17:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:18:56 INFO - Installing collected packages: blobuploader, requests, docopt 17:18:56 INFO - Running setup.py install for blobuploader 17:18:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:18:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:18:56 INFO - Running setup.py install for requests 17:18:56 INFO - Running setup.py install for docopt 17:18:57 INFO - Successfully installed blobuploader requests docopt 17:18:57 INFO - Cleaning up... 17:18:57 INFO - Return code: 0 17:18:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:57 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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:18:57 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 17:18:57 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 17:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:57 INFO - 'HOME': '/Users/cltbld', 17:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:57 INFO - 'LOGNAME': 'cltbld', 17:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:57 INFO - 'MOZ_NO_REMOTE': '1', 17:18:57 INFO - 'NO_EM_RESTART': '1', 17:18:57 INFO - 'PAGER': '/bin/cat', 17:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:57 INFO - 'PWD': '/builds/slave/test', 17:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:57 INFO - 'SHELL': '/bin/bash', 17:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:18:57 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:57 INFO - 'USER': 'cltbld', 17:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:57 INFO - 'XPC_FLAGS': '0x0', 17:18:57 INFO - 'XPC_SERVICE_NAME': '0', 17:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:57 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-RgzMef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:57 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-WrePju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:57 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-GhiJC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:57 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-4nd1qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:57 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-19EIZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-OSqWsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-FCM46S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-BJpB06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-xT02i4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-VMF8Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-ecLt8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-m7ZYzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-vjW7Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-C4hmB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:58 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-eQ_jjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:59 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-iAo3mK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:59 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-o0U_KZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:18:59 INFO - Running setup.py install for manifestparser 17:18:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:18:59 INFO - Running setup.py install for mozcrash 17:18:59 INFO - Running setup.py install for mozdebug 17:18:59 INFO - Running setup.py install for mozdevice 17:18:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:18:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:18:59 INFO - Running setup.py install for mozfile 17:18:59 INFO - Running setup.py install for mozhttpd 17:19:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Running setup.py install for mozinfo 17:19:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Running setup.py install for mozInstall 17:19:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Running setup.py install for mozleak 17:19:00 INFO - Running setup.py install for mozlog 17:19:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Running setup.py install for moznetwork 17:19:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:19:00 INFO - Running setup.py install for mozprocess 17:19:01 INFO - Running setup.py install for mozprofile 17:19:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:19:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:19:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:19:01 INFO - Running setup.py install for mozrunner 17:19:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:19:01 INFO - Running setup.py install for mozscreenshot 17:19:01 INFO - Running setup.py install for moztest 17:19:01 INFO - Running setup.py install for mozversion 17:19:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:19:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:19:01 INFO - Cleaning up... 17:19:01 INFO - Return code: 0 17:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19: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')]} 17:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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')]} 17:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:19: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 17:19: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 17:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:01 INFO - 'HOME': '/Users/cltbld', 17:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:01 INFO - 'LOGNAME': 'cltbld', 17:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:01 INFO - 'MOZ_NO_REMOTE': '1', 17:19:01 INFO - 'NO_EM_RESTART': '1', 17:19:01 INFO - 'PAGER': '/bin/cat', 17:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:01 INFO - 'PWD': '/builds/slave/test', 17:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:01 INFO - 'SHELL': '/bin/bash', 17:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:19:01 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:01 INFO - 'USER': 'cltbld', 17:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:01 INFO - 'XPC_FLAGS': '0x0', 17:19:01 INFO - 'XPC_SERVICE_NAME': '0', 17:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-zBZBaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:19:02 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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-paQeex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:19:02 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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-QyGVFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:19: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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-MUH_gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:19: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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-coLHfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:19: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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-1ZrSUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:19: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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-GXKnJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:19: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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-WwApfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:19: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)) 17:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:19:02 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-V7Rwjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:19:03 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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Rzze_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:19:03 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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-aq9PCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:19:03 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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-SOB0Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:19: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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-a5Wlpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:19: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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-EwGPX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:19: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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-gacXCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:19: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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-ZKoYDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:19: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)) 17:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:19:03 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-0ix0no-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19: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 17:19:06 INFO - Downloading blessings-1.6.tar.gz 17:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:19:06 INFO - Installing collected packages: blessings 17:19:06 INFO - Running setup.py install for blessings 17:19:06 INFO - Successfully installed blessings 17:19:06 INFO - Cleaning up... 17:19:06 INFO - Return code: 0 17:19:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:19:07 INFO - Reading from file tmpfile_stdout 17:19:07 INFO - Current package versions: 17:19:07 INFO - blessings == 1.6 17:19:07 INFO - blobuploader == 1.2.4 17:19:07 INFO - docopt == 0.6.1 17:19:07 INFO - functools32 == 3.2.3-2 17:19:07 INFO - jsonschema == 2.5.1 17:19:07 INFO - manifestparser == 1.1 17:19:07 INFO - mozInstall == 1.12 17:19:07 INFO - mozcrash == 1.0 17:19:07 INFO - mozdebug == 0.1 17:19:07 INFO - mozdevice == 0.49 17:19:07 INFO - mozfile == 1.2 17:19:07 INFO - mozhttpd == 0.7 17:19:07 INFO - mozinfo == 0.9 17:19:07 INFO - mozleak == 0.1 17:19:07 INFO - mozlog == 3.4 17:19:07 INFO - moznetwork == 0.27 17:19:07 INFO - mozprocess == 0.24 17:19:07 INFO - mozprofile == 0.28 17:19:07 INFO - mozrunner == 6.13 17:19:07 INFO - mozscreenshot == 0.1 17:19:07 INFO - mozsystemmonitor == 0.3 17:19:07 INFO - moztest == 0.8 17:19:07 INFO - mozversion == 1.4 17:19:07 INFO - psutil == 3.1.1 17:19:07 INFO - requests == 1.2.3 17:19:07 INFO - wsgiref == 0.1.2 17:19:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19: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')]} 17:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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')]} 17:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:19: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 17:19: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 17:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:07 INFO - 'HOME': '/Users/cltbld', 17:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:07 INFO - 'LOGNAME': 'cltbld', 17:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:07 INFO - 'MOZ_NO_REMOTE': '1', 17:19:07 INFO - 'NO_EM_RESTART': '1', 17:19:07 INFO - 'PAGER': '/bin/cat', 17:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:07 INFO - 'PWD': '/builds/slave/test', 17:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:07 INFO - 'SHELL': '/bin/bash', 17:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:19:07 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:07 INFO - 'USER': 'cltbld', 17:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:07 INFO - 'XPC_FLAGS': '0x0', 17:19:07 INFO - 'XPC_SERVICE_NAME': '0', 17:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19: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)) 17:19:07 INFO - Cleaning up... 17:19:07 INFO - Return code: 0 17:19:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:19: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')]} 17:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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')]} 17:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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 0x10640cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10640f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa70d8be10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, '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.bLW9ZvohQ6/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.TILnklCsEV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:19: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 17:19: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 17:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:07 INFO - 'HOME': '/Users/cltbld', 17:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:07 INFO - 'LOGNAME': 'cltbld', 17:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:07 INFO - 'MOZ_NO_REMOTE': '1', 17:19:07 INFO - 'NO_EM_RESTART': '1', 17:19:07 INFO - 'PAGER': '/bin/cat', 17:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:07 INFO - 'PWD': '/builds/slave/test', 17:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:07 INFO - 'SHELL': '/bin/bash', 17:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:19:07 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:07 INFO - 'USER': 'cltbld', 17:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:07 INFO - 'XPC_FLAGS': '0x0', 17:19:07 INFO - 'XPC_SERVICE_NAME': '0', 17:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:19:07 INFO - Cleaning up... 17:19:07 INFO - Return code: 0 17:19:07 INFO - Running post-action listener: _resource_record_post_action 17:19:07 INFO - Running post-action listener: _start_resource_monitoring 17:19:07 INFO - Starting resource monitoring. 17:19:07 INFO - [mozharness: 2017-02-09 01:19:07.614288Z] Finished create-virtualenv step (success) 17:19:07 INFO - [mozharness: 2017-02-09 01:19:07.615250Z] Running install step. 17:19:07 INFO - Running pre-action listener: _resource_record_pre_action 17:19:07 INFO - Running main action method: install 17:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:19:07 INFO - Reading from file tmpfile_stdout 17:19:07 INFO - Detecting whether we're running mozinstall >=1.0... 17:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:19:07 INFO - Reading from file tmpfile_stdout 17:19:07 INFO - Output received: 17:19:07 INFO - Usage: mozinstall [options] installer 17:19:07 INFO - Options: 17:19:07 INFO - -h, --help show this help message and exit 17:19:07 INFO - -d DEST, --destination=DEST 17:19:07 INFO - Directory to install application into. [default: 17:19:07 INFO - "/builds/slave/test"] 17:19:07 INFO - --app=APP Application being installed. [default: firefox] 17:19:07 INFO - mkdir: /builds/slave/test/build/application 17:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 17:19:27 INFO - Reading from file tmpfile_stdout 17:19:27 INFO - Output received: 17:19:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:19:27 INFO - Running post-action listener: _resource_record_post_action 17:19:27 INFO - [mozharness: 2017-02-09 01:19:27.538732Z] Finished install step (success) 17:19:27 INFO - [mozharness: 2017-02-09 01:19:27.538914Z] Running run-tests step. 17:19:27 INFO - Running pre-action listener: _resource_record_pre_action 17:19:27 INFO - Running main action method: run_tests 17:19:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:19:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:19:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:19:27 INFO - 2017-02-08 17:19:27.553 screenresolution[1894:14476] starting screenresolution argv=screenresolution get 17:19:27 INFO - 2017-02-08 17:19:27.556 screenresolution[1894:14476] Display 0: 1600x1200x32@60 17:19:27 INFO - 2017-02-08 17:19:27.561 screenresolution[1895:14478] starting screenresolution argv=screenresolution list 17:19:27 INFO - Available Modes on Display 0 17:19:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:19:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:19:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:19:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:19:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:19:27 INFO - Intel Iris: 17:19:27 INFO - Chipset Model: Intel Iris 17:19:27 INFO - Type: GPU 17:19:27 INFO - Bus: Built-In 17:19:27 INFO - VRAM (Dynamic, Max): 1536 MB 17:19:27 INFO - Vendor: Intel (0x8086) 17:19:27 INFO - Device ID: 0x0a2e 17:19:27 INFO - Revision ID: 0x0009 17:19:27 INFO - Displays: 17:19:27 INFO - Display: 17:19:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:19:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:19:27 INFO - Display Serial Number: 1600x1200 60 17:19:27 INFO - Main Display: Yes 17:19:27 INFO - Mirror: Off 17:19:27 INFO - Online: Yes 17:19:27 INFO - Rotation: Supported 17:19:27 INFO - Return code: 0 17:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:19:27 INFO - Python 2.7.3 17:19:27 INFO - Return code: 0 17:19:27 INFO - grabbing minidump binary from tooltool 17:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:19:27 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 0x1061ead30>, '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 0x106111db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061dfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:19:27 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 17:19:27 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 17:19:27 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 17:19:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:19:27 INFO - Return code: 0 17:19:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:19:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:19:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:19:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:19:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0328 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bLW9ZvohQ6/Render', 17:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:27 INFO - 'HOME': '/Users/cltbld', 17:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:27 INFO - 'LOGNAME': 'cltbld', 17:19:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:19:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:27 INFO - 'MOZ_NO_REMOTE': '1', 17:19:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:19:27 INFO - 'NO_EM_RESTART': '1', 17:19:27 INFO - 'PAGER': '/bin/cat', 17:19:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:27 INFO - 'PWD': '/builds/slave/test', 17:19:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:27 INFO - 'SHELL': '/bin/bash', 17:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TILnklCsEV/Listeners', 17:19:27 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:27 INFO - 'USER': 'cltbld', 17:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:27 INFO - 'XPC_FLAGS': '0x0', 17:19:27 INFO - 'XPC_SERVICE_NAME': '0', 17:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:19:28 INFO - mozversion application_buildid: 20170208161121 17:19:28 INFO - mozversion application_changeset: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 17:19:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:19:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:19:28 INFO - mozversion application_name: Firefox 17:19:28 INFO - mozversion application_remotingname: firefox-dev 17:19:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:19:28 INFO - mozversion application_vendor: Mozilla 17:19:28 INFO - mozversion application_version: 53.0a2 17:19:28 INFO - mozversion platform_buildid: 20170208161121 17:19:28 INFO - mozversion platform_changeset: 8dcc2332e280ee23815b44ba76f78c9919d9ebd0 17:19:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:19:28 INFO - mozversion platform_version: 53.0a2 17:19:28 INFO - using testdate: 1486603168 17:19:28 INFO - actual date: 1486603168 17:19:28 INFO - starting webserver on 'localhost:52713' 17:19:28 INFO - SUITE-START | Running 2 tests 17:19:28 INFO - TEST-START | dromaeo_css 17:19:28 INFO - operating with platform_type : mac_ 17:19:28 INFO - Initialising browser for dromaeo_css test... 17:19:28 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpslEyJH/profile http://localhost:52713/getInfo.html) 17:19:30 INFO - PROCESS | 1905 | __metrics Screen width/height:1600/1200 17:19:30 INFO - PROCESS | 1905 | colorDepth:24 17:19:30 INFO - PROCESS | 1905 | Browser inner width/height: 1024/689 17:19:30 INFO - PROCESS | 1905 | __metrics 17:19:30 INFO - PROCESS | 1905 | 1486603170414 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:19:30 INFO - PROCESS | 1905 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:19:30 INFO - PROCESS | 1905 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:19:31 INFO - TEST-INFO | 1905: exit 0 17:19:31 INFO - Browser initialized. 17:19:31 INFO - Running cycle 1/1 for dromaeo_css test... 17:19:31 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpslEyJH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:43 INFO - PROCESS | 1907 | RSS: Main: 232206336 17:19:43 INFO - PROCESS | 1907 | 17:22:47 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:52713/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52713/tests/dromaeo/cssquery-ext.html) 17:22:49 INFO - PROCESS | 1907 | RSS: Main: 360431616 17:22:49 INFO - PROCESS | 1907 | 17:24:33 INFO - PROCESS | 1907 | ************************* 17:24:33 INFO - PROCESS | 1907 | A coding exception was thrown and uncaught in a Task. 17:24:33 INFO - PROCESS | 1907 | 17:24:33 ERROR - PROCESS | 1907 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:24:33 INFO - PROCESS | 1907 | Full stack: 17:24:33 INFO - PROCESS | 1907 | ************************* 17:25:52 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:52713/tests/dromaeo/cssquery-ext.html (next: http://localhost:52713/tests/dromaeo/cssquery-jquery.html) 17:25:52 INFO - PROCESS | 1907 | RSS: Main: 361410560 17:25:52 INFO - PROCESS | 1907 | 17:28:54 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:52713/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52713/tests/dromaeo/cssquery-mootools.html) 17:28:55 INFO - PROCESS | 1907 | RSS: Main: 328675328 17:28:55 INFO - PROCESS | 1907 | 17:31:57 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:52713/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52713/tests/dromaeo/cssquery-prototype.html) 17:31:58 INFO - PROCESS | 1907 | RSS: Main: 349503488 17:31:58 INFO - PROCESS | 1907 | 17:35:01 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:52713/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52713/tests/dromaeo/cssquery-yui.html) 17:35:02 INFO - PROCESS | 1907 | RSS: Main: 324816896 17:35:02 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:52713/tests/dromaeo/cssquery-yui.html (next: http://localhost:52713/tests/dromaeo/cssquery-dojo.html) 17:38:05 INFO - PROCESS | 1907 | RSS: Main: 550682624 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | __start_tp_report 17:38:05 INFO - PROCESS | 1907 | _x_x_mozilla_page_load 17:38:05 INFO - PROCESS | 1907 | _x_x_mozilla_page_load_details 17:38:05 INFO - PROCESS | 1907 | |i|pagename|runs| 17:38:05 INFO - PROCESS | 1907 | |0;dojo.html;3390.64398238185;3395.2530443302467;3397.7111945484626;3400.6843327755428;3457.4468085106373;5835.824925252238;5839.795607153746;5861.8827623447505;5879.823605291837;5882.147088672145;5712.971435142829;5772.595918285721;5793.275840519935;5793.739281732322;5799.7680092796345;5170.974145129278;5211.921821172686;5231.73841307935;5245.055889939809;5344.706041167734;5215.817446389377;5217.47825217479;5225.764840582164;5324.148136298193;5330.38700549436;5294.894102117955;5321.095413779667;5361.463853614647;5367.141257398817;5374.274472946161;5349.224362467459;5356.946430535703;5370.43610420904;5381.0852155133725;5382.4886635769535;5245.737713114329;5323.760430780624;5341.732913354317;5346.27825243591;5358.46415358461;4561.863144105682;5705;5724.484796368347;5846.123081537761;5866.296044474648;6254.312025677172;6319.399657032574;6339.397757213103;6339.904901426482;6357.427831495188;6278.6860656966965;6336.049592561107;6339.778107766206;6352.4918006559365;6365.713542890535;5839;5890.617109887887;5937.465628093492;5965.463108320272;5976.223090998143;6287.025511045796;6319.146915166486;6329.715162817684;6350.364963503705;6369.745210191633;6291.087792270096;6293.779717709858;6306.274778400542;6306.400891915261;6311.873762524724;5209.53114219722;6248.968755156195;6264.154339164245;6318.494520438354;6320.209973753281;6146.170267013986;6203.534734894901;6227.6263424193785;6296.181496405529;6302.464290535356;5206.193040078794;5206.843794686166;6292.685365731695;6305.432511074026;6334.398232167937;5041.773120209599;5051.166557517969;5053.0651829410945;5053.242013697901;5075.619328550373;4477.776111194493;4515.683902126885;4536.432945881765;4538.682292239578;4549.658775591844;5701;5759.164921086419;5764.481196692234;5855.648661080349;5954.8809023819285;6197.1324014637985;6262.5929314594405;6312.147860038928;6330.0821380899515;6334.429901308904;5765.509931655856;5832.037713777264;5880.588358814927;5932.317783454958;5947.197128387612;5460.918086228709;5578.135389014709;5578.163275508706;5590;5592.468715472024;6275.215598050244;6301.8109456716375;6391.201099862517;6494.935050649433;6504.317046710122;6234.0337247726675;6339.936600633934;6344.30212676596;6345.8730825384155;6354.364563543609;6484.935150648433;6499.967500162469;6504.804855854332;6506.316836732075;6508.121403610452;6113.694315284129;6125.510168486406;6333.588316759396;6344.68276586178;6348.3651634838;6248.4064013918605;6249.0626406039455;6294.716737746904;6317;6321.905171422341;6170.784022559189;6242.063690446469;6262.624242545462;6271.3728627136925;6273.278572964167;6020.066889632114;6258.780942666985;6259.78090766821;6268.4671802897265;6282.528810339334;5993.910091348721;5997.370276120944;6003.8198854034445;6023.728932197974;6098.9326867798845;6179.486539757028;6201.410865967726;6219.813405597839;6224.06639004153;6229.3770622937345;6044.879102417839;6060.484858787053;6083.300420451527;6084.269887613337;6090 17:38:05 INFO - PROCESS | 1907 | |1;ext.html;440.0802323144627;470.05988023952096;480.96633235673494;496.6622696566335;508.7710530261383;32358.440571537143;41409.79295103524;43351.566484335184;43506;45346;44517;45119.77440112803;45218;45267.0946581069;45368;8553.95723021384;9265.305102117334;9488.24094072476;9490.193333566654;9514.904850951505;8504.829903401927;11391.259568128045;11614.709632259177;11637.767244655102;11805.996490298292;11411.144164187677;11493.597724079658;11494.540218391254;11546.307221566733;11573.479193436273;8490.915090849074;8715.694950676696;11384.260023098555;11453.770924581504;11590.18868679197;11298.548058077668;11553.653390398302;11561.595344162997;11587.826182607268;11588.536458541732;41613.375799363035;41978.74063778061;41982;42013.579864201274;42224.78887605542;3997.780122093284;4021.959780402188;4068.4304197412384;4119.217348703737;4303.956960430418;29611;30247.243818904484;30278;31296.06111816649;32698;3104.8292343921075;3143.4341818472667;3256.153400115963;3317.9004629861142;3343.6154842193214;348711;351687;352396;486579.99999999645;491225;66523;67877.98183027259;67967.66016169987;68490.65754671294;68656.313436866;71131;71239;71258;71265.28734712586;71417.64291178511;16814.831851681327;16860.241289142017;16868;16955.49133525996;17511.562210944572;11799.233049851731;12056.63830085099;12086.697832554257;13030.609081727564;13153.276569788562;19061.714074288895;19438.22247110128;19549.315773947845;19696.803031969783;19751.901240493706;5467.617266791286;6395.168628078413;6402.583832050995;6404.423601875854;6448.387403196689;862.9956850215709;867.2281669314315;890.6081324217324;891.2869704236584;904.0597778310483;25815.444661277194;28664;29993;31118.62817001212;31181.68818311833;932.4731526687535;994.8905620381897;1063.9148868090533;1101.4823033174396;1140.0708422635691;14895.329710163069;15248.923755381375;15714.21428928572;15855.682886342209;16259.83740162607;636006;638322.8083859644;641475;642348;644776;62565.68717156385;62954.68522657449;63006.369936300965;63094;63125;1416.2989320286524;1443.9278036098362;1454.7454195515743;1455.8689717925438;1458.9708205836037;24514.50970980535;28801.42397152087;29209.85395073011;29481;30424;52917.20624190717;53343;53434.46565534295;53449.73275133599;53755.462445375044;455720;546768;547352;550222;550624.2468787631;23387.649185262577;25663;25919.740802591732;26486.470270594094;27000.864995674896;36750.63249367472;37064.25871482609;45215.77392113018;45262;45516;35489.46765798567;36017.099572510895;37089;37406.91462954453;37761.81119094387;36164.4575331365;36621.718239861475;37130.44304335383;37744;38177.427338590496;35141.29717405689;36618.45072323971;36838.8158059208;36937;36948.81525592462;26833;30062.69937300599;31288;32485.350292993535;32665.836670816494;24416.901239443912;24642;24736.876315618305;24841.006359745406;24861.980584852965 17:38:05 INFO - PROCESS | 1907 | |2;jquery.html;395.6083477357416;398.0665339778219;401.6064257028114;405.95128584569846;424.2003822794034;14494.855051449482;19543.99999999998;20779.58440831186;20849.583008339825;20898.582028359444;16086.83913160868;17744.733828992576;20997;21214;21399.35801925941;5840.795572154975;6184.412480814315;6203.1625730526475;6203.2866220384785;6204.286507051702;5950.166976623254;6013.128096426016;6088.269407671063;6109.14471973924;6216.098665693472;6034.668093254869;6050.727717252734;6060.484878780305;6063;6073.058675905242;5798.2172406725185;5899.882002359929;5909.26134233223;6040.607360521574;6051.334353221142;5284.815031473919;5297.841064768063;5301.787928482856;5302.310699609065;5303.124984377573;26023.479530409477;26168.084117055812;26313.3421664458;27101.864490677617;27198.45603087927;2803.3272014716526;2808.8314701117793;2817.8591070446396;2818.8167769094944;2830.3631682871396;14834.406623735038;15582.688346233124;15665;15685.215739212994;16145.32679673694;1852.5796325683914;1853.1568136497997;1857.7120546315343;1858.5911099558075;1870.4014715291112;92037;97029;121766;123277;123314.38342808318;11437.771244575228;11794.823077653842;11804.350760708072;11842.38678827498;11849.763004740029;15230.771538426932;15264.618384540254;15301.311440985182;15324.770128448083;15356.616084597974;7268.927310726931;7955.124936257007;8921.241694455914;9006.63973441055;9065.365424420359;8981.640734370683;8994.235489983294;9034;9158.633654653873;9232.815343693088;5999.730012149464;7337.669804858794;7486.401087912954;7496.362809161283;7500.774976750707;1943.154727693458;1969.773476050244;1970.231609672229;1976.377441106047;1976.8122028407279;912.6714382822179;1042.145440738587;1049.7533079726259;1068.1454836131063;1089.596849165798;20494.487637808877;21401.036953337138;22964.770352296262;23512.7648723514;23760.16839410647;900.3382513852313;951.9428834269966;979.8334283171878;980.3333733061444;1104.5195340027115;10761.946190269155;10999.560017599366;11087.778244435067;11132.220744547803;11152.553897843994;727272;758830.2058489783;773481;778296.1085194538;782153;146630.80053798988;164066;165366;170532.32604532826;175639;1018.5620183321095;1090.3512410115968;1100.6367898593444;1117.1844553476008;1117.536222467667;15416.845831541541;17949.192286347406;18919.864808111557;19173.90413047926;19225.903870480557;40408.79795601003;57825;58325.416745833696;59404.70297648484;59736;55824;76923;86752;87048.12951870401;88267.55866220628;8189.303909167787;8235.21765432283;8266.256036956704;8725.694600688945;8774.38579299443;9267.72196834096;12860.485580576673;13146;13152.276624785345;13571.59285221445;8951;9174.54127293646;11038.613648522263;11209.15931305139;11639.941800290944;5319.813806516908;9186.954065229631;9569.473678947725;10955.630546181726;11275.774484510428;11511.251768635011;11626.76746465083;11703.2978021319;11863.822042669195;11938.985186259266;7274;7544.132424771222;7674.884876726966;7849.901013857951;8857.335699822668;9813.9018609813;11049.447527623426;11196.720081998015;11293.71765705864;11522.596709115141 17:38:05 INFO - PROCESS | 1907 | |3;mootools.html;1444.40779280495;1447.601909474895;1449.4854326714017;1462.349254581711;1466.5380405172373;8050.919490805098;8445.831083378329;8451.577421128943;8453.830923381543;8464.195901389374;6121.632702037869;7666.769996900102;7668.731594394198;7957;8257.25684688378;2311.849729767564;2351.541449417364;2357.1867705641525;2359.6814430051895;2360.2801145650656;2348.847324923883;2361.586722323595;2371.762823717632;2377.643353496972;2384.618461046233;2062.834973202148;2145.5601601671697;2149.183310342076;2153.192552792703;2331.7525124058634;2101.936941891746;2109.504266497372;2268.1494439585153;2338.695969523968;2340.6606042123967;2196.7478537233783;2371.30046636241;2373.2524254859663;2378.191414918919;2380.1788383007834;7626;8463.365247606454;8479.915200847974;8484.32125429964;8485.83028339436;5076.82231121909;5231.476852314776;5242;5245.79016839326;5248.947510524884;3288;3388.5086662434055;3587.461880717913;3600.135967367839;3636.2000359920776;1210.1468464732432;1218.792805223114;1260.6092111445482;1274.9681257968532;1277.5755033138078;10811.297265677706;10821.458927053616;10835.729106772314;10855.40295283759;10865.293755905841;4414.713043652152;4664.906701865943;4748.19280722278;4774.641901857374;4802.759862006886;9432.481213533185;9452.291078169164;9460.432374057578;9474;9481.525923703788;5243.318368612056;5263.605229607795;5270.051390749625;5273.762680679302;5293.153095504701;4670.602998745077;4731.621470282369;4768.3085952536685;4833.854984350475;4837.250226214942;3867.5939026401825;3909.2963266612282;3937.5078115235597;3963.0686788604653;3990.900227494336;1270.5045032437356;1279.9040071994639;1283.3198404845448;1290.7547565962436;1295.6501744528925;779.8557266905641;786.4887822915151;808.955507447096;812.6911773526023;812.9674813007414;5428.75570599324;5435.211894275387;5557.666540007613;5594.608377413542;5614.831555053355;549.9395066542677;574.6494638270651;582.8746819433758;584.748558120004;585.9326177489557;2994.8053376530456;3898.6491215790716;3927.2341893330527;3948.0129967508124;4046.291898917678;9759.463229522444;10013.949930250448;10018.849717254247;10034.448105354275;10043.397396156108;9702.078302561245;9824.754381140528;9867.555959981819;9895.80208395828;9966.152877005536;7526.5107767994905;7867.881981770278;7896.881546776803;7917.485363451357;7917.960410197912;5631.63394379364;5633.38032816398;5680.573956953328;5803.9419605803405;5843.707814609337;8845.646174152962;9154.130357616015;9322.207612353026;9413.811723765622;9425.575849086945;9057.501837398742;9322.207612353026;9459.337846350689;9481.810363792823;9485.952570237105;5921.378255283305;7251.274872512707;7270.89093663606;7393.963030184815;7441.255874412732;7912.208779122042;7978.521288722695;7982;7995.800104997189;7999.040115185982;7107.218205997439;7218.133823941161;7230.566166030055;7266.273372662691;7371.705131794668;6869.175698915962;7017.859642807013;7090.4682148840075;7109.182444019004;7135.571865687868;6404.647744374104;6434;6506.772262970773;6511.283758786435;6523.934760652332;4973.303737476828;5014.498550144956;5039.924401133912;5058.937623099043;5081.390233172043;4902.779374928065;5004.3494345734825;5008;5130.79476820923;5195.142801437721 17:38:05 INFO - PROCESS | 1907 | |4;prototype.html;357.10188874979434;366.7950781682173;367.5405742821473;378.4531352196076;379.1715102501037;5158.123119069758;5253.922945796112;5371.946280537193;5432.266644003058;5451.538273196519;4494.415725955621;4813.783379747911;5298.860744939837;5331.57347412207;5478.890422191564;4013.1772986537835;4070.6336429721246;4158.792060396983;4169.749815011094;4234.9153016939645;3817.675497582709;3895.7728315580644;3981.0142393205097;4180.749155050707;4234.343676730112;3674.485572019906;3698.927311079797;3912.4131380293043;4055.702175303904;4084.5915408459214;4009.278329900617;4017.2725727936977;4017.5781542938157;4021.77880216588;4043.6765058795227;3517.56030496188;3518.296340731864;3520;3526.5768107827225;3530.4174811156126;4327.004788898539;4388.464037586851;4598.770061496945;4872.903596690751;5259.684418934838;3839.5968423315708;3967.3652215645357;4012.6589239914642;4497.437820272466;4559.338895860082;4970.6520543561965;5612.915806262909;5623.493885550321;5670.291213598301;5673.120666296731;3040.7415369693604;3826.5408151022057;3829.4064420014943;3840.519935008124;3921.9019524512114;5431.891362172774;5827.854303642401;5894.410558944071;5900.341788091368;5926.718213487795;3288.523164141234;3412.334594754049;3730.4031354983617;3904.570497245301;3909.179072394829;3367.4443716786454;3675.5405574303213;3945.270125026879;3993.3610622300293;4011.2779699654343;4087.6116768906904;4544.045750392388;4605.930911036337;4611.838585649553;4647.651426143053;4919.942212424275;5447.5641948644015;5570.86072848182;5635.436456354331;5674.347450043298;1115.83820346051;1154.5670373609896;1194.5639841457978;1210.9101808372536;1251.3680591301334;659.6701649175412;718.5006420537676;751.8158899732833;766.8044648614567;867.1025488619375;4749.24012158053;4850.6362022848425;4980.144359249703;4990.900181996412;5117.897642047138;4698.640472062423;5444.945550544523;5455.345358556999;5502.889942201214;5643.746031428595;4511.729296242236;4961.90076198474;4966.801327946841;4971.558248108008;5077.435894873065;2616.435892825042;2720.9660329075223;2782.7912906531687;2867.426514697069;2882.423515296949;4554.544545545419;4914.606831453475;5797.36229014808;5839.474447299764;5862.237909071793;5150.49766159008;5172.637915345889;5509.099360720545;5907.278414598861;5920.851978700567;5205.427402985669;5282.683039017671;5903.93729128761;6081.74975259157;6116.327204007555;5374.919376209282;5788.568801103463;5791.942080579141;5799.82600521985;5874.206982057368;4719.268513380432;4792.592629626521;5602.971985140048;5717.628354156966;5722.3691247995785;5284.168977491442;5915.290165180034;5964.7912323068485;6028;6089.51283897296;3376.3078568892824;3828.272628200633;3848.441975913533;3868.1490072184074;3872.6127387262177;5667.3482549507335;5682.914756278742;5701.546105743009;5741.827745167651;5763.9135412968;4933.9506604933495;5313.814016509404;5627.43725627434;5632;5652.304308707289;4363.47638283408;5506.889862202814;5508.421615730451;5537.117380090788;5631.8592035199445;5194.571492839499;5323.5360275922885;5365.8999905020155;5417.936547589308;5493.313335833021;5485.862853428696;5615.663060216237;5649.683629711799;5712.514436272963;5713.1715901193265;5125.949180417931;5427.402985671652;5485.080221922295;5561.749721262634;5608.214849921096 17:38:05 INFO - PROCESS | 1907 | |5;yui.html;690.2338404371148;715;719.8488317453331;768.7962689887181;844.8986121665401;14827;15261;15445.073295602258;15451.922740386297;15461.22693865305;13745.862541374583;14363.497277595287;14520.201388923604;14527.636809079779;14535.636609084779;847.6778824046869;857.5926434943415;858.8153546987396;863.2144748279068;863.2662237098478;668.2682462703357;694.6179601219335;695.7008486350867;699.2448155991542;850.0904032684998;716.1763971432867;719.0508528742063;861.0829466618063;905.0180554098811;907.133687328603;900.2797761790541;900.698266080867;901.062894589626;901.2609660078705;904.0191392339319;649.4966401039233;670.6747227594612;670.8624731929942;672.0490505934098;673.0678010954814;11992.28046317215;12464;14680;15117.017393869473;15174.620634484116;14252.501162459264;14278.64303392413;14288;14449.566513004522;14475.855241447662;11119.610813621566;11443.370614616193;11620.477078531401;11912.761744765055;11985.880141198651;3290.243244053857;3575.4636804479537;3746.4380342948775;3793.165503589206;3886.125621735115;96918.0308196916;99152;99249;100591;100617;10891.455427228766;11086.722831929266;11109.166812489095;11205.551777928955;11625.476853541611;23060.654090188662;23214;23251.3024609262;23350.299491014957;23397.06411743511;7750.961245193738;7813.413993950477;7828.804279892935;7840.490368126168;7977.48146370484;4233.407322974786;4274.465691788527;4390.736555806662;4446.3108218226225;4457.821687132543;3572.9464058039152;3627.8730244441845;3665.578458477309;3672.412414013745;3677.669009789132;649.76283656466;652.6508318049821;659.5580960756209;665.4476784269142;670.9865802683919;683.1460674157304;694.6804469943883;700.9264027277062;729.9817504562322;742.8885667149971;8144.104148543655;9801.901980980241;10026.899731002597;10212.183025357954;10466.110380617733;617.6355949989528;619.1332135011021;631.6841579210395;643.8036398898288;644.3524258120559;4710.764461776966;4981.501849814989;4982.975085124551;4985.9501404985485;4996.625253105959;96664;98291.50854245682;101012;101591.47612785814;102400.48799756102;88105.55947220222;88155;89033.66449503263;91598;91717.62423563653;65549;69127;74448;74564.62717686377;76744.23255767483;9555.761105972406;9557.474338911426;9622;9630.32587718853;9643.27675424332;50190.99618007692;50736.49263507318;51759.48240517547;52301.47698523118;52822.47177528175;53134.73432632812;66765.66617166882;67798.3220167792;68960;69240;9235.630574776933;11528.308301501937;11699.766004680028;11910.344931028876;12101.939490302491;12376.133670642968;12436.129470936947;12533.561325353568;12627.810582841448;12779.424925878544;11488.770224595293;11766.647000589996;11983.880161198276;12006.699832503908;12186.51253949832;11828.704282392664;11916.10629202796;12334.383280836102;12605.306708130774;12614.810777838156;11307.265027773168;11320.320780753182;11434.256773309708;11452;11665.300081995107;8545.786355341166;8770.605322760619;9248.445093294424;10219.23355748307;10561.788764224826;8571.343390376413;8841.469511829053;9292.58183381763;9952.3230066427;9968.67330446758 17:38:05 INFO - PROCESS | 1907 | __end_tp_report 17:38:05 INFO - PROCESS | 1907 | __start_cc_report 17:38:05 INFO - PROCESS | 1907 | _x_x_mozilla_cycle_collect,1387 17:38:05 INFO - PROCESS | 1907 | __end_cc_report 17:38:05 INFO - PROCESS | 1907 | __startTimestamp1486604285863__endTimestamp 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | ------- Summary: start ------- 17:38:05 INFO - PROCESS | 1907 | Number of tests: 6 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | [#0] dojo.html Cycles:165 Average:5822.45 Median:6023.73 stddev:650.55 (10.8%) stddev-sans-first:623.95 17:38:05 INFO - PROCESS | 1907 | Values: 3390.6 3395.3 3397.7 3400.7 3457.4 5835.8 5839.8 5861.9 5879.8 5882.1 5713.0 5772.6 5793.3 5793.7 5799.8 5171.0 5211.9 5231.7 5245.1 5344.7 5215.8 5217.5 5225.8 5324.1 5330.4 5294.9 5321.1 5361.5 5367.1 5374.3 5349.2 5356.9 5370.4 5381.1 5382.5 5245.7 5323.8 5341.7 5346.3 5358.5 4561.9 5705.0 5724.5 5846.1 5866.3 6254.3 6319.4 6339.4 6339.9 6357.4 6278.7 6336.0 6339.8 6352.5 6365.7 5839.0 5890.6 5937.5 5965.5 5976.2 6287.0 6319.1 6329.7 6350.4 6369.7 6291.1 6293.8 6306.3 6306.4 6311.9 5209.5 6249.0 6264.2 6318.5 6320.2 6146.2 6203.5 6227.6 6296.2 6302.5 5206.2 5206.8 6292.7 6305.4 6334.4 5041.8 5051.2 5053.1 5053.2 5075.6 4477.8 4515.7 4536.4 4538.7 4549.7 5701.0 5759.2 5764.5 5855.6 5954.9 6197.1 6262.6 6312.1 6330.1 6334.4 5765.5 5832.0 5880.6 5932.3 5947.2 5460.9 5578.1 5578.2 5590.0 5592.5 6275.2 6301.8 6391.2 6494.9 6504.3 6234.0 6339.9 6344.3 6345.9 6354.4 6484.9 6500.0 6504.8 6506.3 6508.1 6113.7 6125.5 6333.6 6344.7 6348.4 6248.4 6249.1 6294.7 6317.0 6321.9 6170.8 6242.1 6262.6 6271.4 6273.3 6020.1 6258.8 6259.8 6268.5 6282.5 5993.9 5997.4 6003.8 6023.7 6098.9 6179.5 6201.4 6219.8 6224.1 6229.4 6044.9 6060.5 6083.3 6084.3 6090.0 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | [#1] ext.html Cycles:180 Average:67173.17 Median:36794.72 stddev:143215.73 (389.2%) stddev-sans-first:143529.85 17:38:05 INFO - PROCESS | 1907 | Values: 440.1 470.1 481.0 496.7 508.8 32358.4 41409.8 43351.6 43506.0 45346.0 44517.0 45119.8 45218.0 45267.1 45368.0 8554.0 9265.3 9488.2 9490.2 9514.9 8504.8 11391.3 11614.7 11637.8 11806.0 11411.1 11493.6 11494.5 11546.3 11573.5 8490.9 8715.7 11384.3 11453.8 11590.2 11298.5 11553.7 11561.6 11587.8 11588.5 41613.4 41978.7 41982.0 42013.6 42224.8 3997.8 4022.0 4068.4 4119.2 4304.0 29611.0 30247.2 30278.0 31296.1 32698.0 3104.8 3143.4 3256.2 3317.9 3343.6 348711.0 351687.0 352396.0 486580.0 491225.0 66523.0 67878.0 67967.7 68490.7 68656.3 71131.0 71239.0 71258.0 71265.3 71417.6 16814.8 16860.2 16868.0 16955.5 17511.6 11799.2 12056.6 12086.7 13030.6 13153.3 19061.7 19438.2 19549.3 19696.8 19751.9 5467.6 6395.2 6402.6 6404.4 6448.4 863.0 867.2 890.6 891.3 904.1 25815.4 28664.0 29993.0 31118.6 31181.7 932.5 994.9 1063.9 1101.5 1140.1 14895.3 15248.9 15714.2 15855.7 16259.8 636006.0 638322.8 641475.0 642348.0 644776.0 62565.7 62954.7 63006.4 63094.0 63125.0 1416.3 1443.9 1454.7 1455.9 1459.0 24514.5 28801.4 29209.9 29481.0 30424.0 52917.2 53343.0 53434.5 53449.7 53755.5 455720.0 546768.0 547352.0 550222.0 550624.2 23387.6 25663.0 25919.7 26486.5 27000.9 36750.6 37064.3 45215.8 45262.0 45516.0 35489.5 36017.1 37089.0 37406.9 37761.8 36164.5 36621.7 37130.4 37744.0 38177.4 35141.3 36618.5 36838.8 36937.0 36948.8 26833.0 30062.7 31288.0 32485.4 32665.8 24416.9 24642.0 24736.9 24841.0 24862.0 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | [#2] jquery.html Cycles:180 Average:40805.57 Median:15000.89 stddev:127019.24 (846.7%) stddev-sans-first:127339.32 17:38:05 INFO - PROCESS | 1907 | Values: 395.6 398.1 401.6 406.0 424.2 14494.9 19544.0 20779.6 20849.6 20898.6 16086.8 17744.7 20997.0 21214.0 21399.4 5840.8 6184.4 6203.2 6203.3 6204.3 5950.2 6013.1 6088.3 6109.1 6216.1 6034.7 6050.7 6060.5 6063.0 6073.1 5798.2 5899.9 5909.3 6040.6 6051.3 5284.8 5297.8 5301.8 5302.3 5303.1 26023.5 26168.1 26313.3 27101.9 27198.5 2803.3 2808.8 2817.9 2818.8 2830.4 14834.4 15582.7 15665.0 15685.2 16145.3 1852.6 1853.2 1857.7 1858.6 1870.4 92037.0 97029.0 121766.0 123277.0 123314.4 11437.8 11794.8 11804.4 11842.4 11849.8 15230.8 15264.6 15301.3 15324.8 15356.6 7268.9 7955.1 8921.2 9006.6 9065.4 8981.6 8994.2 9034.0 9158.6 9232.8 5999.7 7337.7 7486.4 7496.4 7500.8 1943.2 1969.8 1970.2 1976.4 1976.8 912.7 1042.1 1049.8 1068.1 1089.6 20494.5 21401.0 22964.8 23512.8 23760.2 900.3 951.9 979.8 980.3 1104.5 10761.9 10999.6 11087.8 11132.2 11152.6 727272.0 758830.2 773481.0 778296.1 782153.0 146630.8 164066.0 165366.0 170532.3 175639.0 1018.6 1090.4 1100.6 1117.2 1117.5 15416.8 17949.2 18919.9 19173.9 19225.9 40408.8 57825.0 58325.4 59404.7 59736.0 55824.0 76923.0 86752.0 87048.1 88267.6 8189.3 8235.2 8266.3 8725.7 8774.4 9267.7 12860.5 13146.0 13152.3 13571.6 8951.0 9174.5 11038.6 11209.2 11639.9 5319.8 9187.0 9569.5 10955.6 11275.8 11511.3 11626.8 11703.3 11863.8 11939.0 7274.0 7544.1 7674.9 7849.9 8857.3 9813.9 11049.4 11196.7 11293.7 11522.6 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | [#3] mootools.html Cycles:180 Average:5439.35 Median:5266.83 stddev:2967.08 (56.3%) stddev-sans-first:2960.22 17:38:05 INFO - PROCESS | 1907 | Values: 1444.4 1447.6 1449.5 1462.3 1466.5 8050.9 8445.8 8451.6 8453.8 8464.2 6121.6 7666.8 7668.7 7957.0 8257.3 2311.8 2351.5 2357.2 2359.7 2360.3 2348.8 2361.6 2371.8 2377.6 2384.6 2062.8 2145.6 2149.2 2153.2 2331.8 2101.9 2109.5 2268.1 2338.7 2340.7 2196.7 2371.3 2373.3 2378.2 2380.2 7626.0 8463.4 8479.9 8484.3 8485.8 5076.8 5231.5 5242.0 5245.8 5248.9 3288.0 3388.5 3587.5 3600.1 3636.2 1210.1 1218.8 1260.6 1275.0 1277.6 10811.3 10821.5 10835.7 10855.4 10865.3 4414.7 4664.9 4748.2 4774.6 4802.8 9432.5 9452.3 9460.4 9474.0 9481.5 5243.3 5263.6 5270.1 5273.8 5293.2 4670.6 4731.6 4768.3 4833.9 4837.3 3867.6 3909.3 3937.5 3963.1 3990.9 1270.5 1279.9 1283.3 1290.8 1295.7 779.9 786.5 809.0 812.7 813.0 5428.8 5435.2 5557.7 5594.6 5614.8 549.9 574.6 582.9 584.7 585.9 2994.8 3898.6 3927.2 3948.0 4046.3 9759.5 10013.9 10018.8 10034.4 10043.4 9702.1 9824.8 9867.6 9895.8 9966.2 7526.5 7867.9 7896.9 7917.5 7918.0 5631.6 5633.4 5680.6 5803.9 5843.7 8845.6 9154.1 9322.2 9413.8 9425.6 9057.5 9322.2 9459.3 9481.8 9486.0 5921.4 7251.3 7270.9 7394.0 7441.3 7912.2 7978.5 7982.0 7995.8 7999.0 7107.2 7218.1 7230.6 7266.3 7371.7 6869.2 7017.9 7090.5 7109.2 7135.6 6404.6 6434.0 6506.8 6511.3 6523.9 4973.3 5014.5 5039.9 5058.9 5081.4 4902.8 5004.3 5008.0 5130.8 5195.1 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | [#4] prototype.html Cycles:180 Average:4491.92 Median:4975.85 stddev:1406.84 (28.3%) stddev-sans-first:1376.13 17:38:05 INFO - PROCESS | 1907 | Values: 357.1 366.8 367.5 378.5 379.2 5158.1 5253.9 5371.9 5432.3 5451.5 4494.4 4813.8 5298.9 5331.6 5478.9 4013.2 4070.6 4158.8 4169.7 4234.9 3817.7 3895.8 3981.0 4180.7 4234.3 3674.5 3698.9 3912.4 4055.7 4084.6 4009.3 4017.3 4017.6 4021.8 4043.7 3517.6 3518.3 3520.0 3526.6 3530.4 4327.0 4388.5 4598.8 4872.9 5259.7 3839.6 3967.4 4012.7 4497.4 4559.3 4970.7 5612.9 5623.5 5670.3 5673.1 3040.7 3826.5 3829.4 3840.5 3921.9 5431.9 5827.9 5894.4 5900.3 5926.7 3288.5 3412.3 3730.4 3904.6 3909.2 3367.4 3675.5 3945.3 3993.4 4011.3 4087.6 4544.0 4605.9 4611.8 4647.7 4919.9 5447.6 5570.9 5635.4 5674.3 1115.8 1154.6 1194.6 1210.9 1251.4 659.7 718.5 751.8 766.8 867.1 4749.2 4850.6 4980.1 4990.9 5117.9 4698.6 5444.9 5455.3 5502.9 5643.7 4511.7 4961.9 4966.8 4971.6 5077.4 2616.4 2721.0 2782.8 2867.4 2882.4 4554.5 4914.6 5797.4 5839.5 5862.2 5150.5 5172.6 5509.1 5907.3 5920.9 5205.4 5282.7 5903.9 6081.7 6116.3 5374.9 5788.6 5791.9 5799.8 5874.2 4719.3 4792.6 5603.0 5717.6 5722.4 5284.2 5915.3 5964.8 6028.0 6089.5 3376.3 3828.3 3848.4 3868.1 3872.6 5667.3 5682.9 5701.5 5741.8 5763.9 4934.0 5313.8 5627.4 5632.0 5652.3 4363.5 5506.9 5508.4 5537.1 5631.9 5194.6 5323.5 5365.9 5417.9 5493.3 5485.9 5615.7 5649.7 5712.5 5713.2 5125.9 5427.4 5485.1 5561.7 5608.2 17:38:05 INFO - PROCESS | 1907 | 17:38:05 INFO - PROCESS | 1907 | [#5] yui.html Cycles:180 Average:19735.40 Median:51247.99 stddev:28416.16 (55.4%) stddev-sans-first:28459.89 17:38:05 INFO - PROCESS | 1907 | Values: 690.2 715.0 719.8 768.8 844.9 14827.0 15261.0 15445.1 15451.9 15461.2 13745.9 14363.5 14520.2 14527.6 14535.6 847.7 857.6 858.8 863.2 863.3 668.3 694.6 695.7 699.2 850.1 716.2 719.1 861.1 905.0 907.1 900.3 900.7 901.1 901.3 904.0 649.5 670.7 670.9 672.0 673.1 11992.3 12464.0 14680.0 15117.0 15174.6 14252.5 14278.6 14288.0 14449.6 14475.9 11119.6 11443.4 11620.5 11912.8 11985.9 3290.2 3575.5 3746.4 3793.2 3886.1 96918.0 99152.0 99249.0 100591.0 100617.0 10891.5 11086.7 11109.2 11205.6 11625.5 23060.7 23214.0 23251.3 23350.3 23397.1 7751.0 7813.4 7828.8 7840.5 7977.5 4233.4 4274.5 4390.7 4446.3 4457.8 3572.9 3627.9 3665.6 3672.4 3677.7 649.8 652.7 659.6 665.4 671.0 683.1 694.7 700.9 730.0 742.9 8144.1 9801.9 10026.9 10212.2 10466.1 617.6 619.1 631.7 643.8 644.4 4710.8 4981.5 4983.0 4986.0 4996.6 96664.0 98291.5 101012.0 101591.5 102400.5 88105.6 88155.0 89033.7 91598.0 91717.6 65549.0 69127.0 74448.0 74564.6 76744.2 9555.8 9557.5 9622.0 9630.3 9643.3 50191.0 50736.5 51759.5 52301.5 52822.5 53134.7 66765.7 67798.3 68960.0 69240.0 9235.6 11528.3 11699.8 11910.3 12101.9 12376.1 12436.1 12533.6 12627.8 12779.4 11488.8 11766.6 11983.9 12006.7 12186.5 11828.7 11916.1 12334.4 12605.3 12614.8 11307.3 11320.3 11434.3 11452.0 11665.3 8545.8 8770.6 9248.4 10219.2 10561.8 8571.3 8841.5 9292.6 9952.3 9968.7 17:38:05 INFO - PROCESS | 1907 | -------- Summary: end -------- 17:38:05 INFO - PROCESS | 1907 | 17:38:06 INFO - TEST-INFO | 1907: exit 0 17:38:06 INFO - TEST-OK | dromaeo_css | took 1118474ms 17:38:06 INFO - TEST-START | kraken 17:38:06 INFO - operating with platform_type : mac_ 17:38:06 INFO - Initialising browser for kraken test... 17:38:06 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpMK4ZtJ/profile http://localhost:52713/getInfo.html) 17:38:09 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 17:38:09 INFO - PROCESS | 1912 | colorDepth:24 17:38:09 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 17:38:09 INFO - PROCESS | 1912 | __metrics 17:38:09 INFO - PROCESS | 1912 | 1486604289072 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:38:09 INFO - PROCESS | 1912 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:38:09 INFO - PROCESS | 1912 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:38:09 INFO - TEST-INFO | 1912: exit 0 17:38:09 INFO - Browser initialized. 17:38:09 INFO - Running cycle 1/1 for kraken test... 17:38:09 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpMK4ZtJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:38:21 INFO - PROCESS | 1913 | RSS: Main: 232415232 17:38:21 INFO - PROCESS | 1913 | 17:38:24 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52713/tests/kraken/driver.html?testName=audio-beat-detection) 17:38:25 INFO - PROCESS | 1913 | RSS: Main: 282550272 17:38:25 INFO - PROCESS | 1913 | 17:38:28 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52713/tests/kraken/driver.html?testName=audio-dft) 17:38:28 INFO - PROCESS | 1913 | RSS: Main: 292589568 17:38:28 INFO - PROCESS | 1913 | 17:38:31 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52713/tests/kraken/driver.html?testName=audio-fft) 17:38:32 INFO - PROCESS | 1913 | RSS: Main: 388542464 17:38:32 INFO - PROCESS | 1913 | 17:38:34 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52713/tests/kraken/driver.html?testName=audio-oscillator) 17:38:35 INFO - PROCESS | 1913 | RSS: Main: 290377728 17:38:35 INFO - PROCESS | 1913 | 17:38:37 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52713/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:38:37 INFO - PROCESS | 1913 | RSS: Main: 291770368 17:38:37 INFO - PROCESS | 1913 | 17:38:40 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52713/tests/kraken/driver.html?testName=imaging-darkroom) 17:38:40 INFO - PROCESS | 1913 | RSS: Main: 334426112 17:38:40 INFO - PROCESS | 1913 | 17:38:43 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52713/tests/kraken/driver.html?testName=imaging-desaturate) 17:38:44 INFO - PROCESS | 1913 | RSS: Main: 334360576 17:38:44 INFO - PROCESS | 1913 | 17:38:47 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52713/tests/kraken/driver.html?testName=json-parse-financial) 17:38:47 INFO - PROCESS | 1913 | RSS: Main: 329375744 17:38:47 INFO - PROCESS | 1913 | 17:38:49 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52713/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:38:50 INFO - PROCESS | 1913 | RSS: Main: 307245056 17:38:50 INFO - PROCESS | 1913 | 17:38:51 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:38:52 INFO - PROCESS | 1913 | RSS: Main: 310624256 17:38:52 INFO - PROCESS | 1913 | 17:38:54 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:38:55 INFO - PROCESS | 1913 | RSS: Main: 315961344 17:38:55 INFO - PROCESS | 1913 | 17:38:58 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:38:59 INFO - PROCESS | 1913 | RSS: Main: 329744384 17:38:59 INFO - PROCESS | 1913 | 17:39:02 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:39:02 INFO - PROCESS | 1913 | RSS: Main: 324055040 17:39:02 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:52713/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52713/tests/kraken/driver.html?testName=ai-astar) 17:39:04 INFO - PROCESS | 1913 | RSS: Main: 368263168 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | __start_tp_report 17:39:04 INFO - PROCESS | 1913 | _x_x_mozilla_page_load 17:39:04 INFO - PROCESS | 1913 | _x_x_mozilla_page_load_details 17:39:04 INFO - PROCESS | 1913 | |i|pagename|runs| 17:39:04 INFO - PROCESS | 1913 | |0;ai-astar;115;115;114;115;113;114;113;114;114;114 17:39:04 INFO - PROCESS | 1913 | |1;audio-beat-detection;139;109;108;145;110;112;135;144;137;110 17:39:04 INFO - PROCESS | 1913 | |2;audio-dft;184;186;184;184;184;184;183;184;183;182 17:39:04 INFO - PROCESS | 1913 | |3;audio-fft;82;58;57;90;61;60;57;57;73;56 17:39:04 INFO - PROCESS | 1913 | |4;audio-oscillator;82;71;86;70;67;69;79;66;69;81 17:39:04 INFO - PROCESS | 1913 | |5;imaging-gaussian-blur;87;85;87;84;87;84;88;84;88;84 17:39:04 INFO - PROCESS | 1913 | |6;imaging-darkroom;94;93;94;93;93;99;94;92;93;94 17:39:04 INFO - PROCESS | 1913 | |7;imaging-desaturate;87;84;82;87;87;84;85;87;85;87 17:39:04 INFO - PROCESS | 1913 | |8;json-parse-financial;58;55;55;53;54;57;56;57;56;57 17:39:04 INFO - PROCESS | 1913 | |9;json-stringify-tinderbox;65;73;81;72;71;69;70;70;69;71 17:39:04 INFO - PROCESS | 1913 | |10;stanford-crypto-aes;87;88;82;83;81;82;86;83;87;91 17:39:04 INFO - PROCESS | 1913 | |11;stanford-crypto-ccm;137;152;146;161;151;136;138;139;174;156 17:39:04 INFO - PROCESS | 1913 | |12;stanford-crypto-pbkdf2;190;237;234;176;168;166;167;167;162;165 17:39:04 INFO - PROCESS | 1913 | |13;stanford-crypto-sha256-iterative;59;63;82;64;66;65;64;65;65;64 17:39:04 INFO - PROCESS | 1913 | __end_tp_report 17:39:04 INFO - PROCESS | 1913 | __start_cc_report 17:39:04 INFO - PROCESS | 1913 | _x_x_mozilla_cycle_collect,1988 17:39:04 INFO - PROCESS | 1913 | __end_cc_report 17:39:04 INFO - PROCESS | 1913 | __startTimestamp1486604344643__endTimestamp 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | ------- Summary: start ------- 17:39:04 INFO - PROCESS | 1913 | Number of tests: 14 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 17:39:04 INFO - PROCESS | 1913 | Values: 115.0 115.0 114.0 115.0 113.0 114.0 113.0 114.0 114.0 114.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:136.00 stddev:16.21 (11.9%) stddev-sans-first:16.37 17:39:04 INFO - PROCESS | 1913 | Values: 139.0 109.0 108.0 145.0 110.0 112.0 135.0 144.0 137.0 110.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 17:39:04 INFO - PROCESS | 1913 | Values: 184.0 186.0 184.0 184.0 184.0 184.0 183.0 184.0 183.0 182.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#3] audio-fft Cycles:10 Average:65.10 Median:60.50 stddev:12.21 (20.2%) stddev-sans-first:11.31 17:39:04 INFO - PROCESS | 1913 | Values: 82.0 58.0 57.0 90.0 61.0 60.0 57.0 57.0 73.0 56.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#4] audio-oscillator Cycles:10 Average:74.00 Median:75.00 stddev:7.23 (9.6%) stddev-sans-first:7.06 17:39:04 INFO - PROCESS | 1913 | Values: 82.0 71.0 86.0 70.0 67.0 69.0 79.0 66.0 69.0 81.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 17:39:04 INFO - PROCESS | 1913 | Values: 87.0 85.0 87.0 84.0 87.0 84.0 88.0 84.0 88.0 84.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:1.91 (2.0%) stddev-sans-first:2.03 17:39:04 INFO - PROCESS | 1913 | Values: 94.0 93.0 94.0 93.0 93.0 99.0 94.0 92.0 93.0 94.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.80 17:39:04 INFO - PROCESS | 1913 | Values: 87.0 84.0 82.0 87.0 87.0 84.0 85.0 87.0 85.0 87.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.50 stddev:1.55 (2.7%) stddev-sans-first:1.42 17:39:04 INFO - PROCESS | 1913 | Values: 58.0 55.0 55.0 53.0 54.0 57.0 56.0 57.0 56.0 57.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:4.09 (5.8%) stddev-sans-first:3.70 17:39:04 INFO - PROCESS | 1913 | Values: 65.0 73.0 81.0 72.0 71.0 69.0 70.0 70.0 69.0 71.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.50 stddev:3.27 (3.8%) stddev-sans-first:3.38 17:39:04 INFO - PROCESS | 1913 | Values: 87.0 88.0 82.0 83.0 81.0 82.0 86.0 83.0 87.0 91.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:151.50 stddev:12.36 (8.2%) stddev-sans-first:12.32 17:39:04 INFO - PROCESS | 1913 | Values: 137.0 152.0 146.0 161.0 151.0 136.0 138.0 139.0 174.0 156.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:172.00 stddev:28.69 (16.7%) stddev-sans-first:30.32 17:39:04 INFO - PROCESS | 1913 | Values: 190.0 237.0 234.0 176.0 168.0 166.0 167.0 167.0 162.0 165.0 17:39:04 INFO - PROCESS | 1913 | 17:39:04 INFO - PROCESS | 1913 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.00 stddev:6.04 (9.3%) stddev-sans-first:5.90 17:39:04 INFO - PROCESS | 1913 | Values: 59.0 63.0 82.0 64.0 66.0 65.0 64.0 65.0 65.0 64.0 17:39:04 INFO - PROCESS | 1913 | -------- Summary: end -------- 17:39:04 INFO - PROCESS | 1913 | 17:39:05 INFO - TEST-INFO | 1913: exit 0 17:39:05 INFO - TEST-OK | kraken | took 58925ms 17:39:05 INFO - SUITE-END | took 1177s 17:39:05 INFO - Completed test suite (00:19:37) 17:39:05 INFO - javascript benchmark 17:39:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.64398238185, 3395.2530443302467, 3397.7111945484626, 3400.6843327755428, 3457.4468085106373, 5835.824925252238, 5839.795607153746, 5861.8827623447505, 5879.823605291837, 5882.147088672145, 5712.971435142829, 5772.595918285721, 5793.275840519935, 5793.739281732322, 5799.7680092796345, 5170.974145129278, 5211.921821172686, 5231.73841307935, 5245.055889939809, 5344.706041167734, 5215.817446389377, 5217.47825217479, 5225.764840582164, 5324.148136298193, 5330.38700549436, 5294.894102117955, 5321.095413779667, 5361.463853614647, 5367.141257398817, 5374.274472946161, 5349.224362467459, 5356.946430535703, 5370.43610420904, 5381.0852155133725, 5382.4886635769535, 5245.737713114329, 5323.760430780624, 5341.732913354317, 5346.27825243591, 5358.46415358461, 4561.863144105682, 5705.0, 5724.484796368347, 5846.123081537761, 5866.296044474648, 6254.312025677172, 6319.399657032574, 6339.397757213103, 6339.904901426482, 6357.427831495188, 6278.6860656966965, 6336.049592561107, 6339.778107766206, 6352.4918006559365, 6365.713542890535, 5839.0, 5890.617109887887, 5937.465628093492, 5965.463108320272, 5976.223090998143, 6287.025511045796, 6319.146915166486, 6329.715162817684, 6350.364963503705, 6369.745210191633, 6291.087792270096, 6293.779717709858, 6306.274778400542, 6306.400891915261, 6311.873762524724, 5209.53114219722, 6248.968755156195, 6264.154339164245, 6318.494520438354, 6320.209973753281, 6146.170267013986, 6203.534734894901, 6227.6263424193785, 6296.181496405529, 6302.464290535356, 5206.193040078794, 5206.843794686166, 6292.685365731695, 6305.432511074026, 6334.398232167937, 5041.773120209599, 5051.166557517969, 5053.0651829410945, 5053.242013697901, 5075.619328550373, 4477.776111194493, 4515.683902126885, 4536.432945881765, 4538.682292239578, 4549.658775591844, 5701.0, 5759.164921086419, 5764.481196692234, 5855.648661080349, 5954.8809023819285, 6197.1324014637985, 6262.5929314594405, 6312.147860038928, 6330.0821380899515, 6334.429901308904, 5765.509931655856, 5832.037713777264, 5880.588358814927, 5932.317783454958, 5947.197128387612, 5460.918086228709, 5578.135389014709, 5578.163275508706, 5590.0, 5592.468715472024, 6275.215598050244, 6301.8109456716375, 6391.201099862517, 6494.935050649433, 6504.317046710122, 6234.0337247726675, 6339.936600633934, 6344.30212676596, 6345.8730825384155, 6354.364563543609, 6484.935150648433, 6499.967500162469, 6504.804855854332, 6506.316836732075, 6508.121403610452, 6113.694315284129, 6125.510168486406, 6333.588316759396, 6344.68276586178, 6348.3651634838, 6248.4064013918605, 6249.0626406039455, 6294.716737746904, 6317.0, 6321.905171422341, 6170.784022559189, 6242.063690446469, 6262.624242545462, 6271.3728627136925, 6273.278572964167, 6020.066889632114, 6258.780942666985, 6259.78090766821, 6268.4671802897265, 6282.528810339334, 5993.910091348721, 5997.370276120944, 6003.8198854034445, 6023.728932197974, 6098.9326867798845, 6179.486539757028, 6201.410865967726, 6219.813405597839, 6224.06639004153, 6229.3770622937345, 6044.879102417839, 6060.484858787053, 6083.300420451527, 6084.269887613337, 6090.0], "value": 5781.703790238253, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.0802323144627, 470.05988023952096, 480.96633235673494, 496.6622696566335, 508.7710530261383, 32358.440571537143, 41409.79295103524, 43351.566484335184, 43506.0, 45346.0, 44517.0, 45119.77440112803, 45218.0, 45267.0946581069, 45368.0, 8553.95723021384, 9265.305102117334, 9488.24094072476, 9490.193333566654, 9514.904850951505, 8504.829903401927, 11391.259568128045, 11614.709632259177, 11637.767244655102, 11805.996490298292, 11411.144164187677, 11493.597724079658, 11494.540218391254, 11546.307221566733, 11573.479193436273, 8490.915090849074, 8715.694950676696, 11384.260023098555, 11453.770924581504, 11590.18868679197, 11298.548058077668, 11553.653390398302, 11561.595344162997, 11587.826182607268, 11588.536458541732, 41613.375799363035, 41978.74063778061, 41982.0, 42013.579864201274, 42224.78887605542, 3997.780122093284, 4021.959780402188, 4068.4304197412384, 4119.217348703737, 4303.956960430418, 29611.0, 30247.243818904484, 30278.0, 31296.06111816649, 32698.0, 3104.8292343921075, 3143.4341818472667, 3256.153400115963, 3317.9004629861142, 3343.6154842193214, 348711.0, 351687.0, 352396.0, 486579.99999999645, 491225.0, 66523.0, 67877.98183027259, 67967.66016169987, 68490.65754671294, 68656.313436866, 71131.0, 71239.0, 71258.0, 71265.28734712586, 71417.64291178511, 16814.831851681327, 16860.241289142017, 16868.0, 16955.49133525996, 17511.562210944572, 11799.233049851731, 12056.63830085099, 12086.697832554257, 13030.609081727564, 13153.276569788562, 19061.714074288895, 19438.22247110128, 19549.315773947845, 19696.803031969783, 19751.901240493706, 5467.617266791286, 6395.168628078413, 6402.583832050995, 6404.423601875854, 6448.387403196689, 862.9956850215709, 867.2281669314315, 890.6081324217324, 891.2869704236584, 904.0597778310483, 25815.444661277194, 28664.0, 29993.0, 31118.62817001212, 31181.68818311833, 932.4731526687535, 994.8905620381897, 1063.9148868090533, 1101.4823033174396, 1140.0708422635691, 14895.329710163069, 15248.923755381375, 15714.21428928572, 15855.682886342209, 16259.83740162607, 636006.0, 638322.8083859644, 641475.0, 642348.0, 644776.0, 62565.68717156385, 62954.68522657449, 63006.369936300965, 63094.0, 63125.0, 1416.2989320286524, 1443.9278036098362, 1454.7454195515743, 1455.8689717925438, 1458.9708205836037, 24514.50970980535, 28801.42397152087, 29209.85395073011, 29481.0, 30424.0, 52917.20624190717, 53343.0, 53434.46565534295, 53449.73275133599, 53755.462445375044, 455720.0, 546768.0, 547352.0, 550222.0, 550624.2468787631, 23387.649185262577, 25663.0, 25919.740802591732, 26486.470270594094, 27000.864995674896, 36750.63249367472, 37064.25871482609, 45215.77392113018, 45262.0, 45516.0, 35489.46765798567, 36017.099572510895, 37089.0, 37406.91462954453, 37761.81119094387, 36164.4575331365, 36621.718239861475, 37130.44304335383, 37744.0, 38177.427338590496, 35141.29717405689, 36618.45072323971, 36838.8158059208, 36937.0, 36948.81525592462, 26833.0, 30062.69937300599, 31288.0, 32485.350292993535, 32665.836670816494, 24416.901239443912, 24642.0, 24736.876315618305, 24841.006359745406, 24861.980584852965], "value": 20133.095452761125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.6083477357416, 398.0665339778219, 401.6064257028114, 405.95128584569846, 424.2003822794034, 14494.855051449482, 19543.99999999998, 20779.58440831186, 20849.583008339825, 20898.582028359444, 16086.83913160868, 17744.733828992576, 20997.0, 21214.0, 21399.35801925941, 5840.795572154975, 6184.412480814315, 6203.1625730526475, 6203.2866220384785, 6204.286507051702, 5950.166976623254, 6013.128096426016, 6088.269407671063, 6109.14471973924, 6216.098665693472, 6034.668093254869, 6050.727717252734, 6060.484878780305, 6063.0, 6073.058675905242, 5798.2172406725185, 5899.882002359929, 5909.26134233223, 6040.607360521574, 6051.334353221142, 5284.815031473919, 5297.841064768063, 5301.787928482856, 5302.310699609065, 5303.124984377573, 26023.479530409477, 26168.084117055812, 26313.3421664458, 27101.864490677617, 27198.45603087927, 2803.3272014716526, 2808.8314701117793, 2817.8591070446396, 2818.8167769094944, 2830.3631682871396, 14834.406623735038, 15582.688346233124, 15665.0, 15685.215739212994, 16145.32679673694, 1852.5796325683914, 1853.1568136497997, 1857.7120546315343, 1858.5911099558075, 1870.4014715291112, 92037.0, 97029.0, 121766.0, 123277.0, 123314.38342808318, 11437.771244575228, 11794.823077653842, 11804.350760708072, 11842.38678827498, 11849.763004740029, 15230.771538426932, 15264.618384540254, 15301.311440985182, 15324.770128448083, 15356.616084597974, 7268.927310726931, 7955.124936257007, 8921.241694455914, 9006.63973441055, 9065.365424420359, 8981.640734370683, 8994.235489983294, 9034.0, 9158.633654653873, 9232.815343693088, 5999.730012149464, 7337.669804858794, 7486.401087912954, 7496.362809161283, 7500.774976750707, 1943.154727693458, 1969.773476050244, 1970.231609672229, 1976.377441106047, 1976.8122028407279, 912.6714382822179, 1042.145440738587, 1049.7533079726259, 1068.1454836131063, 1089.596849165798, 20494.487637808877, 21401.036953337138, 22964.770352296262, 23512.7648723514, 23760.16839410647, 900.3382513852313, 951.9428834269966, 979.8334283171878, 980.3333733061444, 1104.5195340027115, 10761.946190269155, 10999.560017599366, 11087.778244435067, 11132.220744547803, 11152.553897843994, 727272.0, 758830.2058489783, 773481.0, 778296.1085194538, 782153.0, 146630.80053798988, 164066.0, 165366.0, 170532.32604532826, 175639.0, 1018.5620183321095, 1090.3512410115968, 1100.6367898593444, 1117.1844553476008, 1117.536222467667, 15416.845831541541, 17949.192286347406, 18919.864808111557, 19173.90413047926, 19225.903870480557, 40408.79795601003, 57825.0, 58325.416745833696, 59404.70297648484, 59736.0, 55824.0, 76923.0, 86752.0, 87048.12951870401, 88267.55866220628, 8189.303909167787, 8235.21765432283, 8266.256036956704, 8725.694600688945, 8774.38579299443, 9267.72196834096, 12860.485580576673, 13146.0, 13152.276624785345, 13571.59285221445, 8951.0, 9174.54127293646, 11038.613648522263, 11209.15931305139, 11639.941800290944, 5319.813806516908, 9186.954065229631, 9569.473678947725, 10955.630546181726, 11275.774484510428, 11511.251768635011, 11626.76746465083, 11703.2978021319, 11863.822042669195, 11938.985186259266, 7274.0, 7544.132424771222, 7674.884876726966, 7849.901013857951, 8857.335699822668, 9813.9018609813, 11049.447527623426, 11196.720081998015, 11293.71765705864, 11522.596709115141], "value": 10095.372214330104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.40779280495, 1447.601909474895, 1449.4854326714017, 1462.349254581711, 1466.5380405172373, 8050.919490805098, 8445.831083378329, 8451.577421128943, 8453.830923381543, 8464.195901389374, 6121.632702037869, 7666.769996900102, 7668.731594394198, 7957.0, 8257.25684688378, 2311.849729767564, 2351.541449417364, 2357.1867705641525, 2359.6814430051895, 2360.2801145650656, 2348.847324923883, 2361.586722323595, 2371.762823717632, 2377.643353496972, 2384.618461046233, 2062.834973202148, 2145.5601601671697, 2149.183310342076, 2153.192552792703, 2331.7525124058634, 2101.936941891746, 2109.504266497372, 2268.1494439585153, 2338.695969523968, 2340.6606042123967, 2196.7478537233783, 2371.30046636241, 2373.2524254859663, 2378.191414918919, 2380.1788383007834, 7626.0, 8463.365247606454, 8479.915200847974, 8484.32125429964, 8485.83028339436, 5076.82231121909, 5231.476852314776, 5242.0, 5245.79016839326, 5248.947510524884, 3288.0, 3388.5086662434055, 3587.461880717913, 3600.135967367839, 3636.2000359920776, 1210.1468464732432, 1218.792805223114, 1260.6092111445482, 1274.9681257968532, 1277.5755033138078, 10811.297265677706, 10821.458927053616, 10835.729106772314, 10855.40295283759, 10865.293755905841, 4414.713043652152, 4664.906701865943, 4748.19280722278, 4774.641901857374, 4802.759862006886, 9432.481213533185, 9452.291078169164, 9460.432374057578, 9474.0, 9481.525923703788, 5243.318368612056, 5263.605229607795, 5270.051390749625, 5273.762680679302, 5293.153095504701, 4670.602998745077, 4731.621470282369, 4768.3085952536685, 4833.854984350475, 4837.250226214942, 3867.5939026401825, 3909.2963266612282, 3937.5078115235597, 3963.0686788604653, 3990.900227494336, 1270.5045032437356, 1279.9040071994639, 1283.3198404845448, 1290.7547565962436, 1295.6501744528925, 779.8557266905641, 786.4887822915151, 808.955507447096, 812.6911773526023, 812.9674813007414, 5428.75570599324, 5435.211894275387, 5557.666540007613, 5594.608377413542, 5614.831555053355, 549.9395066542677, 574.6494638270651, 582.8746819433758, 584.748558120004, 585.9326177489557, 2994.8053376530456, 3898.6491215790716, 3927.2341893330527, 3948.0129967508124, 4046.291898917678, 9759.463229522444, 10013.949930250448, 10018.849717254247, 10034.448105354275, 10043.397396156108, 9702.078302561245, 9824.754381140528, 9867.555959981819, 9895.80208395828, 9966.152877005536, 7526.5107767994905, 7867.881981770278, 7896.881546776803, 7917.485363451357, 7917.960410197912, 5631.63394379364, 5633.38032816398, 5680.573956953328, 5803.9419605803405, 5843.707814609337, 8845.646174152962, 9154.130357616015, 9322.207612353026, 9413.811723765622, 9425.575849086945, 9057.501837398742, 9322.207612353026, 9459.337846350689, 9481.810363792823, 9485.952570237105, 5921.378255283305, 7251.274872512707, 7270.89093663606, 7393.963030184815, 7441.255874412732, 7912.208779122042, 7978.521288722695, 7982.0, 7995.800104997189, 7999.040115185982, 7107.218205997439, 7218.133823941161, 7230.566166030055, 7266.273372662691, 7371.705131794668, 6869.175698915962, 7017.859642807013, 7090.4682148840075, 7109.182444019004, 7135.571865687868, 6404.647744374104, 6434.0, 6506.772262970773, 6511.283758786435, 6523.934760652332, 4973.303737476828, 5014.498550144956, 5039.924401133912, 5058.937623099043, 5081.390233172043, 4902.779374928065, 5004.3494345734825, 5008.0, 5130.79476820923, 5195.142801437721], "value": 4370.67008104853, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.10188874979434, 366.7950781682173, 367.5405742821473, 378.4531352196076, 379.1715102501037, 5158.123119069758, 5253.922945796112, 5371.946280537193, 5432.266644003058, 5451.538273196519, 4494.415725955621, 4813.783379747911, 5298.860744939837, 5331.57347412207, 5478.890422191564, 4013.1772986537835, 4070.6336429721246, 4158.792060396983, 4169.749815011094, 4234.9153016939645, 3817.675497582709, 3895.7728315580644, 3981.0142393205097, 4180.749155050707, 4234.343676730112, 3674.485572019906, 3698.927311079797, 3912.4131380293043, 4055.702175303904, 4084.5915408459214, 4009.278329900617, 4017.2725727936977, 4017.5781542938157, 4021.77880216588, 4043.6765058795227, 3517.56030496188, 3518.296340731864, 3520.0, 3526.5768107827225, 3530.4174811156126, 4327.004788898539, 4388.464037586851, 4598.770061496945, 4872.903596690751, 5259.684418934838, 3839.5968423315708, 3967.3652215645357, 4012.6589239914642, 4497.437820272466, 4559.338895860082, 4970.6520543561965, 5612.915806262909, 5623.493885550321, 5670.291213598301, 5673.120666296731, 3040.7415369693604, 3826.5408151022057, 3829.4064420014943, 3840.519935008124, 3921.9019524512114, 5431.891362172774, 5827.854303642401, 5894.410558944071, 5900.341788091368, 5926.718213487795, 3288.523164141234, 3412.334594754049, 3730.4031354983617, 3904.570497245301, 3909.179072394829, 3367.4443716786454, 3675.5405574303213, 3945.270125026879, 3993.3610622300293, 4011.2779699654343, 4087.6116768906904, 4544.045750392388, 4605.930911036337, 4611.838585649553, 4647.651426143053, 4919.942212424275, 5447.5641948644015, 5570.86072848182, 5635.436456354331, 5674.347450043298, 1115.83820346051, 1154.5670373609896, 1194.5639841457978, 1210.9101808372536, 1251.3680591301334, 659.6701649175412, 718.5006420537676, 751.8158899732833, 766.8044648614567, 867.1025488619375, 4749.24012158053, 4850.6362022848425, 4980.144359249703, 4990.900181996412, 5117.897642047138, 4698.640472062423, 5444.945550544523, 5455.345358556999, 5502.889942201214, 5643.746031428595, 4511.729296242236, 4961.90076198474, 4966.801327946841, 4971.558248108008, 5077.435894873065, 2616.435892825042, 2720.9660329075223, 2782.7912906531687, 2867.426514697069, 2882.423515296949, 4554.544545545419, 4914.606831453475, 5797.36229014808, 5839.474447299764, 5862.237909071793, 5150.49766159008, 5172.637915345889, 5509.099360720545, 5907.278414598861, 5920.851978700567, 5205.427402985669, 5282.683039017671, 5903.93729128761, 6081.74975259157, 6116.327204007555, 5374.919376209282, 5788.568801103463, 5791.942080579141, 5799.82600521985, 5874.206982057368, 4719.268513380432, 4792.592629626521, 5602.971985140048, 5717.628354156966, 5722.3691247995785, 5284.168977491442, 5915.290165180034, 5964.7912323068485, 6028.0, 6089.51283897296, 3376.3078568892824, 3828.272628200633, 3848.441975913533, 3868.1490072184074, 3872.6127387262177, 5667.3482549507335, 5682.914756278742, 5701.546105743009, 5741.827745167651, 5763.9135412968, 4933.9506604933495, 5313.814016509404, 5627.43725627434, 5632.0, 5652.304308707289, 4363.47638283408, 5506.889862202814, 5508.421615730451, 5537.117380090788, 5631.8592035199445, 5194.571492839499, 5323.5360275922885, 5365.8999905020155, 5417.936547589308, 5493.313335833021, 5485.862853428696, 5615.663060216237, 5649.683629711799, 5712.514436272963, 5713.1715901193265, 5125.949180417931, 5427.402985671652, 5485.080221922295, 5561.749721262634, 5608.214849921096], "value": 4046.2436501485386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.2338404371148, 715.0, 719.8488317453331, 768.7962689887181, 844.8986121665401, 14827.0, 15261.0, 15445.073295602258, 15451.922740386297, 15461.22693865305, 13745.862541374583, 14363.497277595287, 14520.201388923604, 14527.636809079779, 14535.636609084779, 847.6778824046869, 857.5926434943415, 858.8153546987396, 863.2144748279068, 863.2662237098478, 668.2682462703357, 694.6179601219335, 695.7008486350867, 699.2448155991542, 850.0904032684998, 716.1763971432867, 719.0508528742063, 861.0829466618063, 905.0180554098811, 907.133687328603, 900.2797761790541, 900.698266080867, 901.062894589626, 901.2609660078705, 904.0191392339319, 649.4966401039233, 670.6747227594612, 670.8624731929942, 672.0490505934098, 673.0678010954814, 11992.28046317215, 12464.0, 14680.0, 15117.017393869473, 15174.620634484116, 14252.501162459264, 14278.64303392413, 14288.0, 14449.566513004522, 14475.855241447662, 11119.610813621566, 11443.370614616193, 11620.477078531401, 11912.761744765055, 11985.880141198651, 3290.243244053857, 3575.4636804479537, 3746.4380342948775, 3793.165503589206, 3886.125621735115, 96918.0308196916, 99152.0, 99249.0, 100591.0, 100617.0, 10891.455427228766, 11086.722831929266, 11109.166812489095, 11205.551777928955, 11625.476853541611, 23060.654090188662, 23214.0, 23251.3024609262, 23350.299491014957, 23397.06411743511, 7750.961245193738, 7813.413993950477, 7828.804279892935, 7840.490368126168, 7977.48146370484, 4233.407322974786, 4274.465691788527, 4390.736555806662, 4446.3108218226225, 4457.821687132543, 3572.9464058039152, 3627.8730244441845, 3665.578458477309, 3672.412414013745, 3677.669009789132, 649.76283656466, 652.6508318049821, 659.5580960756209, 665.4476784269142, 670.9865802683919, 683.1460674157304, 694.6804469943883, 700.9264027277062, 729.9817504562322, 742.8885667149971, 8144.104148543655, 9801.901980980241, 10026.899731002597, 10212.183025357954, 10466.110380617733, 617.6355949989528, 619.1332135011021, 631.6841579210395, 643.8036398898288, 644.3524258120559, 4710.764461776966, 4981.501849814989, 4982.975085124551, 4985.9501404985485, 4996.625253105959, 96664.0, 98291.50854245682, 101012.0, 101591.47612785814, 102400.48799756102, 88105.55947220222, 88155.0, 89033.66449503263, 91598.0, 91717.62423563653, 65549.0, 69127.0, 74448.0, 74564.62717686377, 76744.23255767483, 9555.761105972406, 9557.474338911426, 9622.0, 9630.32587718853, 9643.27675424332, 50190.99618007692, 50736.49263507318, 51759.48240517547, 52301.47698523118, 52822.47177528175, 53134.73432632812, 66765.66617166882, 67798.3220167792, 68960.0, 69240.0, 9235.630574776933, 11528.308301501937, 11699.766004680028, 11910.344931028876, 12101.939490302491, 12376.133670642968, 12436.129470936947, 12533.561325353568, 12627.810582841448, 12779.424925878544, 11488.770224595293, 11766.647000589996, 11983.880161198276, 12006.699832503908, 12186.51253949832, 11828.704282392664, 11916.10629202796, 12334.383280836102, 12605.306708130774, 12614.810777838156, 11307.265027773168, 11320.320780753182, 11434.256773309708, 11452.0, 11665.300081995107, 8545.786355341166, 8770.605322760619, 9248.445093294424, 10219.23355748307, 10561.788764224826, 8571.343390376413, 8841.469511829053, 9292.58183381763, 9952.3230066427, 9968.67330446758], "value": 7230.903929310183, "unit": "score"}], "name": "dromaeo_css", "value": 7291.581647404421, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 115.0, 113.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 108.0, 145.0, 110.0, 112.0, 135.0, 144.0, 137.0, 110.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 184.0, 184.0, 184.0, 183.0, 184.0, 183.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 57.0, 90.0, 61.0, 60.0, 57.0, 57.0, 73.0, 56.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 86.0, 70.0, 67.0, 69.0, 79.0, 66.0, 69.0, 81.0], "value": 74.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 99.0, 94.0, 92.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 82.0, 87.0, 87.0, 84.0, 85.0, 87.0, 85.0, 87.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 55.0, 53.0, 54.0, 57.0, 56.0, 57.0, 56.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 81.0, 72.0, 71.0, 69.0, 70.0, 70.0, 69.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 82.0, 83.0, 81.0, 82.0, 86.0, 83.0, 87.0, 91.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 152.0, 146.0, 161.0, 151.0, 136.0, 138.0, 139.0, 174.0, 156.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 237.0, 234.0, 176.0, 168.0, 166.0, 167.0, 167.0, 162.0, 165.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 82.0, 64.0, 66.0, 65.0, 64.0, 65.0, 65.0, 64.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1436.9, "alertThreshold": 2.0}]} 17:39:05 INFO - Return code: 0 17:39:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:39:05 ERROR - # TBPL SUCCESS # 17:39:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:39:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:39:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:39:05 INFO - 2017-02-08 17:39:05.962 screenresolution[1917:16532] starting screenresolution argv=screenresolution get 17:39:05 INFO - 2017-02-08 17:39:05.965 screenresolution[1917:16532] Display 0: 1600x1200x32@60 17:39:05 INFO - 2017-02-08 17:39:05.971 screenresolution[1918:16535] starting screenresolution argv=screenresolution list 17:39:05 INFO - Available Modes on Display 0 17:39:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:39:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:39:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:39:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:39:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:39:06 INFO - Intel Iris: 17:39:06 INFO - Chipset Model: Intel Iris 17:39:06 INFO - Type: GPU 17:39:06 INFO - Bus: Built-In 17:39:06 INFO - VRAM (Dynamic, Max): 1536 MB 17:39:06 INFO - Vendor: Intel (0x8086) 17:39:06 INFO - Device ID: 0x0a2e 17:39:06 INFO - Revision ID: 0x0009 17:39:06 INFO - Displays: 17:39:06 INFO - Display: 17:39:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:39:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:39:06 INFO - Display Serial Number: 1600x1200 60 17:39:06 INFO - Main Display: Yes 17:39:06 INFO - Mirror: Off 17:39:06 INFO - Online: Yes 17:39:06 INFO - Rotation: Supported 17:39:06 INFO - Return code: 0 17:39:06 INFO - Running post-action listener: _resource_record_post_action 17:39:06 INFO - [mozharness: 2017-02-09 01:39:06.207428Z] Finished run-tests step (success) 17:39:06 INFO - Running post-run listener: _resource_record_post_run 17:39:06 INFO - Total resource usage - Wall time: 1198s; CPU: 53.0%; Read bytes: 528384; Write bytes: 507103744; Read time: 9; Write time: 1577 17:39:06 INFO - TinderboxPrint: CPU usage
53.4% 17:39:06 INFO - TinderboxPrint: I/O read bytes / time
528,384 / 9 17:39:06 INFO - TinderboxPrint: I/O write bytes / time
507,103,744 / 1,577 17:39:06 INFO - TinderboxPrint: CPU idle
2,231.5 (46.6%) 17:39:06 INFO - TinderboxPrint: CPU system
959.2 (20.0%) 17:39:06 INFO - TinderboxPrint: CPU user
1,603.0 (33.4%) 17:39:06 INFO - TinderboxPrint: Swap in / out
208,494,592 / 0 17:39:06 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 195566080; Write bytes: 190445056; Read time: 14742; Write time: 406 17:39:06 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 528384; Write bytes: 296199168; Read time: 9; Write time: 1113 17:39:06 INFO - Running post-run listener: _upload_blobber_files 17:39:06 INFO - Blob upload gear active. 17:39:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:39:06 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 17:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:39:07 INFO - (blobuploader) - INFO - Open directory for files ... 17:39:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:39:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:39:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:07 INFO - (blobuploader) - INFO - Done attempting. 17:39:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:39:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:39:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:08 INFO - (blobuploader) - INFO - Done attempting. 17:39:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:39:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:09 INFO - (blobuploader) - INFO - Done attempting. 17:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:12 INFO - (blobuploader) - INFO - Done attempting. 17:39:12 INFO - (blobuploader) - INFO - Iteration through files over. 17:39:12 INFO - Return code: 0 17:39:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:39:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:39:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9209330c9f94fd8764e37b184ec1a6e8bd27d1caf549c2c46644eac3d86cc943ba49417b4045179368eef0652b48b8001d062ccf0a02a0c10a4417850af3a902", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c9353af93133ce24d0f17d1ff67ed3b17237b5871f5a0e30ea24af88aa0699441e5b44126c0d971dd9748ff4d8c1ed285d1c5ee2f3838c30f398ae3ba71101c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ade907a93c6510efb087480862c0feb38f12809ebd8b75df9fbb841a57d5160ceb22e2dceaf810f9f69ca5b324452d83743787fc0c6f9b646c4ba0a565a73951", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9e70b11c5a97020b33edeece5bc17a44bc7f873a4b721ff24478d683bcddd56e9b7aab5378350e693dd34993d46b9335ae7f5f07583bfb6aae3f7d0746cfa0"} 17:39:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:39:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:39:12 INFO - Contents: 17:39:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9209330c9f94fd8764e37b184ec1a6e8bd27d1caf549c2c46644eac3d86cc943ba49417b4045179368eef0652b48b8001d062ccf0a02a0c10a4417850af3a902", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c9353af93133ce24d0f17d1ff67ed3b17237b5871f5a0e30ea24af88aa0699441e5b44126c0d971dd9748ff4d8c1ed285d1c5ee2f3838c30f398ae3ba71101c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ade907a93c6510efb087480862c0feb38f12809ebd8b75df9fbb841a57d5160ceb22e2dceaf810f9f69ca5b324452d83743787fc0c6f9b646c4ba0a565a73951", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9e70b11c5a97020b33edeece5bc17a44bc7f873a4b721ff24478d683bcddd56e9b7aab5378350e693dd34993d46b9335ae7f5f07583bfb6aae3f7d0746cfa0"} 17:39:12 INFO - Running post-run listener: copy_logs_to_upload_dir 17:39:12 INFO - Copying logs to upload dir... 17:39:12 INFO - mkdir: /builds/slave/test/build/upload/logs 17:39:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.651480 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-02-08 17:39:12.188919) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-08 17:39:12.192060) ========= 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.bLW9ZvohQ6/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.TILnklCsEV/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9209330c9f94fd8764e37b184ec1a6e8bd27d1caf549c2c46644eac3d86cc943ba49417b4045179368eef0652b48b8001d062ccf0a02a0c10a4417850af3a902", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c9353af93133ce24d0f17d1ff67ed3b17237b5871f5a0e30ea24af88aa0699441e5b44126c0d971dd9748ff4d8c1ed285d1c5ee2f3838c30f398ae3ba71101c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ade907a93c6510efb087480862c0feb38f12809ebd8b75df9fbb841a57d5160ceb22e2dceaf810f9f69ca5b324452d83743787fc0c6f9b646c4ba0a565a73951", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9e70b11c5a97020b33edeece5bc17a44bc7f873a4b721ff24478d683bcddd56e9b7aab5378350e693dd34993d46b9335ae7f5f07583bfb6aae3f7d0746cfa0"} build_url:https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/EAObvDMCTNOSG0WloBnh5g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9209330c9f94fd8764e37b184ec1a6e8bd27d1caf549c2c46644eac3d86cc943ba49417b4045179368eef0652b48b8001d062ccf0a02a0c10a4417850af3a902", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c9353af93133ce24d0f17d1ff67ed3b17237b5871f5a0e30ea24af88aa0699441e5b44126c0d971dd9748ff4d8c1ed285d1c5ee2f3838c30f398ae3ba71101c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ade907a93c6510efb087480862c0feb38f12809ebd8b75df9fbb841a57d5160ceb22e2dceaf810f9f69ca5b324452d83743787fc0c6f9b646c4ba0a565a73951", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff9e70b11c5a97020b33edeece5bc17a44bc7f873a4b721ff24478d683bcddd56e9b7aab5378350e693dd34993d46b9335ae7f5f07583bfb6aae3f7d0746cfa0"}' ========= master_lag: 1.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-08 17:39:13.577470) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:39:13.577839) ========= 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.bLW9ZvohQ6/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.TILnklCsEV/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:39:13.626717) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:39:13.627044) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:39:13.627370) ========= ========= Total master_lag: 1.53 =========