builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1492593358.23 results: success (0) buildid: 20170419004020 builduid: 09d8446ae3704619910565d11d382ce8 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.227951) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.228367) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.228658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.253152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.253490) ========= 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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-04-19 02:15:58-- 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% 143M=0s 2017-04-19 02:15:58 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.404722) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.405047) ========= 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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.021531 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 02:15:58.455996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-19 02:15:58.456355) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WFm2wByg9Z/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.K7IdmJQjpp/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-04-19 02:15:58,519 truncating revision to first 12 chars 2017-04-19 02:15:58,519 Setting DEBUG logging. 2017-04-19 02:15:58,519 attempt 1/10 2017-04-19 02:15:58,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-19 02:16:00,147 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.882414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-19 02:16:00.362590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-19 02:16:00.362913) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-19 02:16:00.363312) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-19 02:16:00.363775) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-19 02:16:00.383596) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-19 02:16:00.383975) ========= /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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16:00 INFO - MultiFileLogger online at 20170419 02:16:00 in /builds/slave/test 02:16:00 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 02:16:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:16:00 INFO - {'append_to_log': False, 02:16:00 INFO - 'base_work_dir': '/builds/slave/test', 02:16:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:16:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:16:00 INFO - 'branch': 'Mozilla-Aurora', 02:16:00 INFO - 'buildbot_json_path': 'buildprops.json', 02:16:00 INFO - 'config_files': ('talos/mac_config.py',), 02:16:00 INFO - 'default_actions': ('clobber', 02:16:00 INFO - 'read-buildbot-config', 02:16:00 INFO - 'download-and-extract', 02:16:00 INFO - 'populate-webroot', 02:16:00 INFO - 'create-virtualenv', 02:16:00 INFO - 'install', 02:16:00 INFO - 'run-tests'), 02:16:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:16:00 INFO - 'download_minidump_stackwalk': True, 02:16:00 INFO - 'download_symbols': 'ondemand', 02:16:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:16:00 INFO - 'tooltool.py': '/tools/tooltool.py', 02:16:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:16:00 INFO - '/tools/misc-python/virtualenv.py')}, 02:16:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:16:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:16:00 INFO - 'gecko_profile': False, 02:16:00 INFO - 'gecko_profile_interval': 0, 02:16:00 INFO - 'installer_path': 'installer.exe', 02:16:00 INFO - 'log_level': 'info', 02:16:00 INFO - 'log_name': 'talos', 02:16:00 INFO - 'log_to_console': True, 02:16:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:16:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:16:00 INFO - 'opt_config_files': (), 02:16:00 INFO - 'pip_index': False, 02:16:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:16:00 INFO - 'cmd': ('bash', 02:16:00 INFO - '-c', 02:16:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:16:00 INFO - 'enabled': True, 02:16:00 INFO - 'halt_on_failure': False, 02:16:00 INFO - 'name': 'check_screen_resolution'},), 02:16:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:16:00 INFO - 'cmd': ('bash', 02:16:00 INFO - '-c', 02:16:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:16:00 INFO - 'enabled': True, 02:16:00 INFO - 'halt_on_failure': False, 02:16:00 INFO - 'name': 'check_screen_resolution'},), 02:16:00 INFO - 'run_cmd_checks_enabled': True, 02:16:00 INFO - 'suite': 'dromaeojs', 02:16:00 INFO - 'system_bits': '32', 02:16:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:16:00 INFO - 'title': 't-yosemite-r7-0292', 02:16:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:16:00 INFO - 'use_talos_json': True, 02:16:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:16:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:16:00 INFO - 'work_dir': 'build'} 02:16:00 INFO - [mozharness: 2017-04-19 09:16:00.565491Z] Running clobber step. 02:16:00 INFO - Running pre-action listener: _resource_record_pre_action 02:16:00 INFO - Running main action method: clobber 02:16:00 INFO - rmtree: /builds/slave/test/build 02:16:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:16:00 INFO - Running post-action listener: _resource_record_post_action 02:16:00 INFO - [mozharness: 2017-04-19 09:16:00.927205Z] Finished clobber step (success) 02:16:00 INFO - [mozharness: 2017-04-19 09:16:00.927367Z] Running read-buildbot-config step. 02:16:00 INFO - Running pre-action listener: _resource_record_pre_action 02:16:00 INFO - Running main action method: read_buildbot_config 02:16:00 INFO - Using buildbot properties: 02:16:00 INFO - { 02:16:00 INFO - "project": "", 02:16:00 INFO - "product": "firefox", 02:16:00 INFO - "script_repo_revision": "production", 02:16:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:16:00 INFO - "repository": "", 02:16:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:16:00 INFO - "buildid": "20170419004020", 02:16:00 INFO - "stage_platform": "macosx64", 02:16:00 INFO - "basedir": "/builds/slave/test", 02:16:00 INFO - "nightly_build": "True", 02:16:00 INFO - "buildnumber": 23, 02:16:00 INFO - "slavename": "t-yosemite-r7-0292", 02:16:00 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:16:00 INFO - "platform": "yosemite_r7", 02:16:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:16:00 INFO - "branch": "mozilla-aurora", 02:16:00 INFO - "repo_path": "releases/mozilla-aurora", 02:16:00 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:16:00 INFO - "pgo_build": "False", 02:16:00 INFO - "builduid": "09d8446ae3704619910565d11d382ce8", 02:16:00 INFO - "slavebuilddir": "test" 02:16:00 INFO - } 02:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:16:00 INFO - Running post-action listener: _resource_record_post_action 02:16:00 INFO - [mozharness: 2017-04-19 09:16:00.930933Z] Finished read-buildbot-config step (success) 02:16:00 INFO - [mozharness: 2017-04-19 09:16:00.931046Z] Running download-and-extract step. 02:16:00 INFO - Running pre-action listener: _resource_record_pre_action 02:16:00 INFO - Running main action method: download_and_extract 02:16:00 INFO - mkdir: /builds/slave/test/build/tests 02:16:00 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')]} 02:16:00 INFO - trying https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:16:02 INFO - Downloaded 1199 bytes. 02:16:02 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:02 INFO - Using the following test package requirements: 02:16:02 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:16:02 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:16:02 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:16:02 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'jsshell-mac.zip'], 02:16:02 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:16:02 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:16:02 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:16:02 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:16:02 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:16:02 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:02 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:16:02 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:16:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:16:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:16:02 INFO - Fetch https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:16:03 INFO - Expected file size: 51794693 02:16:03 INFO - Obtained file size: 51794693 02:16:19 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:16:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:16:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:16:19 INFO - Fetch https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:16:21 INFO - Expected file size: 51794693 02:16:21 INFO - Obtained file size: 51794693 02:16:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:16:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:16:36 INFO - Fetch https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:16:37 INFO - Expected file size: 14404424 02:16:37 INFO - Obtained file size: 14404424 02:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:38 INFO - trying https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:16:38 INFO - Downloading https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:16:40 INFO - Downloaded 74781932 bytes. 02:16:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:16:40 INFO - mkdir: /builds/slave/test/properties 02:16:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:16:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:16:40 INFO - Contents: 02:16:40 INFO - build_url:https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:16:41 INFO - Running post-action listener: _resource_record_post_action 02:16:41 INFO - Running post-action listener: set_extra_try_arguments 02:16:41 INFO - [mozharness: 2017-04-19 09:16:41.119870Z] Finished download-and-extract step (success) 02:16:41 INFO - [mozharness: 2017-04-19 09:16:41.120023Z] Running populate-webroot step. 02:16:41 INFO - Running pre-action listener: _resource_record_pre_action 02:16:41 INFO - Running main action method: populate_webroot 02:16:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:16:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:16:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:16:41 INFO - '--tppagecycles', 02:16:41 INFO - '7'], 02:16:41 INFO - 'tests': ['tsvgm']}}, 02:16:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:16:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:16:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:16:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:16:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['damp', 'tps']}, 02:16:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'tests': ['damp', 'tps']}, 02:16:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['dromaeo_dom']}, 02:16:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:16:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['basic_compositor_video']}, 02:16:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:16:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['a11yr', 02:16:41 INFO - 'ts_paint', 02:16:41 INFO - 'tpaint', 02:16:41 INFO - 'sessionrestore', 02:16:41 INFO - 'sessionrestore_no_auto_restore', 02:16:41 INFO - 'tabpaint']}, 02:16:41 INFO - 'other-e10s': {'tests': ['a11yr', 02:16:41 INFO - 'ts_paint', 02:16:41 INFO - 'tpaint', 02:16:41 INFO - 'sessionrestore', 02:16:41 INFO - 'sessionrestore_no_auto_restore', 02:16:41 INFO - 'tabpaint']}, 02:16:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:16:41 INFO - 'ts_paint', 02:16:41 INFO - 'tpaint', 02:16:41 INFO - 'sessionrestore', 02:16:41 INFO - 'sessionrestore_no_auto_restore', 02:16:41 INFO - 'tabpaint']}, 02:16:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:16:41 INFO - 'ts_paint', 02:16:41 INFO - 'tpaint', 02:16:41 INFO - 'sessionrestore', 02:16:41 INFO - 'sessionrestore_no_auto_restore', 02:16:41 INFO - 'tabpaint']}, 02:16:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['a11yr', 02:16:41 INFO - 'ts_paint', 02:16:41 INFO - 'tpaint', 02:16:41 INFO - 'sessionrestore', 02:16:41 INFO - 'sessionrestore_no_auto_restore', 02:16:41 INFO - 'tabpaint']}, 02:16:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['a11yr', 02:16:41 INFO - 'ts_paint', 02:16:41 INFO - 'tpaint', 02:16:41 INFO - 'sessionrestore', 02:16:41 INFO - 'sessionrestore_no_auto_restore', 02:16:41 INFO - 'tabpaint']}, 02:16:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['tsvgx', 02:16:41 INFO - 'tsvgr_opacity', 02:16:41 INFO - 'tart', 02:16:41 INFO - 'tscrollx', 02:16:41 INFO - 'cart', 02:16:41 INFO - 'tsvg_static']}, 02:16:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:16:41 INFO - 'tsvgr_opacity', 02:16:41 INFO - 'tart', 02:16:41 INFO - 'tscrollx', 02:16:41 INFO - 'cart', 02:16:41 INFO - 'tsvg_static']}, 02:16:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'talos_options': ['--disable-e10s'], 02:16:41 INFO - 'tests': ['tp5o']}, 02:16:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:16:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'talos_options': ['--disable-e10s', 02:16:41 INFO - '--xperf_path', 02:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:41 INFO - 'tests': ['tp5n']}, 02:16:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:16:41 INFO - 'talos_options': ['--xperf_path', 02:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:41 INFO - 'tests': ['tp5n']}}, 02:16:41 INFO - 'talos.zip': {'path': '', 02:16:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:16:41 INFO - Running post-action listener: _resource_record_post_action 02:16:41 INFO - [mozharness: 2017-04-19 09:16:41.138744Z] Finished populate-webroot step (success) 02:16:41 INFO - [mozharness: 2017-04-19 09:16:41.138887Z] Running create-virtualenv step. 02:16:41 INFO - Running pre-action listener: _resource_record_pre_action 02:16:41 INFO - Running main action method: create_virtualenv 02:16:41 INFO - Creating virtualenv /builds/slave/test/build/venv 02:16:41 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 02:16:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:16:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:16:41 INFO - Using real prefix '/tools/python27' 02:16:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:16:41 INFO - Installing distribute.............................................................................................................................................................................................done. 02:16:44 INFO - Installing pip.................done. 02:16:44 INFO - Return code: 0 02:16:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:16:45 INFO - Reading from file tmpfile_stdout 02:16:45 INFO - Output received: 02:16:45 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) 02:16:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16: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', 'pip>=1.5'] in /builds/slave/test/build 02:16: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 pip>=1.5 02:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:45 INFO - 'HOME': '/Users/cltbld', 02:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:45 INFO - 'LOGNAME': 'cltbld', 02:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:45 INFO - 'MOZ_NO_REMOTE': '1', 02:16:45 INFO - 'NO_EM_RESTART': '1', 02:16:45 INFO - 'PAGER': '/bin/cat', 02:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:45 INFO - 'PWD': '/builds/slave/test', 02:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:45 INFO - 'SHELL': '/bin/bash', 02:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:16:45 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:45 INFO - 'USER': 'cltbld', 02:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:45 INFO - 'XPC_FLAGS': '0x0', 02:16:45 INFO - 'XPC_SERVICE_NAME': '0', 02:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:45 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 02:16:45 INFO - Cleaning up... 02:16:45 INFO - Return code: 0 02:16:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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', '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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:16: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 psutil>=3.1.1 02:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:45 INFO - 'HOME': '/Users/cltbld', 02:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:45 INFO - 'LOGNAME': 'cltbld', 02:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:45 INFO - 'MOZ_NO_REMOTE': '1', 02:16:45 INFO - 'NO_EM_RESTART': '1', 02:16:45 INFO - 'PAGER': '/bin/cat', 02:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:45 INFO - 'PWD': '/builds/slave/test', 02:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:45 INFO - 'SHELL': '/bin/bash', 02:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:16:45 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:45 INFO - 'USER': 'cltbld', 02:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:45 INFO - 'XPC_FLAGS': '0x0', 02:16:45 INFO - 'XPC_SERVICE_NAME': '0', 02:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:45 INFO - Downloading/unpacking psutil>=3.1.1 02:16: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 02:16: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 02:16: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 02:16: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 02:16: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 02:16: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 02:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:16:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:49 INFO - Installing collected packages: psutil 02:16:49 INFO - Running setup.py install for psutil 02:16:49 INFO - building 'psutil._psutil_osx' extension 02:16:49 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 02:16:49 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 02:16:49 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 02:16:49 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 02:16:49 INFO - building 'psutil._psutil_posix' extension 02:16:49 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 02:16:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:16:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:16:49 INFO - ^ 02:16:49 INFO - 1 warning generated. 02:16:49 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 02:16:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:50 INFO - Successfully installed psutil 02:16:50 INFO - Cleaning up... 02:16:50 INFO - Return code: 0 02:16:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:50 INFO - 'HOME': '/Users/cltbld', 02:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:50 INFO - 'LOGNAME': 'cltbld', 02:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:50 INFO - 'MOZ_NO_REMOTE': '1', 02:16:50 INFO - 'NO_EM_RESTART': '1', 02:16:50 INFO - 'PAGER': '/bin/cat', 02:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:50 INFO - 'PWD': '/builds/slave/test', 02:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:50 INFO - 'SHELL': '/bin/bash', 02:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:16:50 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:50 INFO - 'USER': 'cltbld', 02:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:50 INFO - 'XPC_FLAGS': '0x0', 02:16:50 INFO - 'XPC_SERVICE_NAME': '0', 02:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:16:53 INFO - Installing collected packages: mozsystemmonitor 02:16:53 INFO - Running setup.py install for mozsystemmonitor 02:16:53 INFO - Successfully installed mozsystemmonitor 02:16:53 INFO - Cleaning up... 02:16:53 INFO - Return code: 0 02:16:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:16:53 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')]} 02:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:53 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')]} 02:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:54 INFO - 'HOME': '/Users/cltbld', 02:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:54 INFO - 'LOGNAME': 'cltbld', 02:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:54 INFO - 'MOZ_NO_REMOTE': '1', 02:16:54 INFO - 'NO_EM_RESTART': '1', 02:16:54 INFO - 'PAGER': '/bin/cat', 02:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:54 INFO - 'PWD': '/builds/slave/test', 02:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:54 INFO - 'SHELL': '/bin/bash', 02:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:16:54 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:54 INFO - 'USER': 'cltbld', 02:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:54 INFO - 'XPC_FLAGS': '0x0', 02:16:54 INFO - 'XPC_SERVICE_NAME': '0', 02:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:54 INFO - Downloading/unpacking jsonschema==2.5.1 02:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:16:57 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 02:16:57 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 02:16:57 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 02:16:57 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 02:16:57 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 02:16:57 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 02:16:57 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:58 INFO - warning: no files found matching '*.txt' 02:16:58 INFO - no previously-included directories found matching 'build' 02:16:58 INFO - no previously-included directories found matching 'dist' 02:16:58 INFO - no previously-included directories found matching '.git*' 02:16:58 INFO - Installing collected packages: jsonschema, functools32 02:16:58 INFO - Running setup.py install for functools32 02:16:58 INFO - warning: no files found matching '*.txt' 02:16:58 INFO - no previously-included directories found matching 'build' 02:16:58 INFO - no previously-included directories found matching 'dist' 02:16:58 INFO - no previously-included directories found matching '.git*' 02:16:58 INFO - Successfully installed jsonschema functools32 02:16:58 INFO - Cleaning up... 02:16:58 INFO - Return code: 0 02:16:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:58 INFO - 'HOME': '/Users/cltbld', 02:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:58 INFO - 'LOGNAME': 'cltbld', 02:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:58 INFO - 'MOZ_NO_REMOTE': '1', 02:16:58 INFO - 'NO_EM_RESTART': '1', 02:16:58 INFO - 'PAGER': '/bin/cat', 02:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:58 INFO - 'PWD': '/builds/slave/test', 02:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:58 INFO - 'SHELL': '/bin/bash', 02:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:16:58 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:58 INFO - 'USER': 'cltbld', 02:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:58 INFO - 'XPC_FLAGS': '0x0', 02:16:58 INFO - 'XPC_SERVICE_NAME': '0', 02:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:16:58 INFO - Cleaning up... 02:16:58 INFO - Return code: 0 02:16:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:58 INFO - 'HOME': '/Users/cltbld', 02:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:58 INFO - 'LOGNAME': 'cltbld', 02:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:58 INFO - 'MOZ_NO_REMOTE': '1', 02:16:58 INFO - 'NO_EM_RESTART': '1', 02:16:58 INFO - 'PAGER': '/bin/cat', 02:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:58 INFO - 'PWD': '/builds/slave/test', 02:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:58 INFO - 'SHELL': '/bin/bash', 02:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:16:58 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:58 INFO - 'USER': 'cltbld', 02:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:58 INFO - 'XPC_FLAGS': '0x0', 02:16:58 INFO - 'XPC_SERVICE_NAME': '0', 02:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:58 INFO - Downloading/unpacking blobuploader==1.2.4 02:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:01 INFO - Downloading blobuploader-1.2.4.tar.gz 02:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:17:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:17:01 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 02:17:01 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 02:17:01 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 02:17:01 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 02:17:01 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 02:17:01 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 02:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:17:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:02 INFO - Downloading docopt-0.6.1.tar.gz 02:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:17:02 INFO - Installing collected packages: blobuploader, requests, docopt 02:17:02 INFO - Running setup.py install for blobuploader 02:17:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:17:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:17:02 INFO - Running setup.py install for requests 02:17:03 INFO - Running setup.py install for docopt 02:17:03 INFO - Successfully installed blobuploader requests docopt 02:17:03 INFO - Cleaning up... 02:17:03 INFO - Return code: 0 02:17:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:03 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')]} 02:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:03 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')]} 02:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:03 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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:17:03 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 02:17:03 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 02:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:03 INFO - 'HOME': '/Users/cltbld', 02:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:03 INFO - 'LOGNAME': 'cltbld', 02:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:03 INFO - 'MOZ_NO_REMOTE': '1', 02:17:03 INFO - 'NO_EM_RESTART': '1', 02:17:03 INFO - 'PAGER': '/bin/cat', 02:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:03 INFO - 'PWD': '/builds/slave/test', 02:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:03 INFO - 'SHELL': '/bin/bash', 02:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:17:03 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:03 INFO - 'USER': 'cltbld', 02:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:03 INFO - 'XPC_FLAGS': '0x0', 02:17:03 INFO - 'XPC_SERVICE_NAME': '0', 02:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:03 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gJRuV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-JE4Ltt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-XsdLx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-tB1Oa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-B_NM2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-8ikuph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-TnR3CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-36uhCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-f3lZTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Bwo5JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:04 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-CSeA3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-D6lmYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-b01dU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-348twR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-tZNRGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-yqBEhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:05 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-iE6ccf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:17:05 INFO - Running setup.py install for manifestparser 02:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:17:05 INFO - Running setup.py install for mozcrash 02:17:06 INFO - Running setup.py install for mozdebug 02:17:06 INFO - Running setup.py install for mozdevice 02:17:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozfile 02:17:06 INFO - Running setup.py install for mozhttpd 02:17:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozinfo 02:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozInstall 02:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:17:06 INFO - Running setup.py install for mozleak 02:17:07 INFO - Running setup.py install for mozlog 02:17:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Running setup.py install for moznetwork 02:17:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Running setup.py install for mozprocess 02:17:07 INFO - Running setup.py install for mozprofile 02:17:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Running setup.py install for mozrunner 02:17:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:17:07 INFO - Running setup.py install for mozscreenshot 02:17:08 INFO - Running setup.py install for moztest 02:17:08 INFO - Running setup.py install for mozversion 02:17:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:17:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:17:08 INFO - Cleaning up... 02:17:08 INFO - Return code: 0 02:17:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:08 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')]} 02:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:08 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')]} 02:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:08 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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:17:08 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 02:17:08 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 02:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:08 INFO - 'HOME': '/Users/cltbld', 02:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:08 INFO - 'LOGNAME': 'cltbld', 02:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:08 INFO - 'MOZ_NO_REMOTE': '1', 02:17:08 INFO - 'NO_EM_RESTART': '1', 02:17:08 INFO - 'PAGER': '/bin/cat', 02:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:08 INFO - 'PWD': '/builds/slave/test', 02:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:08 INFO - 'SHELL': '/bin/bash', 02:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:17:08 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:08 INFO - 'USER': 'cltbld', 02:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:08 INFO - 'XPC_FLAGS': '0x0', 02:17:08 INFO - 'XPC_SERVICE_NAME': '0', 02:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-QKzg23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-10D9yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:08 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-hdvSDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:08 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)) 02:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-D1TSk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-pYtFBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-TnlHgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-iXFuju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-SOPN_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-zSFtnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-vDcd8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-tc1ShH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:09 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)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-uy3wMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:09 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-SxYp94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:10 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)) 02:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:10 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-G6xmBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:10 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)) 02:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:10 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-tbaDDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:10 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)) 02:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:10 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Xq467j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:10 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)) 02:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:10 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-3NzcGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:10 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)) 02:17:10 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)) 02:17:10 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)) 02:17:10 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)) 02:17:10 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)) 02:17:10 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)) 02:17:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:17:10 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 02:17:10 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 02:17:10 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 02:17:10 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 02:17:10 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 02:17:10 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 02:17:13 INFO - Downloading blessings-1.6.tar.gz 02:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:17:13 INFO - Installing collected packages: blessings 02:17:13 INFO - Running setup.py install for blessings 02:17:13 INFO - Successfully installed blessings 02:17:13 INFO - Cleaning up... 02:17:13 INFO - Return code: 0 02:17:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:17:13 INFO - Reading from file tmpfile_stdout 02:17:13 INFO - Current package versions: 02:17:13 INFO - blessings == 1.6 02:17:13 INFO - blobuploader == 1.2.4 02:17:13 INFO - docopt == 0.6.1 02:17:13 INFO - functools32 == 3.2.3-2 02:17:13 INFO - jsonschema == 2.5.1 02:17:13 INFO - manifestparser == 1.1 02:17:13 INFO - mozInstall == 1.12 02:17:13 INFO - mozcrash == 1.0 02:17:13 INFO - mozdebug == 0.1 02:17:13 INFO - mozdevice == 0.49 02:17:13 INFO - mozfile == 1.2 02:17:13 INFO - mozhttpd == 0.7 02:17:13 INFO - mozinfo == 0.9 02:17:13 INFO - mozleak == 0.1 02:17:13 INFO - mozlog == 3.4 02:17:13 INFO - moznetwork == 0.27 02:17:13 INFO - mozprocess == 0.25 02:17:13 INFO - mozprofile == 0.28 02:17:13 INFO - mozrunner == 6.13 02:17:13 INFO - mozscreenshot == 0.1 02:17:13 INFO - mozsystemmonitor == 0.3 02:17:13 INFO - moztest == 0.8 02:17:13 INFO - mozversion == 1.4 02:17:13 INFO - psutil == 3.1.1 02:17:13 INFO - requests == 1.2.3 02:17:13 INFO - wsgiref == 0.1.2 02:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:13 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')]} 02:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:13 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')]} 02:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:13 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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:17:13 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 02:17:13 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 02:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:13 INFO - 'HOME': '/Users/cltbld', 02:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:13 INFO - 'LOGNAME': 'cltbld', 02:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:13 INFO - 'MOZ_NO_REMOTE': '1', 02:17:13 INFO - 'NO_EM_RESTART': '1', 02:17:13 INFO - 'PAGER': '/bin/cat', 02:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:13 INFO - 'PWD': '/builds/slave/test', 02:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:13 INFO - 'SHELL': '/bin/bash', 02:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:17:13 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:13 INFO - 'USER': 'cltbld', 02:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:13 INFO - 'XPC_FLAGS': '0x0', 02:17:13 INFO - 'XPC_SERVICE_NAME': '0', 02:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:13 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)) 02:17:13 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)) 02:17:13 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)) 02:17:13 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)) 02:17:13 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)) 02:17:13 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)) 02:17:13 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)) 02:17:14 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)) 02:17:14 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)) 02:17:14 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)) 02:17:14 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)) 02:17:14 INFO - Cleaning up... 02:17:14 INFO - Return code: 0 02:17:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:17:14 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')]} 02:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:14 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')]} 02:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:14 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 0x10565cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10565f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d79da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, '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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 02:17:14 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 02:17:14 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 02:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:14 INFO - 'HOME': '/Users/cltbld', 02:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:14 INFO - 'LOGNAME': 'cltbld', 02:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:14 INFO - 'MOZ_NO_REMOTE': '1', 02:17:14 INFO - 'NO_EM_RESTART': '1', 02:17:14 INFO - 'PAGER': '/bin/cat', 02:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:14 INFO - 'PWD': '/builds/slave/test', 02:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:14 INFO - 'SHELL': '/bin/bash', 02:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:17:14 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:14 INFO - 'USER': 'cltbld', 02:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:14 INFO - 'XPC_FLAGS': '0x0', 02:17:14 INFO - 'XPC_SERVICE_NAME': '0', 02:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:17:14 INFO - Cleaning up... 02:17:14 INFO - Return code: 0 02:17:14 INFO - Running post-action listener: _resource_record_post_action 02:17:14 INFO - Running post-action listener: _start_resource_monitoring 02:17:14 INFO - Starting resource monitoring. 02:17:14 INFO - [mozharness: 2017-04-19 09:17:14.311385Z] Finished create-virtualenv step (success) 02:17:14 INFO - [mozharness: 2017-04-19 09:17:14.312277Z] Running install step. 02:17:14 INFO - Running pre-action listener: _resource_record_pre_action 02:17:14 INFO - Running main action method: install 02:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:17:14 INFO - Reading from file tmpfile_stdout 02:17:14 INFO - Detecting whether we're running mozinstall >=1.0... 02:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:17:14 INFO - Reading from file tmpfile_stdout 02:17:14 INFO - Output received: 02:17:14 INFO - Usage: mozinstall [options] installer 02:17:14 INFO - Options: 02:17:14 INFO - -h, --help show this help message and exit 02:17:14 INFO - -d DEST, --destination=DEST 02:17:14 INFO - Directory to install application into. [default: 02:17:14 INFO - "/builds/slave/test"] 02:17:14 INFO - --app=APP Application being installed. [default: firefox] 02:17:14 INFO - mkdir: /builds/slave/test/build/application 02:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:17:35 INFO - Reading from file tmpfile_stdout 02:17:35 INFO - Output received: 02:17:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:17:35 INFO - Running post-action listener: _resource_record_post_action 02:17:35 INFO - [mozharness: 2017-04-19 09:17:35.220445Z] Finished install step (success) 02:17:35 INFO - [mozharness: 2017-04-19 09:17:35.220573Z] Running run-tests step. 02:17:35 INFO - Running pre-action listener: _resource_record_pre_action 02:17:35 INFO - Running main action method: run_tests 02:17:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:17:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:17:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:17:35 INFO - 2017-04-19 02:17:35.233 screenresolution[2857:305216] starting screenresolution argv=screenresolution get 02:17:35 INFO - 2017-04-19 02:17:35.236 screenresolution[2857:305216] Display 0: 1600x1200x32@60 02:17:35 INFO - 2017-04-19 02:17:35.241 screenresolution[2858:305220] starting screenresolution argv=screenresolution list 02:17:35 INFO - Available Modes on Display 0 02:17:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:17:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:17:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:17:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:17:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:17:35 INFO - Intel Iris: 02:17:35 INFO - Chipset Model: Intel Iris 02:17:35 INFO - Type: GPU 02:17:35 INFO - Bus: Built-In 02:17:35 INFO - VRAM (Dynamic, Max): 1536 MB 02:17:35 INFO - Vendor: Intel (0x8086) 02:17:35 INFO - Device ID: 0x0a2e 02:17:35 INFO - Revision ID: 0x0009 02:17:35 INFO - Displays: 02:17:35 INFO - Display: 02:17:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:17:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:17:35 INFO - Display Serial Number: 1600x1200 60 02:17:35 INFO - Main Display: Yes 02:17:35 INFO - Mirror: Off 02:17:35 INFO - Online: Yes 02:17:35 INFO - Rotation: Supported 02:17:35 INFO - Return code: 0 02:17:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:17:35 INFO - Python 2.7.3 02:17:35 INFO - Return code: 0 02:17:35 INFO - grabbing minidump binary from tooltool 02:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:35 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 0x10543ad30>, '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 0x105361db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10542fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:17:35 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 02:17:35 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 02:17:35 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 02:17:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:17:35 INFO - Return code: 0 02:17:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:17:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:17:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:17:35 INFO - ENV: RUST_BACKTRACE is now 1 02:17:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:17:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:17:35 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/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 02:17:35 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/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --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 02:17:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFm2wByg9Z/Render', 02:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:35 INFO - 'HOME': '/Users/cltbld', 02:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:35 INFO - 'LOGNAME': 'cltbld', 02:17:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:17:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:35 INFO - 'MOZ_NO_REMOTE': '1', 02:17:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:17:35 INFO - 'NO_EM_RESTART': '1', 02:17:35 INFO - 'PAGER': '/bin/cat', 02:17:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:35 INFO - 'PWD': '/builds/slave/test', 02:17:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:35 INFO - 'RUST_BACKTRACE': '1', 02:17:35 INFO - 'SHELL': '/bin/bash', 02:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K7IdmJQjpp/Listeners', 02:17:35 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 02:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:35 INFO - 'USER': 'cltbld', 02:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:35 INFO - 'XPC_FLAGS': '0x0', 02:17:35 INFO - 'XPC_SERVICE_NAME': '0', 02:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:35 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/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 02:17:35 INFO - mozversion application_buildid: 20170419004020 02:17:35 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:17:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:17:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:17:35 INFO - mozversion application_name: Firefox 02:17:35 INFO - mozversion application_remotingname: firefox-dev 02:17:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:17:35 INFO - mozversion application_vendor: Mozilla 02:17:35 INFO - mozversion application_version: 54.0a2 02:17:35 INFO - mozversion platform_buildid: 20170419004020 02:17:35 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:17:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:17:35 INFO - mozversion platform_version: 54.0a2 02:17:35 INFO - using testdate: 1492593455 02:17:35 INFO - actual date: 1492593455 02:17:35 INFO - starting webserver on 'localhost:61183' 02:17:35 INFO - SUITE-START | Running 2 tests 02:17:35 INFO - TEST-START | dromaeo_css 02:17:35 INFO - operating with platform_type : mac_ 02:17:35 INFO - Initialising browser for dromaeo_css test... 02:17:35 INFO - TEST-INFO | started process 2868 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpnHcpC7/profile http://localhost:61183/getInfo.html) 02:17:38 INFO - PROCESS | 2868 | __metrics Screen width/height:1600/1200 02:17:38 INFO - PROCESS | 2868 | colorDepth:24 02:17:38 INFO - PROCESS | 2868 | Browser inner width/height: 1024/689 02:17:38 INFO - PROCESS | 2868 | __metrics 02:17:38 INFO - PROCESS | 2868 | 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" 02:17:38 INFO - PROCESS | 2868 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:17:39 INFO - TEST-INFO | 2868: exit 0 02:17:39 INFO - Browser initialized. 02:17:39 INFO - Running cycle 1/1 for dromaeo_css test... 02:17:39 INFO - TEST-INFO | started process 2872 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpnHcpC7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:51 INFO - PROCESS | 2872 | RSS: Main: 210567168 02:17:51 INFO - PROCESS | 2872 | 02:20:56 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:61183/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61183/tests/dromaeo/cssquery-ext.html) 02:20:58 INFO - PROCESS | 2872 | RSS: Main: 411598848 02:20:58 INFO - PROCESS | 2872 | 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | A coding exception was thrown and uncaught in a Task. 02:22:41 INFO - PROCESS | 2872 | 02:22:41 ERROR - PROCESS | 2872 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:22:41 INFO - PROCESS | 2872 | Full stack: 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | A coding exception was thrown and uncaught in a Task. 02:22:41 INFO - PROCESS | 2872 | 02:22:41 ERROR - PROCESS | 2872 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:22:41 INFO - PROCESS | 2872 | Full stack: 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | A coding exception was thrown and uncaught in a Task. 02:22:41 INFO - PROCESS | 2872 | 02:22:41 ERROR - PROCESS | 2872 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:22:41 INFO - PROCESS | 2872 | Full stack: 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | ************************* 02:22:41 INFO - PROCESS | 2872 | A coding exception was thrown and uncaught in a Task. 02:22:41 INFO - PROCESS | 2872 | 02:22:41 ERROR - PROCESS | 2872 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:22:41 INFO - PROCESS | 2872 | Full stack: 02:22:41 INFO - PROCESS | 2872 | ************************* 02:24:01 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:61183/tests/dromaeo/cssquery-ext.html (next: http://localhost:61183/tests/dromaeo/cssquery-jquery.html) 02:24:02 INFO - PROCESS | 2872 | RSS: Main: 353361920 02:24:02 INFO - PROCESS | 2872 | 02:27:04 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:61183/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61183/tests/dromaeo/cssquery-mootools.html) 02:27:05 INFO - PROCESS | 2872 | RSS: Main: 345395200 02:27:05 INFO - PROCESS | 2872 | 02:30:07 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:61183/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61183/tests/dromaeo/cssquery-prototype.html) 02:30:08 INFO - PROCESS | 2872 | RSS: Main: 317177856 02:30:08 INFO - PROCESS | 2872 | 02:33:12 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:61183/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61183/tests/dromaeo/cssquery-yui.html) 02:33:12 INFO - PROCESS | 2872 | RSS: Main: 316465152 02:33:12 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | Cycle 1(1): loaded http://localhost:61183/tests/dromaeo/cssquery-yui.html (next: http://localhost:61183/tests/dromaeo/cssquery-dojo.html) 02:36:16 INFO - PROCESS | 2872 | RSS: Main: 451428352 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | __start_tp_report 02:36:16 INFO - PROCESS | 2872 | _x_x_mozilla_page_load 02:36:16 INFO - PROCESS | 2872 | _x_x_mozilla_page_load_details 02:36:16 INFO - PROCESS | 2872 | |i|pagename|runs| 02:36:16 INFO - PROCESS | 2872 | |0;dojo.html;2909.6508418989724;3383.0696558446425;3429.3141371725656;3440.3635327464403;3452.7583069185166;5837.824865254039;5851.239338885945;5866.794662186824;5867.3252575953775;5869.325027621825;5250.212468129783;5519.834404967857;5932.2584676915385;5947.672877991708;5979.611325263855;5350.5452036576935;5370.7046112463795;5382.138857782756;5392.622516423852;5440.619156659035;5279.762410691528;5282.339707536558;5295.655782374152;5448.618596698233;5463.644863083907;5364.1149210380245;5391.353037635489;5395.568354531628;5431.4840090191365;5459.290292262001;5412.377576578704;5418.647787893774;5419.59353048519;5428.6742795432;5458.754356053987;5217.817376391846;5289.391719952217;5354.571634269249;5360.195970604402;5424.213489044106;5795.710214489259;5796.478316951452;5816.156657284712;5872.618279811842;5885.293764748215;6358.491320694333;6404.967975160094;6409.711562979677;6411.967940160316;6422.646754428504;5005.724685142316;6371.076193951897;6394.360563943615;6403.679816009228;6421.389967953037;5811.360750317504;5901.232839730807;5934.1692163097205;6084.361142080063;6091.208142941389;6438.034294855809;6450.935490645127;6472.741090356427;6474.838129046811;6474.870502589922;6376.266729326187;6377.808665740035;6378.425941665272;6396.744130234832;6411.1024456576115;6238.064290356483;6321.1150438938585;6381.936180638227;6404.743810247538;6410.487161027107;5389.757460914268;6322;6325.367463253638;6357.364263573606;6404.975203967343;6353.555251132469;6353.682315884187;6362.1093046973465;6370.840728981812;6376.872462550723;5049.5707864831165;5099.796008159632;5104.2088476286235;5121.334226550524;5125.538701516807;4493.820247190141;4502.752348620792;4547.795349209294;4551.249043907718;4580.358749775034;5970.34326224115;5984.581079324493;5995.670238136944;6022.247219097613;6036.698165091816;6323.7786677466065;6354.269259035178;6355.586886852432;6365.2361716594305;6367.554271200971;5698.17376480408;5801.88396232073;5942.15164036438;5952;6014.127951447016;5527.944720552823;5528.585356098309;5533.363663178706;5536.640118392292;5538.16927460876;6338.366163383625;6354.332795056545;6355.618662880242;6359.49124070064;6365.586236894587;6262.812115636538;6291.905621415774;6361.904571431525;6368.108464814837;6407.935920640734;6541.934580654323;6546.214454265328;6551.868962620816;6554.573952693059;6573;6348.555601107878;6379.489640828815;6381.936180638134;6386.201724784402;6392.5844820088405;6272.843178920563;6286.465650419766;6341.619502829845;6359.936400635935;6365.8090257290505;6116.457510225511;6175.6912154393;6187.319394866651;6194.5044396449075;6253.624782513064;6325.304216536086;6343.3339499351905;6352.841178970562;6359.8092057238355;6363.872722545616;6207.317195108444;6228.065790131516;6234.719437625227;6245.094461302995;6259.718312676032;5319.909418569261;6143.784967526115;6148.616561273857;6356.459700925288;6376.649284289409;6030.155778191144;6126.670355029888;6142.191841058572;6170.938290617216;6183.165272688309 02:36:16 INFO - PROCESS | 2872 | |1;ext.html;664.2958464028129;794.2732399854132;811.0794248527923;827.6896163938524;870.203763556346;37194.8841534754;37810.43284350733;37885.86342409729;37994.810025949904;38039.80980095103;37503.812480937624;37861.43207851885;39044.609553904455;39097.609023909754;39113.80443097788;7229.4939354245225;9074.364794464374;9214.170724634781;9226.077392260753;9226.67706630268;7337.33963943245;10629.255952083357;10902.61840835571;11017.28387654804;11068.612598559052;10890.836637450444;10904.291221070645;10908.781824363508;10909.399983000932;10913.23607347486;10849.67450976468;10853.891461085366;10855.294405863593;10861.294015888941;10875.293105948089;9103.954480227623;10705.304155229886;10763.19276054299;10768.3538987661;10780.0297973182;36872.63127368719;37068.88793336204;37106.443403348974;37133.62866371329;37233.81383093094;3822.3884178531302;3838.500994870677;3838.769673819552;3871.0709429736944;3899.3760998240145;25266.621000685005;26175.607365889337;27733.86133069342;27885.442291154064;28051.719482805114;3011.1719277198945;3088.83011434371;3177.618685757724;3244.0916543367894;3277.0987978305916;332104;332975;334451;335526;337777;51314;51326.74336628368;51416.22875656868;51542.22686659703;51560.74219628953;51084.23373649398;51583.226251606255;51591.96816063733;51645.22532162021;51694.22458663123;14200;14273.78589321161;14279;14314.642133946734;14336.283185840666;12490;12493.500259989498;12493.625191244277;12500.812487812691;12501.437435315433;11272.436378181059;11662.008729258108;11869.228500147463;11884;12017.25749766279;4435.600795928382;5456.672599644034;5457.6452530586175;5461.836144915659;5490.121580547093;1078.6386560502162;1123.1464087293716;1141.14414189358;1151.7178291318512;1191.3328536019856;25703.18291960841;25954.610680839804;25971.870140649175;26091.217263482125;26224;1113.3709454158375;1143.2568830260232;1261.703499677575;1265.6013355792895;1276.9744605107846;13168.078234523491;13343.466261349437;13470.52853150135;13488.595342139752;13527.864721352857;422036;423204;423740;424339;424649;53846.46153538493;53928.730356347965;54002;54095;54164.72917635387;1392.2273138408282;1448.7681970884607;1450.985490145106;1457.453454954371;1457.9416823326949;24520.386990325384;25277;25300.493990120463;25345;25387.746122539276;38072.61927380691;43023.354649679655;44825.77587112044;44953.77523112494;44977.55022449734;389694;390702;390727;392079.07920920424;393042;32662.673373266913;33103.66896331102;33184.668153318155;33333.49999749957;33384.666153338156;41500.79249603733;41618;42751.572484276;42907.35638965481;42943.141137176455;28829.99095031664;29042;29756.958506453037;29958.400831982803;30238.84880575583;28778.42443151167;29238.853805730836;29620.851895740383;29854.850725746233;29869.551956720232;33901.83049084739;34526.30947380988;34895.65104348924;35144.824275878455;35188.64811351854;29556.113316600535;29899.65451554642;30024.399512010074;30516.237094072825;30625.693743062286;23686;23804.58106691923;23990.52018959646;24137.91379387967;24547.38631534226 02:36:16 INFO - PROCESS | 2872 | |2;jquery.html;338.16979315779764;379.50285126484323;389.0080295247121;400.67345113732307;416.53764321603023;13204.207747535143;13720.51978180764;14006.509772157977;20744.377668669964;20852.37442876712;18172;22184.778152218474;22448.326550203477;22844.428889277733;22927.968241429135;6513.120728701612;6706.932930670704;6712.832179195522;6856.314368563154;6893.207281162655;4118.176364727052;6727.932720672779;6735;6739.258681545026;6773.729050837986;4348.412964249833;7246.094238220223;7568.091828980502;7686.961565192194;7705.075390953093;7422.554646721187;7692.115406728242;7706.152323244439;7790.610469476504;7802.921970780277;7245.420366370678;7449.813754656123;7648.42636802242;7664.923350766477;7675.347595454393;26932.865335673392;27113.593296100575;27230.863845680844;27260.863695681594;27558;3385.3906296866553;3402.3195360927666;3466.341395134916;3501.8074005929666;3507.421275489541;15804.683906321925;15920.840791592052;16346.34614615414;16371.93582417139;17196.656066878717;1693.5173475559407;1758.630687555615;2097.590969760897;2112.6830975353664;2117.364790562825;111492.32761508503;112145;132071.33964330214;135948.9607655886;139678.6032139686;21678.891605542187;21814;21885;22126.77873221247;22195.334139975505;36615.53537858509;37220.627793722255;37375.4393684095;37387.25225495475;37461.62538374636;10961.725956851142;11256.380899050631;11464.140189485655;11499.252548584314;11626.069914406828;7501.66242519088;9125.589348479334;9131.543422828832;9208.769780755534;9328.533573321307;10659.84010239847;10692.572297108185;10735.677929662123;10924.344539327665;10932.180086493545;3261.5433839262523;3579.0494180671594;3728.844058341923;4002.5797291284;4334.8266069357505;876.7501262140387;897.7620930453435;921.7511271956535;935.2050756856618;965.7199412170532;20034.799652003585;22208.222712204995;22828;22908.427289317566;23279.88360058189;947.1286416496839;950.9476978766096;964.7588102974256;976.3312131190157;980.2696990741942;11435.656930292105;11564.537418503165;11980.46087926028;11998.340091295062;12015.879841201651;731361;747435;754209;770724;792351;105002.47498762458;130009;134392;135433;136538;1256.704674401515;1264.2225031605496;1300.089937044073;1300.3043371796043;1304.778187708092;19705.802941970396;20605;20999.47501312418;21185.258515951868;21190.258340957993;41482.79258603688;55226.44773552374;56216.71891640679;58104.70947645235;58150.709246453494;77751.61124194342;77824.61087694525;78224.04439889049;86608;89339;7431.925680743339;11578.94210528942;11674.88325116772;11678.532858685558;12005.079187330335;12937.159084659466;12947.417366218731;12951.158174718614;13325.400356984153;13344.332783360987;9427.764305892408;11020.118390528578;11207.991280784769;11442.599509017144;11455.484503197542;10630.627928022483;10644.627438039632;10777.838332424863;10975.121990240921;11028.834567481656;11416.086713062768;11664.533418663159;11682;11757.88242117602;11884.881151188378;11508.424578771195;11624.94187529057;11741.823872642088;11893.881061189624;12013.279203247834;11119.332840029625;11138.665840024812;11204.237296541301;11463.828042579202;11718.355490448108 02:36:16 INFO - PROCESS | 2872 | |3;mootools.html;1535.3091109000952;1540.4287626620846;1564.906105633662;1570.0265835182188;1582.3116944129304;10540;11139.832902506469;11145.554177832888;11149.665510034692;11163.99524042837;9808.803823923517;10266.435346055963;11181.105511559075;11185.440727963589;11185.66443006709;2399.028393500635;2428.9878550607223;2443.4624382635793;2443.816713746467;2445.29086564896;2340.8010319122895;2423.45472268739;2426.793722533587;2428.6599876017285;2430;2341.075275266269;2407.181558270188;2414.8671823049726;2420.8305418620703;2425.3209101451534;2316.3745788637143;2386.486905315357;2394.341556072077;2398.3764221302413;2402.2072716003677;2369.4313364792324;2392.210570511727;2398.988005059981;2400.3759022654062;2406.1818981546357;10779.407132607703;11050.668479945614;11053;11058.170637202162;11063.391513466837;5725.341585717654;5878.50032747217;5889.85275368115;5898.351181370046;5907.970460147715;3588.551431071116;3607.729420293462;3686.4654625079215;3690.4833323334756;3711.981440092782;1191.7676053169635;1200.5617949448388;1231.7536492701406;1256.2462522486526;1256.4974010395824;12344.259344439362;12661.240325580402;12984.87015129846;13142.73714525704;13277.269750163738;4003.6396724294955;4265.7440553566885;4322.135572885435;4385.407969924083;4387.670924680661;11519.30884146954;11653.708657283467;11669.474873630706;11694;11701.531938722526;5797.130430435384;5818.21454103699;5823.388544202882;5825.854353641193;5829.970850145807;5104.1067813133295;5237.528622424;5294.391145018295;5321.707306098202;5393.002294575516;3839.520059992501;3874.670652994471;3941.9802900985887;3963.128111815396;3973.5033120859894;1024.1653052761976;1042.817506936283;1043.446973104256;1043.9404004934997;1044.3368461027303;895.9462432254086;896.5382827843666;961.6922584772806;963.0786876816929;987.0573602209872;6358.650274234869;6553.901691474632;6593.83379352275;6686.83282917931;6819.897701534481;967.2782447243917;977.0717818072859;990.1929927109383;999.3454287441738;1003.934744241622;3497.370473314777;4022.9798851006144;4233.301505251599;4257.808398622007;4376.474823021258;12973.351332433529;13616.18302901829;13628.523001694894;13630.795538066937;13704.24626645544;13330.333483325794;13432.261225632488;13660.795088073686;13669.316534173251;13698.589042328746;8642.783930401665;10133.240006999504;10240.539175737109;10250.692479225636;10265.589376425009;5797.507211887037;6747.966260168668;6926.34199751043;6986.47601429905;6991.475639326969;11995.160338776202;12081.214721043105;12246.20399674054;12280.508779649072;12436.689082772642;12386.876131238572;12460.688482788002;12503.687407814878;12611.0541709374;12627.368631568568;8306.958465207636;8376.916230837614;8462.365322600706;8875.423097498642;9262.95368523153;10265.640702575374;10357.016083472057;10444.268901176845;10456;10464.686059418229;8682.044975052613;8685.956570217108;8762.123787621187;8773.824523509367;8843.955780221058;8537.74386768398;8542.658293668184;8623.353248506262;8635.136486351315;8644.783880402789;7817.0619525657285;7921.366290696846;7960.800979975315;7975.960120199362;7981.960090199512;5798.391168927371;5820.912686309794;5862;6021.518278537794;6032.728527216374;5884.529237661062;5928.170056192051;6002.519798416029;6159.784407545714;6299.748010079546 02:36:16 INFO - PROCESS | 2872 | |4;prototype.html;346.0569946894712;355.24155927095677;355.5093970320958;364.5388583441946;375.5924821568598;4214.262504061789;4376.606105450509;4728.810847566093;4921.874609622889;4929.950700492994;3827.8468861245515;4289.292266775986;4865.343178670878;4895.339129217555;4897.3878265216845;4065.654419374342;4112.650424713903;4129.525104612978;4150.916981660365;4200.327947528396;4111.560953666223;4145.709800313979;4158.230727315441;4173.478315210584;4190.182914331706;3781.73527853049;4026.4966879140106;4113.526944401402;4146.813393397304;4236.237477254109;4019.1712770689264;4092.406601042862;4174.269502837006;4336.544662810423;4369.606735393798;3582.677559019675;3942.3495123304906;3942.743721658083;4127.422160897477;4138.379243113557;4206.569766279449;4754.524547545252;4777.522247775195;4973.4280557735965;5031.622628302855;3690;4313.4471590227495;4372.934405983913;4605.687379096941;5044.243363495469;4681.180793361148;5059.748411956681;5287.391949925769;5288.68267903927;5311;3631.2025547353965;4133.251387827091;4497.628223391897;4543.8182472701055;4624.866907607658;4766.856994290177;5142.503097893468;5419.238747407108;5638.295213098363;5808.796692115756;4675.392199014106;4718.952810471851;4748.477667456577;4808.687435316693;4845.370101886732;4048.676105911579;4668.85993420198;4746.572808447257;4871.224800779424;4999.275105109738;4192.987366064306;4424.601785839225;4935.975320123376;4946.925796113062;5159.458256883048;4483.820647174077;5045.899082018339;5260.763265653055;5272.755431877299;5292.417834038252;1368.4809861054223;1431.8759773577692;1626.910519921392;1652.991735041317;1663.0936139803594;1267.0787732057963;1341.932903354848;1341.9463221471033;1368.8768010879069;1382.0394825596113;4415.690901636919;5049.520295571915;5052.444231134572;5054.393472783289;5084.1188760158975;5052.9030452362595;5463.726813659301;5476.576090216572;5479.53423958968;5491.780328786804;4344.891377715519;4399.978000110043;4461.866144015685;4572.474165470947;5043.798248070109;2216.180013395054;2513.6229565565313;2637.3011152044724;2833.5891295762;2845.7336485264273;5243.082460569385;5305.973470132624;5339.706316152571;5628;5733.885322293614;5084.720340381241;5420.647657902303;5558.527525160407;5588.441155884379;5694.402087780723;5052.444231134572;5303.549198318186;5593.797333573301;5618.466245706733;5636.971815140898;4602.424696912886;4992.975035124801;5049.444561098349;5423.556041913334;5447.385255077665;4703.976480117714;5126.6923984561045;5189.642152444402;5334.493223143795;5346.492083252085;5100.240417056109;5342.630656190504;5521.337439507285;5565.554755619622;5689.54483641316;3980.6417422430964;4348.499922508952;4735.810567577259;4742.1227072991605;4749.548792864673;4602.964333025043;4648.697834640738;4891.241857512091;5223.772413482904;5483.067878460592;4473.881529617595;4655.371524844239;5038.168702164102;5218.452062533379;5236.162214045735;4785.253382515287;5294.338207724035;5310.734463276744;5324.840254792362;5336.985972665181;4718.41019872516;4753.524647535219;5109.011228431261;5160.626111988528;5220.869478263074;4832.782524786463;4986.850394488171;5071.095256652431;5079.755459912345;5397.757100930389;4419.734815911055;5082.796688132581;5152.27398821399;5182.844514664566;5193.246979188072 02:36:16 INFO - PROCESS | 2872 | |5;yui.html;732.198242923998;756.4213376766775;759.5328872743264;809.7327881799007;823.2714048067459;14534.34595443205;16409.917950410232;16439.342426302963;16474.258658360373;16509.504714858573;12124.393780310971;14774.409023639068;15027.474038408658;15157.848421515782;15756.921215393935;922.1792604581927;936.1293996583175;941.5198248893046;945.0927109974399;945.3382632157483;944.7826999790052;945.012461977232;945.0785484152965;946.3375637054089;949.4255975135047;957.7749228931195;984.6701355046068;984.9113579777819;987.1362557761959;987.8616993620899;971.5676523946846;985.0838719990406;986.9883369546272;989.2382865193832;991.5637119667323;728.3117454005967;731.4733391957782;731.5208538407298;731.7585196885016;732.2348146187247;15967.760483592756;15970.600734981603;15982.200889955571;16024.679506409922;16542.503724888273;14026.228557429336;14149.717005659932;14246.715065698732;14276.785848212285;14369.353379097965;11640.825387619192;11812.645620631394;11859.347735874524;11867.52529898803;12001.819972700416;3586.8923932282073;3622.329868974222;3672.8347224374966;3751.362268414376;3840.943740471365;108841;115865.42067289694;117610.82389176084;117720.41139794332;118878;10407.479626018669;10621.468926553796;10859.18556108295;10992.450377481095;11357.886421135849;20787.272445464336;22638.773612263994;23028.078876845073;23048;23073.077076916736;7475.775726728207;7506.662200201004;7523.849523009619;7567.508111972704;7587.506812057199;4255.638270747021;4347.456567929009;4415.161119387306;4428.535003824616;4485.842995495207;3487.2851065531504;3589.21037371783;3590.5152804371596;3592.497050412944;3594.263176048904;518.3922738247419;623.7910300049534;624.865653884417;625.5308518611042;635.0315768453054;689.9241083480813;691.2948792231896;693.2580653148194;711.2070041903344;711.4273010226714;9368.812623747486;9882.566573877259;10279.691609251735;10340.844887326695;10378.584856605803;652.9183852018498;670.2660586657602;688.9793306200822;691.872003679321;693.175121605288;4870.342503762017;4995.500449954975;5009.949900501021;5040.2691609716385;5044.4198917125;88832;105607;105679;106978.46510767397;107240.92759072465;50068.4979450617;76791;89714.55142724376;93533;98250;77146.4570708594;77257.61371193107;79168;79662;79762;10705.304155229886;10864.45677716127;10934.125269978224;11066.114710823276;11119.666410007712;49936.75031624819;49965.25121620726;50210.74894625504;50683.49316506788;52135;63559.682201588694;64374.35625643683;64945.67527162334;64970.675146623966;65122;9498.95250523743;10394.532246049097;10473.79052418932;10927.672169834917;12734.426950787421;13738.725225495635;14377.496787612383;14536.200508971686;14800.706169033167;15059.698806024036;13653.931730341683;13758;13895.861041389457;13937.600994406464;14121.43514259418;13687.520936767165;13828.446862125402;13838.861611383758;13929.164250145024;14163.787543186654;12715.618531444072;13080.80378794338;13278.203307801563;13537.661558461117;14440.989130760747;9651.517424128906;9722.368046076983;9751.80496390054;10030.749231269278;10187.337823041478;11118.666440006813;11200.38397888124;11360.54557817678;11470.311781292816;11598.07215422781 02:36:16 INFO - PROCESS | 2872 | __end_tp_report 02:36:16 INFO - PROCESS | 2872 | __start_cc_report 02:36:16 INFO - PROCESS | 2872 | _x_x_mozilla_cycle_collect,2313 02:36:16 INFO - PROCESS | 2872 | __end_cc_report 02:36:16 INFO - PROCESS | 2872 | __startTimestamp1492594576508__endTimestamp 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | ------- Summary: start ------- 02:36:16 INFO - PROCESS | 2872 | Number of tests: 6 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | [#0] dojo.html Cycles:165 Average:5884.29 Median:6143.78 stddev:670.07 (10.9%) stddev-sans-first:630.19 02:36:16 INFO - PROCESS | 2872 | Values: 2909.7 3383.1 3429.3 3440.4 3452.8 5837.8 5851.2 5866.8 5867.3 5869.3 5250.2 5519.8 5932.3 5947.7 5979.6 5350.5 5370.7 5382.1 5392.6 5440.6 5279.8 5282.3 5295.7 5448.6 5463.6 5364.1 5391.4 5395.6 5431.5 5459.3 5412.4 5418.6 5419.6 5428.7 5458.8 5217.8 5289.4 5354.6 5360.2 5424.2 5795.7 5796.5 5816.2 5872.6 5885.3 6358.5 6405.0 6409.7 6412.0 6422.6 5005.7 6371.1 6394.4 6403.7 6421.4 5811.4 5901.2 5934.2 6084.4 6091.2 6438.0 6450.9 6472.7 6474.8 6474.9 6376.3 6377.8 6378.4 6396.7 6411.1 6238.1 6321.1 6381.9 6404.7 6410.5 5389.8 6322.0 6325.4 6357.4 6405.0 6353.6 6353.7 6362.1 6370.8 6376.9 5049.6 5099.8 5104.2 5121.3 5125.5 4493.8 4502.8 4547.8 4551.2 4580.4 5970.3 5984.6 5995.7 6022.2 6036.7 6323.8 6354.3 6355.6 6365.2 6367.6 5698.2 5801.9 5942.2 5952.0 6014.1 5527.9 5528.6 5533.4 5536.6 5538.2 6338.4 6354.3 6355.6 6359.5 6365.6 6262.8 6291.9 6361.9 6368.1 6407.9 6541.9 6546.2 6551.9 6554.6 6573.0 6348.6 6379.5 6381.9 6386.2 6392.6 6272.8 6286.5 6341.6 6359.9 6365.8 6116.5 6175.7 6187.3 6194.5 6253.6 6325.3 6343.3 6352.8 6359.8 6363.9 6207.3 6228.1 6234.7 6245.1 6259.7 5319.9 6143.8 6148.6 6356.5 6376.6 6030.2 6126.7 6142.2 6170.9 6183.2 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | [#1] ext.html Cycles:180 Average:52346.21 Median:3210.86 stddev:101773.48 (3169.7%) stddev-sans-first:101985.00 02:36:16 INFO - PROCESS | 2872 | Values: 664.3 794.3 811.1 827.7 870.2 37194.9 37810.4 37885.9 37994.8 38039.8 37503.8 37861.4 39044.6 39097.6 39113.8 7229.5 9074.4 9214.2 9226.1 9226.7 7337.3 10629.3 10902.6 11017.3 11068.6 10890.8 10904.3 10908.8 10909.4 10913.2 10849.7 10853.9 10855.3 10861.3 10875.3 9104.0 10705.3 10763.2 10768.4 10780.0 36872.6 37068.9 37106.4 37133.6 37233.8 3822.4 3838.5 3838.8 3871.1 3899.4 25266.6 26175.6 27733.9 27885.4 28051.7 3011.2 3088.8 3177.6 3244.1 3277.1 332104.0 332975.0 334451.0 335526.0 337777.0 51314.0 51326.7 51416.2 51542.2 51560.7 51084.2 51583.2 51592.0 51645.2 51694.2 14200.0 14273.8 14279.0 14314.6 14336.3 12490.0 12493.5 12493.6 12500.8 12501.4 11272.4 11662.0 11869.2 11884.0 12017.3 4435.6 5456.7 5457.6 5461.8 5490.1 1078.6 1123.1 1141.1 1151.7 1191.3 25703.2 25954.6 25971.9 26091.2 26224.0 1113.4 1143.3 1261.7 1265.6 1277.0 13168.1 13343.5 13470.5 13488.6 13527.9 422036.0 423204.0 423740.0 424339.0 424649.0 53846.5 53928.7 54002.0 54095.0 54164.7 1392.2 1448.8 1451.0 1457.5 1457.9 24520.4 25277.0 25300.5 25345.0 25387.7 38072.6 43023.4 44825.8 44953.8 44977.6 389694.0 390702.0 390727.0 392079.1 393042.0 32662.7 33103.7 33184.7 33333.5 33384.7 41500.8 41618.0 42751.6 42907.4 42943.1 28830.0 29042.0 29757.0 29958.4 30238.8 28778.4 29238.9 29620.9 29854.9 29869.6 33901.8 34526.3 34895.7 35144.8 35188.6 29556.1 29899.7 30024.4 30516.2 30625.7 23686.0 23804.6 23990.5 24137.9 24547.4 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | [#2] jquery.html Cycles:180 Average:41793.19 Median:22201.78 stddev:125355.97 (564.6%) stddev-sans-first:125668.98 02:36:16 INFO - PROCESS | 2872 | Values: 338.2 379.5 389.0 400.7 416.5 13204.2 13720.5 14006.5 20744.4 20852.4 18172.0 22184.8 22448.3 22844.4 22928.0 6513.1 6706.9 6712.8 6856.3 6893.2 4118.2 6727.9 6735.0 6739.3 6773.7 4348.4 7246.1 7568.1 7687.0 7705.1 7422.6 7692.1 7706.2 7790.6 7802.9 7245.4 7449.8 7648.4 7664.9 7675.3 26932.9 27113.6 27230.9 27260.9 27558.0 3385.4 3402.3 3466.3 3501.8 3507.4 15804.7 15920.8 16346.3 16371.9 17196.7 1693.5 1758.6 2097.6 2112.7 2117.4 111492.3 112145.0 132071.3 135949.0 139678.6 21678.9 21814.0 21885.0 22126.8 22195.3 36615.5 37220.6 37375.4 37387.3 37461.6 10961.7 11256.4 11464.1 11499.3 11626.1 7501.7 9125.6 9131.5 9208.8 9328.5 10659.8 10692.6 10735.7 10924.3 10932.2 3261.5 3579.0 3728.8 4002.6 4334.8 876.8 897.8 921.8 935.2 965.7 20034.8 22208.2 22828.0 22908.4 23279.9 947.1 950.9 964.8 976.3 980.3 11435.7 11564.5 11980.5 11998.3 12015.9 731361.0 747435.0 754209.0 770724.0 792351.0 105002.5 130009.0 134392.0 135433.0 136538.0 1256.7 1264.2 1300.1 1300.3 1304.8 19705.8 20605.0 20999.5 21185.3 21190.3 41482.8 55226.4 56216.7 58104.7 58150.7 77751.6 77824.6 78224.0 86608.0 89339.0 7431.9 11578.9 11674.9 11678.5 12005.1 12937.2 12947.4 12951.2 13325.4 13344.3 9427.8 11020.1 11208.0 11442.6 11455.5 10630.6 10644.6 10777.8 10975.1 11028.8 11416.1 11664.5 11682.0 11757.9 11884.9 11508.4 11624.9 11741.8 11893.9 12013.3 11119.3 11138.7 11204.2 11463.8 11718.4 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | [#3] mootools.html Cycles:180 Average:6644.95 Median:2436.73 stddev:4070.63 (167.1%) stddev-sans-first:4063.94 02:36:16 INFO - PROCESS | 2872 | Values: 1535.3 1540.4 1564.9 1570.0 1582.3 10540.0 11139.8 11145.6 11149.7 11164.0 9808.8 10266.4 11181.1 11185.4 11185.7 2399.0 2429.0 2443.5 2443.8 2445.3 2340.8 2423.5 2426.8 2428.7 2430.0 2341.1 2407.2 2414.9 2420.8 2425.3 2316.4 2386.5 2394.3 2398.4 2402.2 2369.4 2392.2 2399.0 2400.4 2406.2 10779.4 11050.7 11053.0 11058.2 11063.4 5725.3 5878.5 5889.9 5898.4 5908.0 3588.6 3607.7 3686.5 3690.5 3712.0 1191.8 1200.6 1231.8 1256.2 1256.5 12344.3 12661.2 12984.9 13142.7 13277.3 4003.6 4265.7 4322.1 4385.4 4387.7 11519.3 11653.7 11669.5 11694.0 11701.5 5797.1 5818.2 5823.4 5825.9 5830.0 5104.1 5237.5 5294.4 5321.7 5393.0 3839.5 3874.7 3942.0 3963.1 3973.5 1024.2 1042.8 1043.4 1043.9 1044.3 895.9 896.5 961.7 963.1 987.1 6358.7 6553.9 6593.8 6686.8 6819.9 967.3 977.1 990.2 999.3 1003.9 3497.4 4023.0 4233.3 4257.8 4376.5 12973.4 13616.2 13628.5 13630.8 13704.2 13330.3 13432.3 13660.8 13669.3 13698.6 8642.8 10133.2 10240.5 10250.7 10265.6 5797.5 6748.0 6926.3 6986.5 6991.5 11995.2 12081.2 12246.2 12280.5 12436.7 12386.9 12460.7 12503.7 12611.1 12627.4 8307.0 8376.9 8462.4 8875.4 9263.0 10265.6 10357.0 10444.3 10456.0 10464.7 8682.0 8686.0 8762.1 8773.8 8844.0 8537.7 8542.7 8623.4 8635.1 8644.8 7817.1 7921.4 7960.8 7976.0 7982.0 5798.4 5820.9 5862.0 6021.5 6032.7 5884.5 5928.2 6002.5 6159.8 6299.7 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | [#4] prototype.html Cycles:180 Average:4453.22 Median:4772.19 stddev:1198.25 (25.1%) stddev-sans-first:1161.28 02:36:16 INFO - PROCESS | 2872 | Values: 346.1 355.2 355.5 364.5 375.6 4214.3 4376.6 4728.8 4921.9 4930.0 3827.8 4289.3 4865.3 4895.3 4897.4 4065.7 4112.7 4129.5 4150.9 4200.3 4111.6 4145.7 4158.2 4173.5 4190.2 3781.7 4026.5 4113.5 4146.8 4236.2 4019.2 4092.4 4174.3 4336.5 4369.6 3582.7 3942.3 3942.7 4127.4 4138.4 4206.6 4754.5 4777.5 4973.4 5031.6 3690.0 4313.4 4372.9 4605.7 5044.2 4681.2 5059.7 5287.4 5288.7 5311.0 3631.2 4133.3 4497.6 4543.8 4624.9 4766.9 5142.5 5419.2 5638.3 5808.8 4675.4 4719.0 4748.5 4808.7 4845.4 4048.7 4668.9 4746.6 4871.2 4999.3 4193.0 4424.6 4936.0 4946.9 5159.5 4483.8 5045.9 5260.8 5272.8 5292.4 1368.5 1431.9 1626.9 1653.0 1663.1 1267.1 1341.9 1341.9 1368.9 1382.0 4415.7 5049.5 5052.4 5054.4 5084.1 5052.9 5463.7 5476.6 5479.5 5491.8 4344.9 4400.0 4461.9 4572.5 5043.8 2216.2 2513.6 2637.3 2833.6 2845.7 5243.1 5306.0 5339.7 5628.0 5733.9 5084.7 5420.6 5558.5 5588.4 5694.4 5052.4 5303.5 5593.8 5618.5 5637.0 4602.4 4993.0 5049.4 5423.6 5447.4 4704.0 5126.7 5189.6 5334.5 5346.5 5100.2 5342.6 5521.3 5565.6 5689.5 3980.6 4348.5 4735.8 4742.1 4749.5 4603.0 4648.7 4891.2 5223.8 5483.1 4473.9 4655.4 5038.2 5218.5 5236.2 4785.3 5294.3 5310.7 5324.8 5337.0 4718.4 4753.5 5109.0 5160.6 5220.9 4832.8 4986.9 5071.1 5079.8 5397.8 4419.7 5082.8 5152.3 5182.8 5193.2 02:36:16 INFO - PROCESS | 2872 | 02:36:16 INFO - PROCESS | 2872 | [#5] yui.html Cycles:180 Average:20568.34 Median:3712.10 stddev:29839.74 (803.9%) stddev-sans-first:29886.28 02:36:16 INFO - PROCESS | 2872 | Values: 732.2 756.4 759.5 809.7 823.3 14534.3 16409.9 16439.3 16474.3 16509.5 12124.4 14774.4 15027.5 15157.8 15756.9 922.2 936.1 941.5 945.1 945.3 944.8 945.0 945.1 946.3 949.4 957.8 984.7 984.9 987.1 987.9 971.6 985.1 987.0 989.2 991.6 728.3 731.5 731.5 731.8 732.2 15967.8 15970.6 15982.2 16024.7 16542.5 14026.2 14149.7 14246.7 14276.8 14369.4 11640.8 11812.6 11859.3 11867.5 12001.8 3586.9 3622.3 3672.8 3751.4 3840.9 108841.0 115865.4 117610.8 117720.4 118878.0 10407.5 10621.5 10859.2 10992.5 11357.9 20787.3 22638.8 23028.1 23048.0 23073.1 7475.8 7506.7 7523.8 7567.5 7587.5 4255.6 4347.5 4415.2 4428.5 4485.8 3487.3 3589.2 3590.5 3592.5 3594.3 518.4 623.8 624.9 625.5 635.0 689.9 691.3 693.3 711.2 711.4 9368.8 9882.6 10279.7 10340.8 10378.6 652.9 670.3 689.0 691.9 693.2 4870.3 4995.5 5009.9 5040.3 5044.4 88832.0 105607.0 105679.0 106978.5 107240.9 50068.5 76791.0 89714.6 93533.0 98250.0 77146.5 77257.6 79168.0 79662.0 79762.0 10705.3 10864.5 10934.1 11066.1 11119.7 49936.8 49965.3 50210.7 50683.5 52135.0 63559.7 64374.4 64945.7 64970.7 65122.0 9499.0 10394.5 10473.8 10927.7 12734.4 13738.7 14377.5 14536.2 14800.7 15059.7 13653.9 13758.0 13895.9 13937.6 14121.4 13687.5 13828.4 13838.9 13929.2 14163.8 12715.6 13080.8 13278.2 13537.7 14441.0 9651.5 9722.4 9751.8 10030.7 10187.3 11118.7 11200.4 11360.5 11470.3 11598.1 02:36:16 INFO - PROCESS | 2872 | -------- Summary: end -------- 02:36:16 INFO - PROCESS | 2872 | 02:36:17 INFO - TEST-INFO | 2872: exit 0 02:36:17 INFO - TEST-OK | dromaeo_css | took 1121438ms 02:36:17 INFO - TEST-START | kraken 02:36:17 INFO - operating with platform_type : mac_ 02:36:17 INFO - Initialising browser for kraken test... 02:36:17 INFO - TEST-INFO | started process 2875 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpC62Lzi/profile http://localhost:61183/getInfo.html) 02:36:19 INFO - PROCESS | 2875 | __metrics Screen width/height:1600/1200 02:36:19 INFO - PROCESS | 2875 | colorDepth:24 02:36:19 INFO - PROCESS | 2875 | Browser inner width/height: 1024/689 02:36:19 INFO - PROCESS | 2875 | __metrics 02:36:19 INFO - PROCESS | 2875 | 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" 02:36:19 INFO - PROCESS | 2875 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:36:20 INFO - TEST-INFO | 2875: exit 0 02:36:20 INFO - Browser initialized. 02:36:20 INFO - Running cycle 1/1 for kraken test... 02:36:20 INFO - TEST-INFO | started process 2877 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpC62Lzi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:36:32 INFO - PROCESS | 2877 | RSS: Main: 215117824 02:36:32 INFO - PROCESS | 2877 | 02:36:35 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61183/tests/kraken/driver.html?testName=audio-beat-detection) 02:36:36 INFO - PROCESS | 2877 | RSS: Main: 267612160 02:36:36 INFO - PROCESS | 2877 | 02:36:39 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61183/tests/kraken/driver.html?testName=audio-dft) 02:36:39 INFO - PROCESS | 2877 | RSS: Main: 279199744 02:36:39 INFO - PROCESS | 2877 | 02:36:43 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61183/tests/kraken/driver.html?testName=audio-fft) 02:36:43 INFO - PROCESS | 2877 | RSS: Main: 376176640 02:36:43 INFO - PROCESS | 2877 | 02:36:45 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61183/tests/kraken/driver.html?testName=audio-oscillator) 02:36:46 INFO - PROCESS | 2877 | RSS: Main: 277929984 02:36:46 INFO - PROCESS | 2877 | 02:36:48 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61183/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:36:48 INFO - PROCESS | 2877 | RSS: Main: 279576576 02:36:48 INFO - PROCESS | 2877 | 02:36:51 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61183/tests/kraken/driver.html?testName=imaging-darkroom) 02:36:52 INFO - PROCESS | 2877 | RSS: Main: 320835584 02:36:52 INFO - PROCESS | 2877 | 02:36:55 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61183/tests/kraken/driver.html?testName=imaging-desaturate) 02:36:56 INFO - PROCESS | 2877 | RSS: Main: 322056192 02:36:56 INFO - PROCESS | 2877 | 02:36:59 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61183/tests/kraken/driver.html?testName=json-parse-financial) 02:36:59 INFO - PROCESS | 2877 | RSS: Main: 317526016 02:36:59 INFO - PROCESS | 2877 | 02:37:01 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61183/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:37:02 INFO - PROCESS | 2877 | RSS: Main: 295841792 02:37:02 INFO - PROCESS | 2877 | 02:37:03 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:37:04 INFO - PROCESS | 2877 | RSS: Main: 297918464 02:37:04 INFO - PROCESS | 2877 | 02:37:06 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:37:07 INFO - PROCESS | 2877 | RSS: Main: 304242688 02:37:07 INFO - PROCESS | 2877 | 02:37:10 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:37:11 INFO - PROCESS | 2877 | RSS: Main: 319053824 02:37:11 INFO - PROCESS | 2877 | 02:37:13 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:37:14 INFO - PROCESS | 2877 | RSS: Main: 312455168 02:37:14 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | Cycle 1(1): loaded http://localhost:61183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61183/tests/kraken/driver.html?testName=ai-astar) 02:37:16 INFO - PROCESS | 2877 | RSS: Main: 356753408 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | __start_tp_report 02:37:16 INFO - PROCESS | 2877 | _x_x_mozilla_page_load 02:37:16 INFO - PROCESS | 2877 | _x_x_mozilla_page_load_details 02:37:16 INFO - PROCESS | 2877 | |i|pagename|runs| 02:37:16 INFO - PROCESS | 2877 | |0;ai-astar;162;170;143;116;115;115;115;115;116;115 02:37:16 INFO - PROCESS | 2877 | |1;audio-beat-detection;141;109;132;143;112;110;108;107;133;144 02:37:16 INFO - PROCESS | 2877 | |2;audio-dft;182;182;183;184;185;185;187;184;185;183 02:37:16 INFO - PROCESS | 2877 | |3;audio-fft;80;60;60;92;62;60;62;61;76;62 02:37:16 INFO - PROCESS | 2877 | |4;audio-oscillator;87;71;87;69;69;68;82;69;69;84 02:37:16 INFO - PROCESS | 2877 | |5;imaging-gaussian-blur;90;95;94;90;91;91;91;95;91;91 02:37:16 INFO - PROCESS | 2877 | |6;imaging-darkroom;97;97;97;97;104;144;96;96;97;97 02:37:16 INFO - PROCESS | 2877 | |7;imaging-desaturate;83;84;86;83;84;83;83;85;81;83 02:37:16 INFO - PROCESS | 2877 | |8;json-parse-financial;63;61;61;63;64;65;64;63;64;63 02:37:16 INFO - PROCESS | 2877 | |9;json-stringify-tinderbox;57;58;63;64;61;58;58;59;58;59 02:37:16 INFO - PROCESS | 2877 | |10;stanford-crypto-aes;83;83;83;86;86;80;99;86;84;90 02:37:16 INFO - PROCESS | 2877 | |11;stanford-crypto-ccm;142;146;143;135;139;142;156;157;196;173 02:37:16 INFO - PROCESS | 2877 | |12;stanford-crypto-pbkdf2;165;159;165;165;165;164;165;164;164;164 02:37:16 INFO - PROCESS | 2877 | |13;stanford-crypto-sha256-iterative;65;67;76;79;61;56;76;78;76;78 02:37:16 INFO - PROCESS | 2877 | __end_tp_report 02:37:16 INFO - PROCESS | 2877 | __start_cc_report 02:37:16 INFO - PROCESS | 2877 | _x_x_mozilla_cycle_collect,2158 02:37:16 INFO - PROCESS | 2877 | __end_cc_report 02:37:16 INFO - PROCESS | 2877 | __startTimestamp1492594636633__endTimestamp 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | ------- Summary: start ------- 02:37:16 INFO - PROCESS | 2877 | Number of tests: 14 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#0] ai-astar Cycles:10 Average:128.20 Median:116.00 stddev:21.80 (18.8%) stddev-sans-first:19.39 02:37:16 INFO - PROCESS | 2877 | Values: 162.0 170.0 143.0 116.0 115.0 115.0 115.0 115.0 116.0 115.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:132.50 stddev:16.00 (12.1%) stddev-sans-first:15.73 02:37:16 INFO - PROCESS | 2877 | Values: 141.0 109.0 132.0 143.0 112.0 110.0 108.0 107.0 133.0 144.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.56 (0.8%) stddev-sans-first:1.48 02:37:16 INFO - PROCESS | 2877 | Values: 182.0 182.0 183.0 184.0 185.0 185.0 187.0 184.0 185.0 183.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.00 stddev:11.21 (18.1%) stddev-sans-first:10.94 02:37:16 INFO - PROCESS | 2877 | Values: 80.0 60.0 60.0 92.0 62.0 60.0 62.0 61.0 76.0 62.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.50 stddev:8.33 (10.9%) stddev-sans-first:7.73 02:37:16 INFO - PROCESS | 2877 | Values: 87.0 71.0 87.0 69.0 69.0 68.0 82.0 69.0 69.0 84.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#5] imaging-gaussian-blur Cycles:10 Average:91.90 Median:91.00 stddev:1.97 (2.2%) stddev-sans-first:1.96 02:37:16 INFO - PROCESS | 2877 | Values: 90.0 95.0 94.0 90.0 91.0 91.0 91.0 95.0 91.0 91.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:97.00 stddev:14.87 (15.3%) stddev-sans-first:15.65 02:37:16 INFO - PROCESS | 2877 | Values: 97.0 97.0 97.0 97.0 104.0 144.0 96.0 96.0 97.0 97.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#7] imaging-desaturate Cycles:10 Average:83.50 Median:83.50 stddev:1.35 (1.6%) stddev-sans-first:1.42 02:37:16 INFO - PROCESS | 2877 | Values: 83.0 84.0 86.0 83.0 84.0 83.0 83.0 85.0 81.0 83.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.50 stddev:1.29 (2.0%) stddev-sans-first:1.36 02:37:16 INFO - PROCESS | 2877 | Values: 63.0 61.0 61.0 63.0 64.0 65.0 64.0 63.0 64.0 63.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:2.37 (4.0%) stddev-sans-first:2.33 02:37:16 INFO - PROCESS | 2877 | Values: 57.0 58.0 63.0 64.0 61.0 58.0 58.0 59.0 58.0 59.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.00 stddev:5.29 (6.2%) stddev-sans-first:5.50 02:37:16 INFO - PROCESS | 2877 | Values: 83.0 83.0 83.0 86.0 86.0 80.0 99.0 86.0 84.0 90.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#11] stanford-crypto-ccm Cycles:10 Average:152.90 Median:151.00 stddev:18.81 (12.5%) stddev-sans-first:19.53 02:37:16 INFO - PROCESS | 2877 | Values: 142.0 146.0 143.0 135.0 139.0 142.0 156.0 157.0 196.0 173.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.00 Median:165.00 stddev:1.83 (1.1%) stddev-sans-first:1.90 02:37:16 INFO - PROCESS | 2877 | Values: 165.0 159.0 165.0 165.0 165.0 164.0 165.0 164.0 164.0 164.0 02:37:16 INFO - PROCESS | 2877 | 02:37:16 INFO - PROCESS | 2877 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:76.00 stddev:8.26 (10.9%) stddev-sans-first:8.45 02:37:16 INFO - PROCESS | 2877 | Values: 65.0 67.0 76.0 79.0 61.0 56.0 76.0 78.0 76.0 78.0 02:37:16 INFO - PROCESS | 2877 | -------- Summary: end -------- 02:37:16 INFO - PROCESS | 2877 | 02:37:17 INFO - TEST-INFO | 2877: exit 0 02:37:17 INFO - TEST-OK | kraken | took 60302ms 02:37:17 INFO - SUITE-END | took 1181s 02:37:17 INFO - Completed test suite (00:19:41) 02:37:17 INFO - javascript benchmark 02:37:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7642.087556823629, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2909.6508418989724, 3383.0696558446425, 3429.3141371725656, 3440.3635327464403, 3452.7583069185166, 5837.824865254039, 5851.239338885945, 5866.794662186824, 5867.3252575953775, 5869.325027621825, 5250.212468129783, 5519.834404967857, 5932.2584676915385, 5947.672877991708, 5979.611325263855, 5350.5452036576935, 5370.7046112463795, 5382.138857782756, 5392.622516423852, 5440.619156659035, 5279.762410691528, 5282.339707536558, 5295.655782374152, 5448.618596698233, 5463.644863083907, 5364.1149210380245, 5391.353037635489, 5395.568354531628, 5431.4840090191365, 5459.290292262001, 5412.377576578704, 5418.647787893774, 5419.59353048519, 5428.6742795432, 5458.754356053987, 5217.817376391846, 5289.391719952217, 5354.571634269249, 5360.195970604402, 5424.213489044106, 5795.710214489259, 5796.478316951452, 5816.156657284712, 5872.618279811842, 5885.293764748215, 6358.491320694333, 6404.967975160094, 6409.711562979677, 6411.967940160316, 6422.646754428504, 5005.724685142316, 6371.076193951897, 6394.360563943615, 6403.679816009228, 6421.389967953037, 5811.360750317504, 5901.232839730807, 5934.1692163097205, 6084.361142080063, 6091.208142941389, 6438.034294855809, 6450.935490645127, 6472.741090356427, 6474.838129046811, 6474.870502589922, 6376.266729326187, 6377.808665740035, 6378.425941665272, 6396.744130234832, 6411.1024456576115, 6238.064290356483, 6321.1150438938585, 6381.936180638227, 6404.743810247538, 6410.487161027107, 5389.757460914268, 6322.0, 6325.367463253638, 6357.364263573606, 6404.975203967343, 6353.555251132469, 6353.682315884187, 6362.1093046973465, 6370.840728981812, 6376.872462550723, 5049.5707864831165, 5099.796008159632, 5104.2088476286235, 5121.334226550524, 5125.538701516807, 4493.820247190141, 4502.752348620792, 4547.795349209294, 4551.249043907718, 4580.358749775034, 5970.34326224115, 5984.581079324493, 5995.670238136944, 6022.247219097613, 6036.698165091816, 6323.7786677466065, 6354.269259035178, 6355.586886852432, 6365.2361716594305, 6367.554271200971, 5698.17376480408, 5801.88396232073, 5942.15164036438, 5952.0, 6014.127951447016, 5527.944720552823, 5528.585356098309, 5533.363663178706, 5536.640118392292, 5538.16927460876, 6338.366163383625, 6354.332795056545, 6355.618662880242, 6359.49124070064, 6365.586236894587, 6262.812115636538, 6291.905621415774, 6361.904571431525, 6368.108464814837, 6407.935920640734, 6541.934580654323, 6546.214454265328, 6551.868962620816, 6554.573952693059, 6573.0, 6348.555601107878, 6379.489640828815, 6381.936180638134, 6386.201724784402, 6392.5844820088405, 6272.843178920563, 6286.465650419766, 6341.619502829845, 6359.936400635935, 6365.8090257290505, 6116.457510225511, 6175.6912154393, 6187.319394866651, 6194.5044396449075, 6253.624782513064, 6325.304216536086, 6343.3339499351905, 6352.841178970562, 6359.8092057238355, 6363.872722545616, 6207.317195108444, 6228.065790131516, 6234.719437625227, 6245.094461302995, 6259.718312676032, 5319.909418569261, 6143.784967526115, 6148.616561273857, 6356.459700925288, 6376.649284289409, 6030.155778191144, 6126.670355029888, 6142.191841058572, 6170.938290617216, 6183.165272688309], "value": 5840.438088169039, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.2958464028129, 794.2732399854132, 811.0794248527923, 827.6896163938524, 870.203763556346, 37194.8841534754, 37810.43284350733, 37885.86342409729, 37994.810025949904, 38039.80980095103, 37503.812480937624, 37861.43207851885, 39044.609553904455, 39097.609023909754, 39113.80443097788, 7229.4939354245225, 9074.364794464374, 9214.170724634781, 9226.077392260753, 9226.67706630268, 7337.33963943245, 10629.255952083357, 10902.61840835571, 11017.28387654804, 11068.612598559052, 10890.836637450444, 10904.291221070645, 10908.781824363508, 10909.399983000932, 10913.23607347486, 10849.67450976468, 10853.891461085366, 10855.294405863593, 10861.294015888941, 10875.293105948089, 9103.954480227623, 10705.304155229886, 10763.19276054299, 10768.3538987661, 10780.0297973182, 36872.63127368719, 37068.88793336204, 37106.443403348974, 37133.62866371329, 37233.81383093094, 3822.3884178531302, 3838.500994870677, 3838.769673819552, 3871.0709429736944, 3899.3760998240145, 25266.621000685005, 26175.607365889337, 27733.86133069342, 27885.442291154064, 28051.719482805114, 3011.1719277198945, 3088.83011434371, 3177.618685757724, 3244.0916543367894, 3277.0987978305916, 332104.0, 332975.0, 334451.0, 335526.0, 337777.0, 51314.0, 51326.74336628368, 51416.22875656868, 51542.22686659703, 51560.74219628953, 51084.23373649398, 51583.226251606255, 51591.96816063733, 51645.22532162021, 51694.22458663123, 14200.0, 14273.78589321161, 14279.0, 14314.642133946734, 14336.283185840666, 12490.0, 12493.500259989498, 12493.625191244277, 12500.812487812691, 12501.437435315433, 11272.436378181059, 11662.008729258108, 11869.228500147463, 11884.0, 12017.25749766279, 4435.600795928382, 5456.672599644034, 5457.6452530586175, 5461.836144915659, 5490.121580547093, 1078.6386560502162, 1123.1464087293716, 1141.14414189358, 1151.7178291318512, 1191.3328536019856, 25703.18291960841, 25954.610680839804, 25971.870140649175, 26091.217263482125, 26224.0, 1113.3709454158375, 1143.2568830260232, 1261.703499677575, 1265.6013355792895, 1276.9744605107846, 13168.078234523491, 13343.466261349437, 13470.52853150135, 13488.595342139752, 13527.864721352857, 422036.0, 423204.0, 423740.0, 424339.0, 424649.0, 53846.46153538493, 53928.730356347965, 54002.0, 54095.0, 54164.72917635387, 1392.2273138408282, 1448.7681970884607, 1450.985490145106, 1457.453454954371, 1457.9416823326949, 24520.386990325384, 25277.0, 25300.493990120463, 25345.0, 25387.746122539276, 38072.61927380691, 43023.354649679655, 44825.77587112044, 44953.77523112494, 44977.55022449734, 389694.0, 390702.0, 390727.0, 392079.07920920424, 393042.0, 32662.673373266913, 33103.66896331102, 33184.668153318155, 33333.49999749957, 33384.666153338156, 41500.79249603733, 41618.0, 42751.572484276, 42907.35638965481, 42943.141137176455, 28829.99095031664, 29042.0, 29756.958506453037, 29958.400831982803, 30238.84880575583, 28778.42443151167, 29238.853805730836, 29620.851895740383, 29854.850725746233, 29869.551956720232, 33901.83049084739, 34526.30947380988, 34895.65104348924, 35144.824275878455, 35188.64811351854, 29556.113316600535, 29899.65451554642, 30024.399512010074, 30516.237094072825, 30625.693743062286, 23686.0, 23804.58106691923, 23990.52018959646, 24137.91379387967, 24547.38631534226], "value": 18360.924674884976, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.16979315779764, 379.50285126484323, 389.0080295247121, 400.67345113732307, 416.53764321603023, 13204.207747535143, 13720.51978180764, 14006.509772157977, 20744.377668669964, 20852.37442876712, 18172.0, 22184.778152218474, 22448.326550203477, 22844.428889277733, 22927.968241429135, 6513.120728701612, 6706.932930670704, 6712.832179195522, 6856.314368563154, 6893.207281162655, 4118.176364727052, 6727.932720672779, 6735.0, 6739.258681545026, 6773.729050837986, 4348.412964249833, 7246.094238220223, 7568.091828980502, 7686.961565192194, 7705.075390953093, 7422.554646721187, 7692.115406728242, 7706.152323244439, 7790.610469476504, 7802.921970780277, 7245.420366370678, 7449.813754656123, 7648.42636802242, 7664.923350766477, 7675.347595454393, 26932.865335673392, 27113.593296100575, 27230.863845680844, 27260.863695681594, 27558.0, 3385.3906296866553, 3402.3195360927666, 3466.341395134916, 3501.8074005929666, 3507.421275489541, 15804.683906321925, 15920.840791592052, 16346.34614615414, 16371.93582417139, 17196.656066878717, 1693.5173475559407, 1758.630687555615, 2097.590969760897, 2112.6830975353664, 2117.364790562825, 111492.32761508503, 112145.0, 132071.33964330214, 135948.9607655886, 139678.6032139686, 21678.891605542187, 21814.0, 21885.0, 22126.77873221247, 22195.334139975505, 36615.53537858509, 37220.627793722255, 37375.4393684095, 37387.25225495475, 37461.62538374636, 10961.725956851142, 11256.380899050631, 11464.140189485655, 11499.252548584314, 11626.069914406828, 7501.66242519088, 9125.589348479334, 9131.543422828832, 9208.769780755534, 9328.533573321307, 10659.84010239847, 10692.572297108185, 10735.677929662123, 10924.344539327665, 10932.180086493545, 3261.5433839262523, 3579.0494180671594, 3728.844058341923, 4002.5797291284, 4334.8266069357505, 876.7501262140387, 897.7620930453435, 921.7511271956535, 935.2050756856618, 965.7199412170532, 20034.799652003585, 22208.222712204995, 22828.0, 22908.427289317566, 23279.88360058189, 947.1286416496839, 950.9476978766096, 964.7588102974256, 976.3312131190157, 980.2696990741942, 11435.656930292105, 11564.537418503165, 11980.46087926028, 11998.340091295062, 12015.879841201651, 731361.0, 747435.0, 754209.0, 770724.0, 792351.0, 105002.47498762458, 130009.0, 134392.0, 135433.0, 136538.0, 1256.704674401515, 1264.2225031605496, 1300.089937044073, 1300.3043371796043, 1304.778187708092, 19705.802941970396, 20605.0, 20999.47501312418, 21185.258515951868, 21190.258340957993, 41482.79258603688, 55226.44773552374, 56216.71891640679, 58104.70947645235, 58150.709246453494, 77751.61124194342, 77824.61087694525, 78224.04439889049, 86608.0, 89339.0, 7431.925680743339, 11578.94210528942, 11674.88325116772, 11678.532858685558, 12005.079187330335, 12937.159084659466, 12947.417366218731, 12951.158174718614, 13325.400356984153, 13344.332783360987, 9427.764305892408, 11020.118390528578, 11207.991280784769, 11442.599509017144, 11455.484503197542, 10630.627928022483, 10644.627438039632, 10777.838332424863, 10975.121990240921, 11028.834567481656, 11416.086713062768, 11664.533418663159, 11682.0, 11757.88242117602, 11884.881151188378, 11508.424578771195, 11624.94187529057, 11741.823872642088, 11893.881061189624, 12013.279203247834, 11119.332840029625, 11138.665840024812, 11204.237296541301, 11463.828042579202, 11718.355490448108], "value": 11594.749173464696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.3091109000952, 1540.4287626620846, 1564.906105633662, 1570.0265835182188, 1582.3116944129304, 10540.0, 11139.832902506469, 11145.554177832888, 11149.665510034692, 11163.99524042837, 9808.803823923517, 10266.435346055963, 11181.105511559075, 11185.440727963589, 11185.66443006709, 2399.028393500635, 2428.9878550607223, 2443.4624382635793, 2443.816713746467, 2445.29086564896, 2340.8010319122895, 2423.45472268739, 2426.793722533587, 2428.6599876017285, 2430.0, 2341.075275266269, 2407.181558270188, 2414.8671823049726, 2420.8305418620703, 2425.3209101451534, 2316.3745788637143, 2386.486905315357, 2394.341556072077, 2398.3764221302413, 2402.2072716003677, 2369.4313364792324, 2392.210570511727, 2398.988005059981, 2400.3759022654062, 2406.1818981546357, 10779.407132607703, 11050.668479945614, 11053.0, 11058.170637202162, 11063.391513466837, 5725.341585717654, 5878.50032747217, 5889.85275368115, 5898.351181370046, 5907.970460147715, 3588.551431071116, 3607.729420293462, 3686.4654625079215, 3690.4833323334756, 3711.981440092782, 1191.7676053169635, 1200.5617949448388, 1231.7536492701406, 1256.2462522486526, 1256.4974010395824, 12344.259344439362, 12661.240325580402, 12984.87015129846, 13142.73714525704, 13277.269750163738, 4003.6396724294955, 4265.7440553566885, 4322.135572885435, 4385.407969924083, 4387.670924680661, 11519.30884146954, 11653.708657283467, 11669.474873630706, 11694.0, 11701.531938722526, 5797.130430435384, 5818.21454103699, 5823.388544202882, 5825.854353641193, 5829.970850145807, 5104.1067813133295, 5237.528622424, 5294.391145018295, 5321.707306098202, 5393.002294575516, 3839.520059992501, 3874.670652994471, 3941.9802900985887, 3963.128111815396, 3973.5033120859894, 1024.1653052761976, 1042.817506936283, 1043.446973104256, 1043.9404004934997, 1044.3368461027303, 895.9462432254086, 896.5382827843666, 961.6922584772806, 963.0786876816929, 987.0573602209872, 6358.650274234869, 6553.901691474632, 6593.83379352275, 6686.83282917931, 6819.897701534481, 967.2782447243917, 977.0717818072859, 990.1929927109383, 999.3454287441738, 1003.934744241622, 3497.370473314777, 4022.9798851006144, 4233.301505251599, 4257.808398622007, 4376.474823021258, 12973.351332433529, 13616.18302901829, 13628.523001694894, 13630.795538066937, 13704.24626645544, 13330.333483325794, 13432.261225632488, 13660.795088073686, 13669.316534173251, 13698.589042328746, 8642.783930401665, 10133.240006999504, 10240.539175737109, 10250.692479225636, 10265.589376425009, 5797.507211887037, 6747.966260168668, 6926.34199751043, 6986.47601429905, 6991.475639326969, 11995.160338776202, 12081.214721043105, 12246.20399674054, 12280.508779649072, 12436.689082772642, 12386.876131238572, 12460.688482788002, 12503.687407814878, 12611.0541709374, 12627.368631568568, 8306.958465207636, 8376.916230837614, 8462.365322600706, 8875.423097498642, 9262.95368523153, 10265.640702575374, 10357.016083472057, 10444.268901176845, 10456.0, 10464.686059418229, 8682.044975052613, 8685.956570217108, 8762.123787621187, 8773.824523509367, 8843.955780221058, 8537.74386768398, 8542.658293668184, 8623.353248506262, 8635.136486351315, 8644.783880402789, 7817.0619525657285, 7921.366290696846, 7960.800979975315, 7975.960120199362, 7981.960090199512, 5798.391168927371, 5820.912686309794, 5862.0, 6021.518278537794, 6032.728527216374, 5884.529237661062, 5928.170056192051, 6002.519798416029, 6159.784407545714, 6299.748010079546], "value": 5104.464394479522, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.0569946894712, 355.24155927095677, 355.5093970320958, 364.5388583441946, 375.5924821568598, 4214.262504061789, 4376.606105450509, 4728.810847566093, 4921.874609622889, 4929.950700492994, 3827.8468861245515, 4289.292266775986, 4865.343178670878, 4895.339129217555, 4897.3878265216845, 4065.654419374342, 4112.650424713903, 4129.525104612978, 4150.916981660365, 4200.327947528396, 4111.560953666223, 4145.709800313979, 4158.230727315441, 4173.478315210584, 4190.182914331706, 3781.73527853049, 4026.4966879140106, 4113.526944401402, 4146.813393397304, 4236.237477254109, 4019.1712770689264, 4092.406601042862, 4174.269502837006, 4336.544662810423, 4369.606735393798, 3582.677559019675, 3942.3495123304906, 3942.743721658083, 4127.422160897477, 4138.379243113557, 4206.569766279449, 4754.524547545252, 4777.522247775195, 4973.4280557735965, 5031.622628302855, 3690.0, 4313.4471590227495, 4372.934405983913, 4605.687379096941, 5044.243363495469, 4681.180793361148, 5059.748411956681, 5287.391949925769, 5288.68267903927, 5311.0, 3631.2025547353965, 4133.251387827091, 4497.628223391897, 4543.8182472701055, 4624.866907607658, 4766.856994290177, 5142.503097893468, 5419.238747407108, 5638.295213098363, 5808.796692115756, 4675.392199014106, 4718.952810471851, 4748.477667456577, 4808.687435316693, 4845.370101886732, 4048.676105911579, 4668.85993420198, 4746.572808447257, 4871.224800779424, 4999.275105109738, 4192.987366064306, 4424.601785839225, 4935.975320123376, 4946.925796113062, 5159.458256883048, 4483.820647174077, 5045.899082018339, 5260.763265653055, 5272.755431877299, 5292.417834038252, 1368.4809861054223, 1431.8759773577692, 1626.910519921392, 1652.991735041317, 1663.0936139803594, 1267.0787732057963, 1341.932903354848, 1341.9463221471033, 1368.8768010879069, 1382.0394825596113, 4415.690901636919, 5049.520295571915, 5052.444231134572, 5054.393472783289, 5084.1188760158975, 5052.9030452362595, 5463.726813659301, 5476.576090216572, 5479.53423958968, 5491.780328786804, 4344.891377715519, 4399.978000110043, 4461.866144015685, 4572.474165470947, 5043.798248070109, 2216.180013395054, 2513.6229565565313, 2637.3011152044724, 2833.5891295762, 2845.7336485264273, 5243.082460569385, 5305.973470132624, 5339.706316152571, 5628.0, 5733.885322293614, 5084.720340381241, 5420.647657902303, 5558.527525160407, 5588.441155884379, 5694.402087780723, 5052.444231134572, 5303.549198318186, 5593.797333573301, 5618.466245706733, 5636.971815140898, 4602.424696912886, 4992.975035124801, 5049.444561098349, 5423.556041913334, 5447.385255077665, 4703.976480117714, 5126.6923984561045, 5189.642152444402, 5334.493223143795, 5346.492083252085, 5100.240417056109, 5342.630656190504, 5521.337439507285, 5565.554755619622, 5689.54483641316, 3980.6417422430964, 4348.499922508952, 4735.810567577259, 4742.1227072991605, 4749.548792864673, 4602.964333025043, 4648.697834640738, 4891.241857512091, 5223.772413482904, 5483.067878460592, 4473.881529617595, 4655.371524844239, 5038.168702164102, 5218.452062533379, 5236.162214045735, 4785.253382515287, 5294.338207724035, 5310.734463276744, 5324.840254792362, 5336.985972665181, 4718.41019872516, 4753.524647535219, 5109.011228431261, 5160.626111988528, 5220.869478263074, 4832.782524786463, 4986.850394488171, 5071.095256652431, 5079.755459912345, 5397.757100930389, 4419.734815911055, 5082.796688132581, 5152.27398821399, 5182.844514664566, 5193.246979188072], "value": 4112.391620982083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.198242923998, 756.4213376766775, 759.5328872743264, 809.7327881799007, 823.2714048067459, 14534.34595443205, 16409.917950410232, 16439.342426302963, 16474.258658360373, 16509.504714858573, 12124.393780310971, 14774.409023639068, 15027.474038408658, 15157.848421515782, 15756.921215393935, 922.1792604581927, 936.1293996583175, 941.5198248893046, 945.0927109974399, 945.3382632157483, 944.7826999790052, 945.012461977232, 945.0785484152965, 946.3375637054089, 949.4255975135047, 957.7749228931195, 984.6701355046068, 984.9113579777819, 987.1362557761959, 987.8616993620899, 971.5676523946846, 985.0838719990406, 986.9883369546272, 989.2382865193832, 991.5637119667323, 728.3117454005967, 731.4733391957782, 731.5208538407298, 731.7585196885016, 732.2348146187247, 15967.760483592756, 15970.600734981603, 15982.200889955571, 16024.679506409922, 16542.503724888273, 14026.228557429336, 14149.717005659932, 14246.715065698732, 14276.785848212285, 14369.353379097965, 11640.825387619192, 11812.645620631394, 11859.347735874524, 11867.52529898803, 12001.819972700416, 3586.8923932282073, 3622.329868974222, 3672.8347224374966, 3751.362268414376, 3840.943740471365, 108841.0, 115865.42067289694, 117610.82389176084, 117720.41139794332, 118878.0, 10407.479626018669, 10621.468926553796, 10859.18556108295, 10992.450377481095, 11357.886421135849, 20787.272445464336, 22638.773612263994, 23028.078876845073, 23048.0, 23073.077076916736, 7475.775726728207, 7506.662200201004, 7523.849523009619, 7567.508111972704, 7587.506812057199, 4255.638270747021, 4347.456567929009, 4415.161119387306, 4428.535003824616, 4485.842995495207, 3487.2851065531504, 3589.21037371783, 3590.5152804371596, 3592.497050412944, 3594.263176048904, 518.3922738247419, 623.7910300049534, 624.865653884417, 625.5308518611042, 635.0315768453054, 689.9241083480813, 691.2948792231896, 693.2580653148194, 711.2070041903344, 711.4273010226714, 9368.812623747486, 9882.566573877259, 10279.691609251735, 10340.844887326695, 10378.584856605803, 652.9183852018498, 670.2660586657602, 688.9793306200822, 691.872003679321, 693.175121605288, 4870.342503762017, 4995.500449954975, 5009.949900501021, 5040.2691609716385, 5044.4198917125, 88832.0, 105607.0, 105679.0, 106978.46510767397, 107240.92759072465, 50068.4979450617, 76791.0, 89714.55142724376, 93533.0, 98250.0, 77146.4570708594, 77257.61371193107, 79168.0, 79662.0, 79762.0, 10705.304155229886, 10864.45677716127, 10934.125269978224, 11066.114710823276, 11119.666410007712, 49936.75031624819, 49965.25121620726, 50210.74894625504, 50683.49316506788, 52135.0, 63559.682201588694, 64374.35625643683, 64945.67527162334, 64970.675146623966, 65122.0, 9498.95250523743, 10394.532246049097, 10473.79052418932, 10927.672169834917, 12734.426950787421, 13738.725225495635, 14377.496787612383, 14536.200508971686, 14800.706169033167, 15059.698806024036, 13653.931730341683, 13758.0, 13895.861041389457, 13937.600994406464, 14121.43514259418, 13687.520936767165, 13828.446862125402, 13838.861611383758, 13929.164250145024, 14163.787543186654, 12715.618531444072, 13080.80378794338, 13278.203307801563, 13537.661558461117, 14440.989130760747, 9651.517424128906, 9722.368046076983, 9751.80496390054, 10030.749231269278, 10187.337823041478, 11118.666440006813, 11200.38397888124, 11360.54557817678, 11470.311781292816, 11598.07215422781], "value": 7631.044703824341, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 170.0, 143.0, 116.0, 115.0, 115.0, 115.0, 115.0, 116.0, 115.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 132.0, 143.0, 112.0, 110.0, 108.0, 107.0, 133.0, 144.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 183.0, 184.0, 185.0, 185.0, 187.0, 184.0, 185.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 92.0, 62.0, 60.0, 62.0, 61.0, 76.0, 62.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 87.0, 69.0, 69.0, 68.0, 82.0, 69.0, 69.0, 84.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 94.0, 90.0, 91.0, 91.0, 91.0, 95.0, 91.0, 91.0], "value": 91.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 104.0, 144.0, 96.0, 96.0, 97.0, 97.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 86.0, 83.0, 84.0, 83.0, 83.0, 85.0, 81.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 63.0, 64.0, 65.0, 64.0, 63.0, 64.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 63.0, 64.0, 61.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 86.0, 86.0, 80.0, 99.0, 86.0, 84.0, 90.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 146.0, 143.0, 135.0, 139.0, 142.0, 156.0, 157.0, 196.0, 173.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 165.0, 165.0, 165.0, 164.0, 165.0, 164.0, 164.0, 164.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 76.0, 79.0, 61.0, 56.0, 76.0, 78.0, 76.0, 78.0], "value": 71.2, "unit": "score"}]}]} 02:37:17 INFO - Return code: 0 02:37:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:37:17 ERROR - # TBPL SUCCESS # 02:37:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:37:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:37:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:37:17 INFO - 2017-04-19 02:37:17.931 screenresolution[2881:307309] starting screenresolution argv=screenresolution get 02:37:17 INFO - 2017-04-19 02:37:17.934 screenresolution[2881:307309] Display 0: 1600x1200x32@60 02:37:17 INFO - 2017-04-19 02:37:17.940 screenresolution[2882:307313] starting screenresolution argv=screenresolution list 02:37:17 INFO - Available Modes on Display 0 02:37:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:37:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:37:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:37:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:37:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:37:18 INFO - Intel Iris: 02:37:18 INFO - Chipset Model: Intel Iris 02:37:18 INFO - Type: GPU 02:37:18 INFO - Bus: Built-In 02:37:18 INFO - VRAM (Dynamic, Max): 1536 MB 02:37:18 INFO - Vendor: Intel (0x8086) 02:37:18 INFO - Device ID: 0x0a2e 02:37:18 INFO - Revision ID: 0x0009 02:37:18 INFO - Displays: 02:37:18 INFO - Display: 02:37:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:37:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:37:18 INFO - Display Serial Number: 1600x1200 60 02:37:18 INFO - Main Display: Yes 02:37:18 INFO - Mirror: Off 02:37:18 INFO - Online: Yes 02:37:18 INFO - Rotation: Supported 02:37:18 INFO - Return code: 0 02:37:18 INFO - Running post-action listener: _resource_record_post_action 02:37:18 INFO - [mozharness: 2017-04-19 09:37:18.183213Z] Finished run-tests step (success) 02:37:18 INFO - Running post-run listener: _resource_record_post_run 02:37:18 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 376832; Write bytes: 509428736; Read time: 4; Write time: 1589 02:37:18 INFO - TinderboxPrint: CPU usage
53.7% 02:37:18 INFO - TinderboxPrint: I/O read bytes / time
376,832 / 4 02:37:18 INFO - TinderboxPrint: I/O write bytes / time
509,428,736 / 1,589 02:37:18 INFO - TinderboxPrint: CPU idle
2,230.0 (46.3%) 02:37:18 INFO - TinderboxPrint: CPU system
956.5 (19.9%) 02:37:18 INFO - TinderboxPrint: CPU user
1,627.2 (33.8%) 02:37:18 INFO - TinderboxPrint: Swap in / out
222,433,280 / 0 02:37:18 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199565824; Write bytes: 201246208; Read time: 15654; Write time: 446 02:37:18 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 376832; Write bytes: 298257920; Read time: 4; Write time: 1107 02:37:18 INFO - Running post-run listener: _upload_blobber_files 02:37:18 INFO - Blob upload gear active. 02:37:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:37:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:37:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:37:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:37:19 INFO - (blobuploader) - INFO - Open directory for files ... 02:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:37:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:20 INFO - (blobuploader) - INFO - Done attempting. 02:37:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:37:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:37:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:21 INFO - (blobuploader) - INFO - Done attempting. 02:37:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:37:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:37:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:22 INFO - (blobuploader) - INFO - Done attempting. 02:37:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:37:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:37:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:24 INFO - (blobuploader) - INFO - Done attempting. 02:37:24 INFO - (blobuploader) - INFO - Iteration through files over. 02:37:24 INFO - Return code: 0 02:37:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:37:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:37:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29c8e831127e3894a1cf2e5f6c27acf1ed2c7636255aa6d3c91afe22fc50c19a87da1f53e1d3c90790d6cdc183e13bc26fa8f575e4a96ad9d0178cd7599a7fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbc3f7065b2ebeccef401d98a7b6b05a7736bb8c0c91fca7f225ec6611df5ae7fc907803b9f4e5ec589c0dd33d822208f3c1a09f4926e5db9f2f3546e7f93988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b9daaab331b8e5f88ed29a24bc60607ca3ced29cff80e9b4856b1abd865e34a783c16e00c307465e2d89431599eb5224341bdc17f14a107bfc10dfacf333515", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba10bcb01a896f6f722750985f4a8acd78a029e5dd00c0d661902caf6cda679ca64eb4bb2574fac59cd1ab87e658118d449d79cba4080cb6e9f373d76a950296"} 02:37:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:37:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:37:24 INFO - Contents: 02:37:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29c8e831127e3894a1cf2e5f6c27acf1ed2c7636255aa6d3c91afe22fc50c19a87da1f53e1d3c90790d6cdc183e13bc26fa8f575e4a96ad9d0178cd7599a7fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbc3f7065b2ebeccef401d98a7b6b05a7736bb8c0c91fca7f225ec6611df5ae7fc907803b9f4e5ec589c0dd33d822208f3c1a09f4926e5db9f2f3546e7f93988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b9daaab331b8e5f88ed29a24bc60607ca3ced29cff80e9b4856b1abd865e34a783c16e00c307465e2d89431599eb5224341bdc17f14a107bfc10dfacf333515", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba10bcb01a896f6f722750985f4a8acd78a029e5dd00c0d661902caf6cda679ca64eb4bb2574fac59cd1ab87e658118d449d79cba4080cb6e9f373d76a950296"} 02:37:24 INFO - Running post-run listener: copy_logs_to_upload_dir 02:37:24 INFO - Copying logs to upload dir... 02:37:24 INFO - mkdir: /builds/slave/test/build/upload/logs 02:37:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.390626 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-19 02:37:24.811843) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-19 02:37:24.815811) ========= 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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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/b29c8e831127e3894a1cf2e5f6c27acf1ed2c7636255aa6d3c91afe22fc50c19a87da1f53e1d3c90790d6cdc183e13bc26fa8f575e4a96ad9d0178cd7599a7fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbc3f7065b2ebeccef401d98a7b6b05a7736bb8c0c91fca7f225ec6611df5ae7fc907803b9f4e5ec589c0dd33d822208f3c1a09f4926e5db9f2f3546e7f93988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b9daaab331b8e5f88ed29a24bc60607ca3ced29cff80e9b4856b1abd865e34a783c16e00c307465e2d89431599eb5224341bdc17f14a107bfc10dfacf333515", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba10bcb01a896f6f722750985f4a8acd78a029e5dd00c0d661902caf6cda679ca64eb4bb2574fac59cd1ab87e658118d449d79cba4080cb6e9f373d76a950296"} build_url:https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/KF-kSloYRoWmCvmGTx_Vwg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b29c8e831127e3894a1cf2e5f6c27acf1ed2c7636255aa6d3c91afe22fc50c19a87da1f53e1d3c90790d6cdc183e13bc26fa8f575e4a96ad9d0178cd7599a7fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbc3f7065b2ebeccef401d98a7b6b05a7736bb8c0c91fca7f225ec6611df5ae7fc907803b9f4e5ec589c0dd33d822208f3c1a09f4926e5db9f2f3546e7f93988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b9daaab331b8e5f88ed29a24bc60607ca3ced29cff80e9b4856b1abd865e34a783c16e00c307465e2d89431599eb5224341bdc17f14a107bfc10dfacf333515", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba10bcb01a896f6f722750985f4a8acd78a029e5dd00c0d661902caf6cda679ca64eb4bb2574fac59cd1ab87e658118d449d79cba4080cb6e9f373d76a950296"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-19 02:37:24.850229) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 02:37:24.850552) ========= 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.WFm2wByg9Z/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.K7IdmJQjpp/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005208 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-19 02:37:24.899606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-19 02:37:24.899927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-19 02:37:24.900458) ========= ========= Total master_lag: 0.18 =========