builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1471027028.61 results: success (0) buildid: 20160812094617 builduid: e9b6a77592dd40ca9a6e484afc6c1d87 revision: c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.612385) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.612818) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.613109) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.637399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.637806) ========= 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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 --2016-08-12 11:37:08-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 37.5M=0s 2016-08-12 11:37:08 (37.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135338 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.795661) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.796036) ========= 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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.026446 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:08.844252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 11:37:08.844618) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wOv0WmXmSE/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.B4kFsRVrDd/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 2016-08-12 11:37:08,927 truncating revision to first 12 chars 2016-08-12 11:37:08,928 Setting DEBUG logging. 2016-08-12 11:37:08,928 attempt 1/10 2016-08-12 11:37:08,928 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c4ad5f94a5bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 11:37:10,358 unpacking tar archive at: mozilla-central-c4ad5f94a5bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.684644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 11:37:10.550962) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:10.551284) ========= script_repo_revision: c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:10.551671) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:10.551932) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 11:37:10.570048) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-08-12 11:37:10.570641) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wOv0WmXmSE/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.B4kFsRVrDd/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:37:10 INFO - MultiFileLogger online at 20160812 11:37:10 in /builds/slave/test 11:37:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:37:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:37:10 INFO - {'append_to_log': False, 11:37:10 INFO - 'base_work_dir': '/builds/slave/test', 11:37:10 INFO - 'blob_upload_branch': 'Firefox', 11:37:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:37:10 INFO - 'branch': 'Firefox', 11:37:10 INFO - 'buildbot_json_path': 'buildprops.json', 11:37:10 INFO - 'config_files': ('talos/mac_config.py',), 11:37:10 INFO - 'default_actions': ('clobber', 11:37:10 INFO - 'read-buildbot-config', 11:37:10 INFO - 'download-and-extract', 11:37:10 INFO - 'populate-webroot', 11:37:10 INFO - 'create-virtualenv', 11:37:10 INFO - 'install', 11:37:10 INFO - 'run-tests'), 11:37:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:37:10 INFO - 'download_minidump_stackwalk': True, 11:37:10 INFO - 'download_symbols': 'ondemand', 11:37:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:37:10 INFO - 'tooltool.py': '/tools/tooltool.py', 11:37:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:37:10 INFO - '/tools/misc-python/virtualenv.py')}, 11:37:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:37:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:37:10 INFO - 'installer_path': 'installer.exe', 11:37:10 INFO - 'log_level': 'info', 11:37:10 INFO - 'log_name': 'talos', 11:37:10 INFO - 'log_to_console': True, 11:37:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:37:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:37:10 INFO - 'opt_config_files': (), 11:37:10 INFO - 'pip_index': False, 11:37:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:37:10 INFO - 'cmd': ('bash', 11:37:10 INFO - '-c', 11:37:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:37:10 INFO - 'enabled': True, 11:37:10 INFO - 'halt_on_failure': False, 11:37:10 INFO - 'name': 'check_screen_resolution'},), 11:37:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:37:10 INFO - 'cmd': ('bash', 11:37:10 INFO - '-c', 11:37:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:37:10 INFO - 'enabled': True, 11:37:10 INFO - 'halt_on_failure': False, 11:37:10 INFO - 'name': 'check_screen_resolution'},), 11:37:10 INFO - 'run_cmd_checks_enabled': True, 11:37:10 INFO - 'sps_profile': False, 11:37:10 INFO - 'sps_profile_interval': 0, 11:37:10 INFO - 'suite': 'dromaeojs', 11:37:10 INFO - 'system_bits': '32', 11:37:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:37:10 INFO - 'title': 't-yosemite-r7-0018', 11:37:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:37:10 INFO - 'use_talos_json': True, 11:37:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:37:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:37:10 INFO - 'work_dir': 'build'} 11:37:10 INFO - [mozharness: 2016-08-12 18:37:10.768877Z] Running clobber step. 11:37:10 INFO - Running pre-action listener: _resource_record_pre_action 11:37:10 INFO - Running main action method: clobber 11:37:10 INFO - rmtree: /builds/slave/test/build 11:37:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:37:12 INFO - Running post-action listener: _resource_record_post_action 11:37:12 INFO - [mozharness: 2016-08-12 18:37:12.905180Z] Finished clobber step (success) 11:37:12 INFO - [mozharness: 2016-08-12 18:37:12.905356Z] Running read-buildbot-config step. 11:37:12 INFO - Running pre-action listener: _resource_record_pre_action 11:37:12 INFO - Running main action method: read_buildbot_config 11:37:12 INFO - Using buildbot properties: 11:37:12 INFO - { 11:37:12 INFO - "project": "", 11:37:12 INFO - "product": "firefox", 11:37:12 INFO - "script_repo_revision": "production", 11:37:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:37:12 INFO - "repository": "", 11:37:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:37:12 INFO - "buildid": "20160812094617", 11:37:12 INFO - "pgo_build": "False", 11:37:12 INFO - "basedir": "/builds/slave/test", 11:37:12 INFO - "buildnumber": 2, 11:37:12 INFO - "slavename": "t-yosemite-r7-0018", 11:37:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:37:12 INFO - "platform": "yosemite_r7", 11:37:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:37:12 INFO - "branch": "mozilla-central", 11:37:12 INFO - "repo_path": "mozilla-central", 11:37:12 INFO - "revision": "c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e", 11:37:12 INFO - "stage_platform": "macosx64", 11:37:12 INFO - "builduid": "e9b6a77592dd40ca9a6e484afc6c1d87", 11:37:12 INFO - "slavebuilddir": "test" 11:37:12 INFO - } 11:37:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:37:12 INFO - Running post-action listener: _resource_record_post_action 11:37:12 INFO - [mozharness: 2016-08-12 18:37:12.908909Z] Finished read-buildbot-config step (success) 11:37:12 INFO - [mozharness: 2016-08-12 18:37:12.909020Z] Running download-and-extract step. 11:37:12 INFO - Running pre-action listener: _resource_record_pre_action 11:37:12 INFO - Running main action method: download_and_extract 11:37:12 INFO - mkdir: /builds/slave/test/build/tests 11:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:12 INFO - trying https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:37:12 INFO - Downloading https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:37:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:37:14 INFO - Downloaded 1199 bytes. 11:37:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:37:14 INFO - Using the following test package requirements: 11:37:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:37:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:37:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:37:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'jsshell-mac.zip'], 11:37:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:37:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:37:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:37:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:37:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:37:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:37:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:37:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:37: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')]} 11:37:14 INFO - trying https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:37:14 INFO - Downloading https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:37:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:37:15 INFO - Downloaded 16328711 bytes. 11:37:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:37:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:37:21 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')]} 11:37:21 INFO - trying https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:37:21 INFO - Downloading https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:37:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:37:23 INFO - Downloaded 16328711 bytes. 11:37:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:29 INFO - trying https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:37:29 INFO - Downloading https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:37:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:38:00 INFO - Downloaded 14413719 bytes. 11:38:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:01 INFO - trying https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:38:01 INFO - Downloading https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:38:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:38:05 INFO - Downloaded 110967868 bytes. 11:38:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:38:05 INFO - mkdir: /builds/slave/test/properties 11:38:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:38:05 INFO - Writing to file /builds/slave/test/properties/build_url 11:38:05 INFO - Contents: 11:38:05 INFO - build_url:https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:38:06 INFO - Running post-action listener: _resource_record_post_action 11:38:06 INFO - Running post-action listener: set_extra_try_arguments 11:38:06 INFO - [mozharness: 2016-08-12 18:38:06.148759Z] Finished download-and-extract step (success) 11:38:06 INFO - [mozharness: 2016-08-12 18:38:06.148888Z] Running populate-webroot step. 11:38:06 INFO - Running pre-action listener: _resource_record_pre_action 11:38:06 INFO - Running main action method: populate_webroot 11:38:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:38:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:38:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:38:06 INFO - '--tppagecycles', 11:38:06 INFO - '7'], 11:38:06 INFO - 'tests': ['tsvgm']}}, 11:38:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:38:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:38:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:38:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:38:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:38:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:38:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['damp', 'tps']}, 11:38:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'tests': ['damp', 'tps']}, 11:38:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['dromaeo_dom']}, 11:38:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:38:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['basic_compositor_video']}, 11:38:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:38:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['a11yr', 11:38:06 INFO - 'ts_paint', 11:38:06 INFO - 'tpaint', 11:38:06 INFO - 'sessionrestore', 11:38:06 INFO - 'sessionrestore_no_auto_restore', 11:38:06 INFO - 'tabpaint']}, 11:38:06 INFO - 'other-e10s': {'tests': ['a11yr', 11:38:06 INFO - 'ts_paint', 11:38:06 INFO - 'tpaint', 11:38:06 INFO - 'sessionrestore', 11:38:06 INFO - 'sessionrestore_no_auto_restore', 11:38:06 INFO - 'tabpaint']}, 11:38:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:38:06 INFO - 'ts_paint', 11:38:06 INFO - 'tpaint', 11:38:06 INFO - 'sessionrestore', 11:38:06 INFO - 'sessionrestore_no_auto_restore', 11:38:06 INFO - 'tabpaint']}, 11:38:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:38:06 INFO - 'ts_paint', 11:38:06 INFO - 'tpaint', 11:38:06 INFO - 'sessionrestore', 11:38:06 INFO - 'sessionrestore_no_auto_restore', 11:38:06 INFO - 'tabpaint']}, 11:38:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['a11yr', 11:38:06 INFO - 'ts_paint', 11:38:06 INFO - 'tpaint', 11:38:06 INFO - 'sessionrestore', 11:38:06 INFO - 'sessionrestore_no_auto_restore', 11:38:06 INFO - 'tabpaint']}, 11:38:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['a11yr', 11:38:06 INFO - 'ts_paint', 11:38:06 INFO - 'tpaint', 11:38:06 INFO - 'sessionrestore', 11:38:06 INFO - 'sessionrestore_no_auto_restore', 11:38:06 INFO - 'tabpaint']}, 11:38:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['tsvgx', 11:38:06 INFO - 'tsvgr_opacity', 11:38:06 INFO - 'tart', 11:38:06 INFO - 'tscrollx', 11:38:06 INFO - 'cart']}, 11:38:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:38:06 INFO - 'tsvgr_opacity', 11:38:06 INFO - 'tart', 11:38:06 INFO - 'tscrollx', 11:38:06 INFO - 'cart']}, 11:38:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'talos_options': ['--disable-e10s'], 11:38:06 INFO - 'tests': ['tp5o']}, 11:38:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'tests': ['tp5o']}, 11:38:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'talos_options': ['--disable-e10s', 11:38:06 INFO - '--xperf_path', 11:38:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:38:06 INFO - 'tests': ['tp5n']}, 11:38:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:06 INFO - 'talos_options': ['--xperf_path', 11:38:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:38:06 INFO - 'tests': ['tp5n']}}, 11:38:06 INFO - 'talos.zip': {'path': '', 11:38:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:38:06 INFO - Running post-action listener: _resource_record_post_action 11:38:06 INFO - [mozharness: 2016-08-12 18:38:06.167373Z] Finished populate-webroot step (success) 11:38:06 INFO - [mozharness: 2016-08-12 18:38:06.167469Z] Running create-virtualenv step. 11:38:06 INFO - Running pre-action listener: _resource_record_pre_action 11:38:06 INFO - Running main action method: create_virtualenv 11:38:06 INFO - Creating virtualenv /builds/slave/test/build/venv 11:38:06 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 11:38:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:38:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:38:06 INFO - Using real prefix '/tools/python27' 11:38:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:38:07 INFO - Installing distribute.............................................................................................................................................................................................done. 11:38:09 INFO - Installing pip.................done. 11:38:09 INFO - Return code: 0 11:38:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:38:09 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')]} 11:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:10 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')]} 11:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:10 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:10 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 11:38:10 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 11:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:10 INFO - 'HOME': '/Users/cltbld', 11:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:10 INFO - 'LOGNAME': 'cltbld', 11:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:10 INFO - 'MOZ_NO_REMOTE': '1', 11:38:10 INFO - 'NO_EM_RESTART': '1', 11:38:10 INFO - 'PAGER': '/bin/cat', 11:38:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:10 INFO - 'PWD': '/builds/slave/test', 11:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:10 INFO - 'SHELL': '/bin/bash', 11:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:10 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:10 INFO - 'USER': 'cltbld', 11:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:10 INFO - 'XPC_FLAGS': '0x0', 11:38:10 INFO - 'XPC_SERVICE_NAME': '0', 11:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:10 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 11:38:10 INFO - Cleaning up... 11:38:10 INFO - Return code: 0 11:38:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:38:10 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')]} 11:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:10 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')]} 11:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:10 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:10 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 11:38:10 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 11:38:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:10 INFO - 'HOME': '/Users/cltbld', 11:38:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:10 INFO - 'LOGNAME': 'cltbld', 11:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:10 INFO - 'MOZ_NO_REMOTE': '1', 11:38:10 INFO - 'NO_EM_RESTART': '1', 11:38:10 INFO - 'PAGER': '/bin/cat', 11:38:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:10 INFO - 'PWD': '/builds/slave/test', 11:38:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:10 INFO - 'SHELL': '/bin/bash', 11:38:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:10 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:10 INFO - 'USER': 'cltbld', 11:38:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:10 INFO - 'XPC_FLAGS': '0x0', 11:38:10 INFO - 'XPC_SERVICE_NAME': '0', 11:38:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:10 INFO - Downloading/unpacking psutil>=3.1.1 11:38: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 11:38: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 11:38: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 11:38: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 11:38: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 11:38: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 11:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:38:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:38:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:38:13 INFO - Installing collected packages: psutil 11:38:13 INFO - Running setup.py install for psutil 11:38:13 INFO - building 'psutil._psutil_osx' extension 11:38:13 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 11:38:14 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 11:38:14 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 11:38:14 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 11:38:14 INFO - building 'psutil._psutil_posix' extension 11:38:14 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 11:38:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:38:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:38:14 INFO - ^ 11:38:14 INFO - 1 warning generated. 11:38:14 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 11:38:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:38:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:38:14 INFO - Successfully installed psutil 11:38:14 INFO - Cleaning up... 11:38:14 INFO - Return code: 0 11:38:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:38: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')]} 11:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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')]} 11:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:38: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 mozsystemmonitor==0.3 11:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:14 INFO - 'HOME': '/Users/cltbld', 11:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:14 INFO - 'LOGNAME': 'cltbld', 11:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:14 INFO - 'MOZ_NO_REMOTE': '1', 11:38:14 INFO - 'NO_EM_RESTART': '1', 11:38:14 INFO - 'PAGER': '/bin/cat', 11:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:14 INFO - 'PWD': '/builds/slave/test', 11:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:14 INFO - 'SHELL': '/bin/bash', 11:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:14 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:14 INFO - 'USER': 'cltbld', 11:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:14 INFO - 'XPC_FLAGS': '0x0', 11:38:14 INFO - 'XPC_SERVICE_NAME': '0', 11:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:38:15 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 11:38:15 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 11:38:15 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 11:38:15 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 11:38:15 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 11:38:15 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 11:38:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:38:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:38:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:38:17 INFO - Installing collected packages: mozsystemmonitor 11:38:17 INFO - Running setup.py install for mozsystemmonitor 11:38:17 INFO - Successfully installed mozsystemmonitor 11:38:17 INFO - Cleaning up... 11:38:18 INFO - Return code: 0 11:38:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:38:18 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')]} 11:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:18 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')]} 11:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:18 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:18 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 11:38:18 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 11:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:18 INFO - 'HOME': '/Users/cltbld', 11:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:18 INFO - 'LOGNAME': 'cltbld', 11:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:18 INFO - 'MOZ_NO_REMOTE': '1', 11:38:18 INFO - 'NO_EM_RESTART': '1', 11:38:18 INFO - 'PAGER': '/bin/cat', 11:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:18 INFO - 'PWD': '/builds/slave/test', 11:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:18 INFO - 'SHELL': '/bin/bash', 11:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:18 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:18 INFO - 'USER': 'cltbld', 11:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:18 INFO - 'XPC_FLAGS': '0x0', 11:38:18 INFO - 'XPC_SERVICE_NAME': '0', 11:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:18 INFO - Downloading/unpacking jsonschema==2.5.1 11:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:38:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:38:20 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 11:38:20 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 11:38:20 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 11:38:20 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 11:38:20 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 11:38:20 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 11:38:21 INFO - Downloading functools32-3.2.3-2.tar.gz 11:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:38:21 INFO - warning: no files found matching '*.txt' 11:38:21 INFO - no previously-included directories found matching 'build' 11:38:21 INFO - no previously-included directories found matching 'dist' 11:38:21 INFO - no previously-included directories found matching '.git*' 11:38:21 INFO - Installing collected packages: jsonschema, functools32 11:38:21 INFO - Running setup.py install for functools32 11:38:21 INFO - warning: no files found matching '*.txt' 11:38:21 INFO - no previously-included directories found matching 'build' 11:38:21 INFO - no previously-included directories found matching 'dist' 11:38:21 INFO - no previously-included directories found matching '.git*' 11:38:21 INFO - Successfully installed jsonschema functools32 11:38:21 INFO - Cleaning up... 11:38:21 INFO - Return code: 0 11:38:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:38:21 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')]} 11:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:21 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')]} 11:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:21 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:21 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 11:38:21 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 11:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:21 INFO - 'HOME': '/Users/cltbld', 11:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:21 INFO - 'LOGNAME': 'cltbld', 11:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:21 INFO - 'MOZ_NO_REMOTE': '1', 11:38:21 INFO - 'NO_EM_RESTART': '1', 11:38:21 INFO - 'PAGER': '/bin/cat', 11:38:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:21 INFO - 'PWD': '/builds/slave/test', 11:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:21 INFO - 'SHELL': '/bin/bash', 11:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:21 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:21 INFO - 'USER': 'cltbld', 11:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:21 INFO - 'XPC_FLAGS': '0x0', 11:38:21 INFO - 'XPC_SERVICE_NAME': '0', 11:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:38:21 INFO - Cleaning up... 11:38:21 INFO - Return code: 0 11:38:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:38:21 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')]} 11:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:21 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')]} 11:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:21 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:21 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 11:38:21 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 11:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:21 INFO - 'HOME': '/Users/cltbld', 11:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:21 INFO - 'LOGNAME': 'cltbld', 11:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:21 INFO - 'MOZ_NO_REMOTE': '1', 11:38:21 INFO - 'NO_EM_RESTART': '1', 11:38:21 INFO - 'PAGER': '/bin/cat', 11:38:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:21 INFO - 'PWD': '/builds/slave/test', 11:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:21 INFO - 'SHELL': '/bin/bash', 11:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:21 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:21 INFO - 'USER': 'cltbld', 11:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:21 INFO - 'XPC_FLAGS': '0x0', 11:38:21 INFO - 'XPC_SERVICE_NAME': '0', 11:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:21 INFO - Downloading/unpacking blobuploader==1.2.4 11:38:21 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 11:38:21 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 11:38:21 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 11:38:21 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 11:38:21 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 11:38:21 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 11:38:24 INFO - Downloading blobuploader-1.2.4.tar.gz 11:38:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:38:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:38:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:38:25 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 11:38:25 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 11:38:25 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 11:38:25 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 11:38:25 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 11:38:25 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 11:38:25 INFO - Downloading docopt-0.6.1.tar.gz 11:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:38:25 INFO - Installing collected packages: blobuploader, requests, docopt 11:38:25 INFO - Running setup.py install for blobuploader 11:38:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:38:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:38:25 INFO - Running setup.py install for requests 11:38:26 INFO - Running setup.py install for docopt 11:38:26 INFO - Successfully installed blobuploader requests docopt 11:38:26 INFO - Cleaning up... 11:38:26 INFO - Return code: 0 11:38:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:26 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')]} 11:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:26 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')]} 11:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:26 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:26 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 11:38:26 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 11:38:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:26 INFO - 'HOME': '/Users/cltbld', 11:38:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:26 INFO - 'LOGNAME': 'cltbld', 11:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:26 INFO - 'MOZ_NO_REMOTE': '1', 11:38:26 INFO - 'NO_EM_RESTART': '1', 11:38:26 INFO - 'PAGER': '/bin/cat', 11:38:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:26 INFO - 'PWD': '/builds/slave/test', 11:38:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:26 INFO - 'SHELL': '/bin/bash', 11:38:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:26 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:26 INFO - 'USER': 'cltbld', 11:38:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:26 INFO - 'XPC_FLAGS': '0x0', 11:38:26 INFO - 'XPC_SERVICE_NAME': '0', 11:38:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:38:26 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-E2NNdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:38:26 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-BG965B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Z1RFtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-0h0eb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-9NliC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-QOerYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-W7LKK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-z7Na9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-gs4nLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-37vmbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-CqrdqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:38:27 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-tzC5Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:38:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-lICh_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:38:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-jbbzfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-OXw76h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:38:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-NZAwaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:38:28 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-JQ_GBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:38:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:38:28 INFO - Running setup.py install for manifestparser 11:38:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:38:28 INFO - Running setup.py install for mozcrash 11:38:28 INFO - Running setup.py install for mozdebug 11:38:28 INFO - Running setup.py install for mozdevice 11:38:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Running setup.py install for mozfile 11:38:29 INFO - Running setup.py install for mozhttpd 11:38:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Running setup.py install for mozinfo 11:38:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Running setup.py install for mozInstall 11:38:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:38:29 INFO - Running setup.py install for mozleak 11:38:29 INFO - Running setup.py install for mozlog 11:38:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:38:30 INFO - Running setup.py install for moznetwork 11:38:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:38:30 INFO - Running setup.py install for mozprocess 11:38:30 INFO - Running setup.py install for mozprofile 11:38:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:38:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:38:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:38:30 INFO - Running setup.py install for mozrunner 11:38:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:38:30 INFO - Running setup.py install for mozscreenshot 11:38:30 INFO - Running setup.py install for moztest 11:38:30 INFO - Running setup.py install for mozversion 11:38:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:38:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:38:31 INFO - Cleaning up... 11:38:31 INFO - Return code: 0 11:38:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:31 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')]} 11:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:31 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')]} 11:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:31 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:31 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 11:38:31 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 11:38:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:31 INFO - 'HOME': '/Users/cltbld', 11:38:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:31 INFO - 'LOGNAME': 'cltbld', 11:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:31 INFO - 'MOZ_NO_REMOTE': '1', 11:38:31 INFO - 'NO_EM_RESTART': '1', 11:38:31 INFO - 'PAGER': '/bin/cat', 11:38:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:31 INFO - 'PWD': '/builds/slave/test', 11:38:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:31 INFO - 'SHELL': '/bin/bash', 11:38:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:31 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:31 INFO - 'USER': 'cltbld', 11:38:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:31 INFO - 'XPC_FLAGS': '0x0', 11:38:31 INFO - 'XPC_SERVICE_NAME': '0', 11:38:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:38:31 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-6im_c_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:38:31 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)) 11:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:38:31 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-gktker-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 11:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:38:31 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-vuq_jU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:38:31 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)) 11:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:38:31 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-WcWlXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:38:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 11:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:38:31 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-N7qwD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:38:31 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)) 11:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:38:31 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-S5gek8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-ct6toG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-rFsIF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Xxxnt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-yRfjg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:38:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-hsXOHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-gwiV_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:38:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-wHZAqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-BqdKSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:38:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-iGNRom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:32 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)) 11:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:38:32 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-vJoLEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 11:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:38:33 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-q5ak7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:38:33 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)) 11:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:38:33 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)) 11:38:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:38:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:38:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:35 INFO - Downloading blessings-1.6.tar.gz 11:38:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:38:35 INFO - Installing collected packages: blessings 11:38:35 INFO - Running setup.py install for blessings 11:38:35 INFO - Successfully installed blessings 11:38:35 INFO - Cleaning up... 11:38:36 INFO - Return code: 0 11:38:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:38:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:38:36 INFO - Reading from file tmpfile_stdout 11:38:36 INFO - Current package versions: 11:38:36 INFO - blessings == 1.6 11:38:36 INFO - blobuploader == 1.2.4 11:38:36 INFO - docopt == 0.6.1 11:38:36 INFO - functools32 == 3.2.3-2 11:38:36 INFO - jsonschema == 2.5.1 11:38:36 INFO - manifestparser == 1.1 11:38:36 INFO - mozInstall == 1.12 11:38:36 INFO - mozcrash == 0.17 11:38:36 INFO - mozdebug == 0.1 11:38:36 INFO - mozdevice == 0.48 11:38:36 INFO - mozfile == 1.2 11:38:36 INFO - mozhttpd == 0.7 11:38:36 INFO - mozinfo == 0.9 11:38:36 INFO - mozleak == 0.1 11:38:36 INFO - mozlog == 3.3 11:38:36 INFO - moznetwork == 0.27 11:38:36 INFO - mozprocess == 0.23 11:38:36 INFO - mozprofile == 0.28 11:38:36 INFO - mozrunner == 6.12 11:38:36 INFO - mozscreenshot == 0.1 11:38:36 INFO - mozsystemmonitor == 0.3 11:38:36 INFO - moztest == 0.7 11:38:36 INFO - mozversion == 1.4 11:38:36 INFO - psutil == 3.1.1 11:38:36 INFO - requests == 1.2.3 11:38:36 INFO - wsgiref == 0.1.2 11:38:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:36 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')]} 11:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:36 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')]} 11:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:36 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:36 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 11:38:36 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 11:38:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:36 INFO - 'HOME': '/Users/cltbld', 11:38:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:36 INFO - 'LOGNAME': 'cltbld', 11:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:36 INFO - 'MOZ_NO_REMOTE': '1', 11:38:36 INFO - 'NO_EM_RESTART': '1', 11:38:36 INFO - 'PAGER': '/bin/cat', 11:38:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:36 INFO - 'PWD': '/builds/slave/test', 11:38:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:36 INFO - 'SHELL': '/bin/bash', 11:38:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:36 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:36 INFO - 'USER': 'cltbld', 11:38:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:36 INFO - 'XPC_FLAGS': '0x0', 11:38:36 INFO - 'XPC_SERVICE_NAME': '0', 11:38:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 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)) 11:38:36 INFO - Cleaning up... 11:38:36 INFO - Return code: 0 11:38:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:38:36 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')]} 11:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:36 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')]} 11:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:36 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 0x10b5eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b681618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84ead9cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, '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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 11:38:36 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 11:38:36 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 11:38:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:38:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:36 INFO - 'HOME': '/Users/cltbld', 11:38:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:36 INFO - 'LOGNAME': 'cltbld', 11:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:36 INFO - 'MOZ_NO_REMOTE': '1', 11:38:36 INFO - 'NO_EM_RESTART': '1', 11:38:36 INFO - 'PAGER': '/bin/cat', 11:38:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:36 INFO - 'PWD': '/builds/slave/test', 11:38:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:36 INFO - 'SHELL': '/bin/bash', 11:38:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:38:36 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:38:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:36 INFO - 'USER': 'cltbld', 11:38:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:36 INFO - 'XPC_FLAGS': '0x0', 11:38:36 INFO - 'XPC_SERVICE_NAME': '0', 11:38:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:38:36 INFO - Cleaning up... 11:38:36 INFO - Return code: 0 11:38:36 INFO - Running post-action listener: _resource_record_post_action 11:38:36 INFO - Running post-action listener: _start_resource_monitoring 11:38:36 INFO - Starting resource monitoring. 11:38:36 INFO - [mozharness: 2016-08-12 18:38:36.918364Z] Finished create-virtualenv step (success) 11:38:36 INFO - [mozharness: 2016-08-12 18:38:36.919382Z] Running install step. 11:38:36 INFO - Running pre-action listener: _resource_record_pre_action 11:38:36 INFO - Running main action method: install 11:38:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:38:37 INFO - Reading from file tmpfile_stdout 11:38:37 INFO - Detecting whether we're running mozinstall >=1.0... 11:38:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:38:37 INFO - Reading from file tmpfile_stdout 11:38:37 INFO - Output received: 11:38:37 INFO - Usage: mozinstall [options] installer 11:38:37 INFO - Options: 11:38:37 INFO - -h, --help show this help message and exit 11:38:37 INFO - -d DEST, --destination=DEST 11:38:37 INFO - Directory to install application into. [default: 11:38:37 INFO - "/builds/slave/test"] 11:38:37 INFO - --app=APP Application being installed. [default: firefox] 11:38:37 INFO - mkdir: /builds/slave/test/build/application 11:38:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:39:11 INFO - Reading from file tmpfile_stdout 11:39:11 INFO - Output received: 11:39:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:39:11 INFO - Running post-action listener: _resource_record_post_action 11:39:11 INFO - [mozharness: 2016-08-12 18:39:11.488118Z] Finished install step (success) 11:39:11 INFO - [mozharness: 2016-08-12 18:39:11.488252Z] Running run-tests step. 11:39:11 INFO - Running pre-action listener: _resource_record_pre_action 11:39:11 INFO - Running main action method: run_tests 11:39:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:39:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:39:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:39:11 INFO - 2016-08-12 11:39:11.502 screenresolution[1636:7480] starting screenresolution argv=screenresolution get 11:39:11 INFO - 2016-08-12 11:39:11.505 screenresolution[1636:7480] Display 0: 1600x1200x32@60 11:39:11 INFO - 2016-08-12 11:39:11.511 screenresolution[1637:7482] starting screenresolution argv=screenresolution list 11:39:11 INFO - Available Modes on Display 0 11:39:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:39:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:39:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:39:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:39:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:39:11 INFO - Intel Iris: 11:39:11 INFO - Chipset Model: Intel Iris 11:39:11 INFO - Type: GPU 11:39:11 INFO - Bus: Built-In 11:39:11 INFO - VRAM (Dynamic, Max): 1536 MB 11:39:11 INFO - Vendor: Intel (0x8086) 11:39:11 INFO - Device ID: 0x0a2e 11:39:11 INFO - Revision ID: 0x0009 11:39:11 INFO - Displays: 11:39:11 INFO - Display: 11:39:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:39:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:39:11 INFO - Display Serial Number: 1600x1200 60 11:39:11 INFO - Main Display: Yes 11:39:11 INFO - Mirror: Off 11:39:11 INFO - Online: Yes 11:39:11 INFO - Rotation: Supported 11:39:11 INFO - Return code: 0 11:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:39:11 INFO - Python 2.7.3 11:39:11 INFO - Return code: 0 11:39:11 INFO - grabbing minidump binary from tooltool 11:39:11 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')]} 11:39:11 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 0x10b550d30>, '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 0x10b2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b548b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:39:11 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 11:39:11 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 11:39:11 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 11:39:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:39:11 INFO - Return code: 0 11:39:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:39:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:39:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:39:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:39:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 11:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 11:39:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wOv0WmXmSE/Render', 11:39:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:11 INFO - 'HOME': '/Users/cltbld', 11:39:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:11 INFO - 'LOGNAME': 'cltbld', 11:39:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:39:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:11 INFO - 'MOZ_NO_REMOTE': '1', 11:39:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:39:11 INFO - 'NO_EM_RESTART': '1', 11:39:11 INFO - 'PAGER': '/bin/cat', 11:39:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:11 INFO - 'PWD': '/builds/slave/test', 11:39:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:39:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:11 INFO - 'SHELL': '/bin/bash', 11:39:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4kFsRVrDd/Listeners', 11:39:11 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 11:39:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:11 INFO - 'USER': 'cltbld', 11:39:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:11 INFO - 'XPC_FLAGS': '0x0', 11:39:11 INFO - 'XPC_SERVICE_NAME': '0', 11:39:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 11:39:12 INFO - mozversion application_buildid: 20160812094617 11:39:12 INFO - mozversion application_changeset: c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e 11:39:12 INFO - mozversion application_display_name: Nightly 11:39:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:39:12 INFO - mozversion application_name: Firefox 11:39:12 INFO - mozversion application_remotingname: firefox 11:39:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:39:12 INFO - mozversion application_vendor: Mozilla 11:39:12 INFO - mozversion application_version: 51.0a1 11:39:12 INFO - mozversion platform_buildid: 20160812094617 11:39:12 INFO - mozversion platform_changeset: c4ad5f94a5bc06aa2e6a49d49ab918aa1cea906e 11:39:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:39:12 INFO - mozversion platform_version: 51.0a1 11:39:12 INFO - using testdate: 1471027152 11:39:12 INFO - actual date: 1471027152 11:39:12 INFO - starting webserver on 'localhost:49263' 11:39:12 INFO - SUITE-START | Running 2 tests 11:39:12 INFO - TEST-START | dromaeo_css 11:39:12 INFO - operating with platform_type : mac_ 11:39:12 INFO - Initialising browser for dromaeo_css test... 11:39:12 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpp9luUD/profile http://localhost:49263/getInfo.html) 11:39:14 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 11:39:14 INFO - PROCESS | 1647 | colorDepth:24 11:39:14 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 11:39:14 INFO - PROCESS | 1647 | __metrics 11:39:14 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:39:14 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:39:15 INFO - TEST-INFO | 1647: exit 0 11:39:15 INFO - Browser initialized. 11:39:15 INFO - Running cycle 1/1 for dromaeo_css test... 11:39:15 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpp9luUD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:39:27 INFO - PROCESS | 1649 | RSS: Main: 243470336 11:39:27 INFO - PROCESS | 1649 | 11:40:18 INFO - PROCESS | 1649 | 1471027218573 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:42:30 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 11:42:31 INFO - PROCESS | 1649 | RSS: Main: 357208064 11:42:31 INFO - PROCESS | 1649 | 11:45:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 11:45:35 INFO - PROCESS | 1649 | RSS: Main: 381001728 11:45:35 INFO - PROCESS | 1649 | 11:48:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 11:48:38 INFO - PROCESS | 1649 | RSS: Main: 374882304 11:48:38 INFO - PROCESS | 1649 | 11:51:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 11:51:41 INFO - PROCESS | 1649 | RSS: Main: 348725248 11:51:41 INFO - PROCESS | 1649 | 11:54:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 11:54:45 INFO - PROCESS | 1649 | RSS: Main: 347271168 11:54:45 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 11:57:48 INFO - PROCESS | 1649 | RSS: Main: 483676160 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | __start_tp_report 11:57:48 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 11:57:48 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 11:57:48 INFO - PROCESS | 1649 | |i|pagename|runs| 11:57:48 INFO - PROCESS | 1649 | |0;dojo.html;3146.056183145057;3266.00386882001;3308.768386212965;3326.7025859914625;3367.612724536679;5445.482679145475;5499.147632117023;5538.529225015868;5549.112142057266;5601.551875849932;5498.917516237259;5555.111182210847;5567.554595632349;5568.693721845297;5568.777248910039;5033.496650334965;5066.18940969445;5091.9236211456855;5112.284280200774;5173.042987047389;5045.596352291808;5061.620378471611;5099.541041306282;5179.663321884065;5184.2223666450145;5169.586433085363;5173.6119791015635;5181.715005674687;5193.195054766498;5224.712640804754;4368.191884501377;4963.854472393483;5161.993411284801;5165.483451654842;5175.223716442526;5063.949360506384;5068.873278168038;5080.695158290478;5126.666766660155;5141.126008578551;4528.366028755977;5434.864128396782;5446.8910621787345;5497;5561.805336813192;5948;5976.940230597682;5980.8504787380225;5982.461578457916;6014.8496287592725;6105.389461053859;6172.1667574877265;6200.162977997958;6205.317415084337;6218;5521.503064724154;5585.1901474286005;5587;5645.379008309083;5715.485606295411;6278.372162783685;6295.213098362705;6316.115743795872;6319.96840015797;6345.873082538324;5952.791652292149;5968.552358573038;5969.492593129633;6016.2479690038745;6016.879662406727;5947.197128387612;6000.0999850022845;6046.09308603713;6130.478909292671;6143.662098584531;6030.27636683592;6061.909071363933;6083.452489275986;6084.5132389408745;6126.23422072241;6036.698165091728;6079.908801367983;6085.5435842312;6106.267247930276;6131.877362452726;4791.664583479123;4852.2479015752615;4867.294242334841;4897.877553061202;4906.435759887588;4340.153670034376;4365.869023929287;4368.541303163186;4405.361222622766;4425.867223983286;5508.256385388001;5514.889702205934;5522.22688823565;5533.22534845122;5576.804811831566;5972.731227094791;6035.486983606443;6061.454469097803;6139.877202456015;6162.137300777895;5443.972780136074;5490.286262785892;5509.925564514881;5518.91721624176;5554.111342185231;5301.019311427398;5304.549113325334;5311.256424100629;5324.440933701983;5349.170878513836;5594.832155035356;6144.907826382697;6193.690315484298;6309.432151106423;6321.841953951004;6122.479589234965;6168.414000670109;6181.443670069715;6189.597676151036;6197.938020619917;6332.430081292706;6348.93651063502;6352.968235158794;6362.713677884413;6364.07720880479;5830.62100963436;5871.853203669943;5930.881382372415;5999.610025348338;6008.369121242382;6046.969765151146;6104.9139292562195;6112.807455516588;6119.776756597957;6144.2319710036245;5850.327212370632;5861.531077513874;5864.120381942572;5874.94125058744;5916.171735956884;5753.9136912945505;5809.128630705428;5810.302763668386;5890.4993075587345;5892.5285977122585;4692.882677932942;5738.081906894877;5800.8259752207505;5863.355030946678;5865.677387743715;5883.94116058834;5937.406259374028;5942.702864856654;5943.257092863392;5969.940300596939;4730.2431610942085;5665.49010589049;5726.656400615976;5768.50967667753;5791.913121303268 11:57:48 INFO - PROCESS | 1649 | |1;ext.html;462.4958794913544;526.4551189518849;527.6332948600722;538.7306346826587;553.5184389581065;31227.531587026184;40774;42594.36108458372;42653.57346426535;42696.14607707844;44291.33562996558;44704.55295447045;44775.77612111943;44813;44827;8639.65441382344;9731.124198822123;9917.305788594802;9928.205743540535;9929.907710151878;8686.739397818075;8957.731268061969;8981.461112333249;9009.954950225272;11867.643970680894;11916.40417979102;12221.877781222207;12231.571894983677;12253.326067066353;12297.262164270172;11972.760544789098;12090.33503157326;12108.878911210952;12110.212836165709;12133.090018248578;12048.759024819454;12131;12134.939325303318;12138.45376958039;12140.3322817245;41772.582274177475;42082;42177;42191.78904105491;42209.36685949713;3411.5053317268857;3466.3933811583124;3949.4668219790246;4127.484064491939;4311.1593239318345;33277.50083748746;33398.49902251468;33425.66574334274;33467.6653233467;33595.83202083974;3142.6385965614018;3361.680640339164;3401.4557670772806;3402.4896265560114;3403.0471467989005;488827;488971.11028889613;489531;490268;490697;61759;62235.37764622386;62339.830919443935;62353;62618.68690656609;67028;67162.66418667973;67244;67285.6635716828;67348.32651673518;16261.59346016359;16541.503754887373;16541.58646033835;16554.337826487048;16572.17139143038;12566.931810796003;12644.925181359687;12660.620181394574;12666.92331151844;12674.49302027927;12994.805077923838;13951;15694.231469793238;17243.56891077708;18631.161597728136;7012.439004879598;7033;7034.6482675866;7042.190148132928;7108.943154443639;898.3711402018482;900.7162743735702;925.2829057480507;1021.7445638590352;1041.099448976645;25271.368215794384;30237.395252095273;31736.682633173834;33522.832385837915;33602.66397336044;1015.9949200253951;1038.127972503101;1060.1571750458404;1090.5855774805523;1091.8198497247865;15702.9214853925;15741.921290393704;16079.276432560562;16106.953048051839;16176.838231617769;657010;662628;662919;664756;668446.657766708;51924.480755191966;58285.125723114186;58741;59058;59858.401415986154;1360.4694169273992;1376.8072469854228;1402.628303499553;1408.3591965655603;1410.8730214280763;27559.86220068887;28019.43961120807;29879.12158713037;32093.681765157446;32136.19659508531;54279.45720542902;55127.724361377936;55196.72401637966;55209;55894.720526397105;596708;598878.0056099691;837477;869077;872115.7547910232;43617.78191109151;43732.34401483917;43786.56213437952;43952.34071488994;44550.3317450232;47407.52592474031;47775;48563.27155092606;48653.5134648649;48777.2683409742;31903.202419939687;37594.43608345932;37978.62021379861;38181.23637527179;39642.60357396389;34904.47643285403;35541.22293885347;39033.2193356137;39757.80121099376;40230.798846005586;37517.2496550062;37628;37690.623093768714;38240;38325.4251186238;29968.400631986802;32910;33678.32643347168;34611.65388346185;34764;22000.669989950482;24510.142145024838;24711.62932555977;25189.62215566731;25216.49567008613 11:57:48 INFO - PROCESS | 1649 | |2;jquery.html;351.35701665951314;371.4558172277614;375.75012616609956;377.5789994156515;403.7819577428187;15207.619809504768;16003.599910002256;20238;20715.792842071576;20770.584588308226;19893.801061989376;20503.384898453034;20862.68705969408;21406.785932140672;21423.892880535575;5348.9197662035;5528.364238112608;5530.170474428827;5569.136783798518;6066.878662426749;3867.2652196082795;6009.429104235112;6109.114178444123;6113.174721412619;6117.847053823645;5881.8823623527505;6150.96924515379;6187.752489900398;6229.844253893666;6239.220097487814;6205.7517699291975;6224.657643829588;6239.532035097386;6751.122354093936;6831.2144103428245;5985.281766188042;5986.28164620244;5987.161797348374;5990.2212712347555;6005.159277701126;13047.54333598329;13203.075784695075;13345.599632011055;13374.264415457146;15840.683186336324;2295.8622482650926;2346.941326466835;2477.7866427477747;2677.250369896432;2907.5638654201825;8051.194880511961;9942.403455792602;10067.748306292327;10396.376217426978;10469.63356282534;1819.2904767140826;1880.9717854232197;1883.0961138653522;1884.406411980222;1913.062599326334;85066.73531625023;100593;115027.42486287598;136963.31518342346;140186.29906850503;11200.663980080612;11307.378094204898;11495.965363117359;11583.189176757714;11715.4142292885;14418.783718244234;14443.711125777425;14448.133112013313;14452.277386130652;14458.638534036732;8245.216704413071;8309.042222068525;8352.540610266493;8397.95801020991;8530.530820804792;6315.643179065589;8191.590420478952;8374.874376884352;8407.747767566983;8415.705450309331;4405.383246345515;4507.504174540798;4517.81928722854;4532.27483602622;4545.704419268249;1868.467486766265;1919.9712004319947;1934.6807776716967;1962.4308950404222;1972.6646470100118;990.6235630460379;990.8117457683018;991.2317953586029;1034.095166729097;1051.8632577764995;20438.89780551088;21394.35816925495;21766.45583860416;21798;21825.708300628525;910.7996240827008;917.8164367126468;954.7040417470606;979.6571200079915;1017.7557386227252;9118.726438206864;11313.773724525463;11338.943305283421;11502.194846360842;11560;572739;578934;582156;596211;596309;125697;125945.37027314988;132367;133327;136477;833.9916600834035;905.4702998745807;998.0069830518536;1022.3559157730582;1040.3965699894043;19016.714749278493;19272.903635482293;19334.226630934605;19350.90324548368;19367.225310987404;53080;53335.4666453346;53569.73215133899;54493;55249.72375138099;76700;78795;79273.60363198147;79402;84216;7637.274458926393;7834.921650783647;7905.367570594453;7906.446548741763;8048.39637027237;11244.437778111225;12033.338166400932;12036.578719744766;12424.813627795771;12455.875441245471;10302.484875756332;10312.742181445525;10709.036186743231;10775.976282253174;10845.295055821347;10443.530041148319;10457.26799124054;10652.62715804943;10666.093382062612;10692.305000174963;10603.840942385716;10647.254692171473;11040.116790656568;11043.282186657842;11086.390248536085;7754.069511658637;7809.492382995087;7881.6453259604605;7889.053313602404;7898.921010790048;7854.096778870503;9232.95383523078;9295.88449386078;10379.792404151724;10854 11:57:48 INFO - PROCESS | 1649 | |3;mootools.html;1317.2162563274849;1367.6991061966366;1369.8493165751772;1653.6609994951036;1898.686716691745;7721.382289416847;8607.74176774697;8784.209421152094;8848.29213662907;8857.202851743341;8608.096149904264;8719.389642725011;8728.956355218232;8733.170348816868;8739.344549158806;2342.8477148985303;2366.976330236693;2367.443650742074;2373.8931748071377;2377.738448770634;2333.3466629343725;2353.694019777435;2381.297517232412;2382.5473160099614;2382.857028578282;2331.5803155432013;2361.633946738258;2369.8933547990296;2377.4888398994212;2377.607694730376;2282.577723121228;2310.3646497213317;2319.5940710375703;2324;2334.568104900599;2314.5486630107134;2316.100797346177;2318.188633978111;2322.082777302956;2324.0239099578216;8706.521141337224;8712.433691810013;8763.649454021832;8780.165884240987;8804.955975220082;5133.178691409394;5306.946930530722;5321.255024296602;5344.465553444663;5348.759305831247;3629.201575653352;3651.5618125824803;3686.078480379905;3717.7583457075475;3725.329440700673;1070.9678709638724;1080.4327727942814;1085.7122862441458;1103.6027030269097;1108.9279196852178;10573.894261057369;11154.828742981952;11795.528178872835;11819.349935753617;11835.349055801928;3864.9227015459533;4400.119976004812;4467.508574056851;4622.815087396534;4631.884202894955;9173.495457749888;9178.082191780768;9180.6786762463;9186.127317904788;9198.03420640823;5202.985417843484;5205.115130427836;5208.140656791663;5214.661047031931;5221.738913054332;4340.088581397878;4499.775011249424;4696.412948381452;4846.80612775485;4888.95111048885;3677.5403074615674;3727.962720372816;3790.583035866108;3831.3678243090135;3842.884713458601;950.8193443245899;985.6008316631776;1143.7369405036889;1148.5405837664835;1153.9076873850072;851.2721623012359;892.7857314244535;904.2449554621965;906.5150144672568;917.9632814687471;4439.844605438859;5737.196792449061;6027.939720602738;6115.296740874856;6188.0717892315615;423.6674210744551;424.1114864359163;437.9846705365297;438.1368703653797;453.6302913125792;3849.2109117630816;3942.6845852331744;4054.3310353791885;4100.227790432749;4149.294619914622;9995.350302230332;10613.25707200504;10764.192685548458;10775.6228532001;10812.080973117216;10522;10617.878509545;10725.946370268099;10728.892711072946;10765.892341076646;8082.9191708083345;8270;8278.379121565908;8279.006519217612;8286.544240066854;5714.857128571819;5798.768049277982;5899.643082091057;5903.498202652822;5983.640981541122;8404.663813447394;9927.40435573868;10010.44942528168;10016.148627366756;10037.949810250902;8065.919340806517;10042;10059.547320370748;10216.131628811338;10217.386956782617;5778.768849245983;6979.127609048869;7179.138503379419;7499.5875226863045;7669.654865531176;7193.640317983975;8112.148224436349;8487.957560212159;8490.27832634233;8504.979402471743;7415.629218539159;7472.0286362772495;7510.624468776648;7566.1298950621385;7638.8090297743;5839.299284085937;6189.783357582463;7160.928390716234;7344.265573442613;7443.739469118555;6917.861642767217;6986.790396288119;7074.752383666547;7083.858322833617;7102.857942841218;5032.521910418504;5050.065737838511;5103.693778373309;5105.489451055013;5183.611229157753;4955.925661115014;5035.16919708244;5094.414142373675;5260.789568417221;5265.262863199078 11:57:48 INFO - PROCESS | 1649 | |4;prototype.html;250.44901217321893;303.2721468475658;309.02194554235837;313.7270574600098;316.78775220602193;5242.457408157045;5275.472452754732;5290.973545132274;5295.173165257985;5295.258663787064;4171.228322760291;5057.84826455206;5166.509181627749;5242.501962313584;5251.763670634821;3101.302207003423;3505.684488396044;3956.960430395688;3981.7610943343493;3985.382265748799;3442.8967130985984;3987.8006099695044;3992.980035099835;4006.91813210433;4014.9397759033636;3976.76139431635;3984.78464068459;3986.5415477220054;3999.560048394674;4010.3783913493307;3832.4478586172645;3973.9801300993454;4002.959970400317;4015.879523614267;4027.1945610877797;2709.972900270992;2920.678725340211;3273.7053665170247;3449.87925422609;3505.333986542574;4609.608183304423;5016.871203979075;5031.119554078058;5088.745562721849;5092.236164575306;3797.6012518685693;3798.4682144499793;4544.318352247156;4586.54134586542;4600.5629465200755;4742.814296425893;5318.867028324323;5393.811216607439;5542.780588270574;5548.750306236229;3130.8591113399953;3523.4362501999813;3806.695464362844;3841.654251117413;3842.3275926712713;5456.781728730846;5594.300712410949;5668.093105103164;5700.37295897451;5724.227229324029;3089.2431354317887;3634.945475817865;3901.044244160199;3908.7068469864876;3920.5883382245024;3317.6350601433824;3521.049316684481;3786.3563194257044;3796.5064541609413;3999.320115580358;3769.6418840210135;3907.2258696325357;4254.808533615933;4512.962018735643;4578.5650363215445;4287.2711639021445;4564.42944631921;5450.91823622646;5455.727213639302;5472.835814925558;1344.4823742859069;1345.4954392102961;1363.352407606379;1377.4778869349425;1383.8270216222973;763.5189830406808;768.7194174126404;790.8260182759784;811.736185739637;850.4514588090647;3643.7449378543247;3918.2359439909515;3954.9011274718364;4229.534751177368;4282.522529727239;3813.5995720448955;4661.743604101739;4661.743604101739;4727.659808394867;4737.620990320766;3634.618365071682;4136.482939632546;4281.443412356436;4300.6989510734575;4360.847370342023;2263.7849404306567;2470.6047032475076;2484.9378765530646;2496.613024981131;2502.810041213938;4216.557261487561;4331.45856767904;4560.566746159109;4822.493638168012;4853.713765851981;4206.558311377261;4292.531794218337;4772.873767682174;4794.513700752777;4803.670219943325;4081.8367265309057;4617.58441740245;5288.471152884681;5934.8813023739285;5954.910676339945;4452.710573812692;4979.6763210392655;5730.656160630376;5737.74180161902;5750.223719797774;4418.09429067034;5446.58388818902;5611.3266408031295;5649.2373529574625;5677.523843800559;4555.33947577606;4932.556069953722;4977.178765503796;5895.322037965689;5937;2888.9855550722114;3327.8835240766457;3662.340778659867;3744.9625503744614;3867.3232184368185;4728.503507131702;4771.618270538418;5244.475552444725;5772.480476757112;5774.939282180451;4784.545468180518;5400.297961265011;5506.669599824023;5548.585110796721;5554.305711786027;5101.260317254051;5256.763445644879;5536.280283563111;5536.91694624589;5545.195946587803;5142.665726727751;5451.672899626035;5470.343558772973;5507.394186639386;5539.639923404966;4446.755428451466;4960.685418364209;5542.445755424425;5542.61201715876;5570.5265052470995;4347.739135651871;4482.394876691605;4962.727050012284;5424.728763561885;5500.367457742411 11:57:48 INFO - PROCESS | 1649 | |5;yui.html;640.1965533255764;726.2193142371951;761.1361105145658;781.238292664652;808.7452452477471;14596.70806583869;15853.286602102904;15868.444604438848;15926.442574509909;15937.68124637508;14628.4880029199;14777.852221477782;14787.556373308791;14797.92601036996;14819.851801481982;678.4165617568887;698.5564166754123;819.0253598218117;824.2828738997081;850.3239924260197;832.4589017138878;834.5493433545909;847.6439895243982;849.0575461238021;851.1445996773234;906.8639704044413;908.3505293714992;909.6088681866795;909.6270529083077;911.2209061252632;715.2525610736792;870.3385427075405;902.8826252587154;903.1104362203224;903.6566104880136;659.4757168051362;662.8541720821412;663.6151032401195;665.1785045468843;667.1893649216225;14695.412183512646;14733.04235224707;14753.483628072965;14766.187859667714;14796.852031479762;13566.864331356757;13768;13828.446862125504;13859.514916977858;14028.789568156486;12378.938105309506;12403.689907752288;12441;12463.501459941592;12753;4446.9555304447185;4509.503954564995;4541.84103556374;4704.482506924235;4710.034442939189;108991.99999999921;109003;109423;109672;112591;9264.305177111744;9401.764955876159;9419.199368053793;9524.095210955087;9553.952230238943;16939.322427103023;17187.484375468757;17299.82700172982;17415.47753567395;17470.563235919202;7639.197884222188;7732.503610188903;7801.29788319054;7813.413993950477;7887;4479.641628669699;4480.641548676098;4501.279795232747;4550.795214215368;4558.703684260512;3313.1551454378978;3365.3269346130874;3415.0949998250485;3417.9145521362166;3421.9315613687586;627.6077451592755;631.210986267166;637.1175921348872;641.0928536121429;643.5655932245716;518.99740501298;545.8007827143064;552.7043031977872;560.8289471711167;565.7227958300404;8552.529610871461;8733.563321833883;8886.066962968924;8929.73210803677;8933.330000250007;589.3399392680226;628.9245290565161;634.3888307916471;635.2948227467507;650.9544331896722;3540.5751309842985;4332.956670433318;4352.020795321027;4386.342048692722;4477.944122141927;147916;150597;151282;153369;157591;86071;115205;120716;124719.75280247263;126982.36508817397;97165;98499.5075024649;107281.46359268154;109644.4517777406;111631.44184279189;9237.861432078658;9734.221262298857;9767.36512126738;9779.168770654574;9794.314397992071;47308.763456182496;47499.81250468766;52721.20918186154;52953.7352313236;53174.40476785703;79329.60335198288;83390;83924.50943632046;84001;85536.14463855281;12759.93620031894;12935.736246157074;13084.803727943898;13179.472821087049;13585;14795.556133316019;14820.777688334469;15109.655582125422;15355;15357;13621.79567306511;13917.164970101827;13922.164670119826;14360.85639143637;14399.42402303938;12503.624891252914;12763.234205947672;12893.613191604267;12920.160189587647;13178.472861085449;13436.798448023092;13472.932635336761;13542.119762215818;13961.860381396462;14015.299235038412;11417.200795944536;11427.542898283975;11449.313041217554;11598;12020.398980051137;9524.999875013023;10147.949260253652;10777.13782897382;11395.544178232778;11588.884111159117 11:57:48 INFO - PROCESS | 1649 | __end_tp_report 11:57:48 INFO - PROCESS | 1649 | __start_cc_report 11:57:48 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1177 11:57:48 INFO - PROCESS | 1649 | __end_cc_report 11:57:48 INFO - PROCESS | 1649 | __startTimestamp1471028268905__endTimestamp 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | ------- Summary: start ------- 11:57:48 INFO - PROCESS | 1649 | Number of tests: 6 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5591.35 Median:5768.51 stddev:640.05 (11.1%) stddev-sans-first:612.60 11:57:48 INFO - PROCESS | 1649 | Values: 3146.1 3266.0 3308.8 3326.7 3367.6 5445.5 5499.1 5538.5 5549.1 5601.6 5498.9 5555.1 5567.6 5568.7 5568.8 5033.5 5066.2 5091.9 5112.3 5173.0 5045.6 5061.6 5099.5 5179.7 5184.2 5169.6 5173.6 5181.7 5193.2 5224.7 4368.2 4963.9 5162.0 5165.5 5175.2 5063.9 5068.9 5080.7 5126.7 5141.1 4528.4 5434.9 5446.9 5497.0 5561.8 5948.0 5976.9 5980.9 5982.5 6014.8 6105.4 6172.2 6200.2 6205.3 6218.0 5521.5 5585.2 5587.0 5645.4 5715.5 6278.4 6295.2 6316.1 6320.0 6345.9 5952.8 5968.6 5969.5 6016.2 6016.9 5947.2 6000.1 6046.1 6130.5 6143.7 6030.3 6061.9 6083.5 6084.5 6126.2 6036.7 6079.9 6085.5 6106.3 6131.9 4791.7 4852.2 4867.3 4897.9 4906.4 4340.2 4365.9 4368.5 4405.4 4425.9 5508.3 5514.9 5522.2 5533.2 5576.8 5972.7 6035.5 6061.5 6139.9 6162.1 5444.0 5490.3 5509.9 5518.9 5554.1 5301.0 5304.5 5311.3 5324.4 5349.2 5594.8 6144.9 6193.7 6309.4 6321.8 6122.5 6168.4 6181.4 6189.6 6197.9 6332.4 6348.9 6353.0 6362.7 6364.1 5830.6 5871.9 5930.9 5999.6 6008.4 6047.0 6104.9 6112.8 6119.8 6144.2 5850.3 5861.5 5864.1 5874.9 5916.2 5753.9 5809.1 5810.3 5890.5 5892.5 4692.9 5738.1 5800.8 5863.4 5865.7 5883.9 5937.4 5942.7 5943.3 5969.9 4730.2 5665.5 5726.7 5768.5 5791.9 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:76884.81 Median:38282.71 stddev:174339.69 (455.4%) stddev-sans-first:174734.33 11:57:48 INFO - PROCESS | 1649 | Values: 462.5 526.5 527.6 538.7 553.5 31227.5 40774.0 42594.4 42653.6 42696.1 44291.3 44704.6 44775.8 44813.0 44827.0 8639.7 9731.1 9917.3 9928.2 9929.9 8686.7 8957.7 8981.5 9010.0 11867.6 11916.4 12221.9 12231.6 12253.3 12297.3 11972.8 12090.3 12108.9 12110.2 12133.1 12048.8 12131.0 12134.9 12138.5 12140.3 41772.6 42082.0 42177.0 42191.8 42209.4 3411.5 3466.4 3949.5 4127.5 4311.2 33277.5 33398.5 33425.7 33467.7 33595.8 3142.6 3361.7 3401.5 3402.5 3403.0 488827.0 488971.1 489531.0 490268.0 490697.0 61759.0 62235.4 62339.8 62353.0 62618.7 67028.0 67162.7 67244.0 67285.7 67348.3 16261.6 16541.5 16541.6 16554.3 16572.2 12566.9 12644.9 12660.6 12666.9 12674.5 12994.8 13951.0 15694.2 17243.6 18631.2 7012.4 7033.0 7034.6 7042.2 7108.9 898.4 900.7 925.3 1021.7 1041.1 25271.4 30237.4 31736.7 33522.8 33602.7 1016.0 1038.1 1060.2 1090.6 1091.8 15702.9 15741.9 16079.3 16107.0 16176.8 657010.0 662628.0 662919.0 664756.0 668446.7 51924.5 58285.1 58741.0 59058.0 59858.4 1360.5 1376.8 1402.6 1408.4 1410.9 27559.9 28019.4 29879.1 32093.7 32136.2 54279.5 55127.7 55196.7 55209.0 55894.7 596708.0 598878.0 837477.0 869077.0 872115.8 43617.8 43732.3 43786.6 43952.3 44550.3 47407.5 47775.0 48563.3 48653.5 48777.3 31903.2 37594.4 37978.6 38181.2 39642.6 34904.5 35541.2 39033.2 39757.8 40230.8 37517.2 37628.0 37690.6 38240.0 38325.4 29968.4 32910.0 33678.3 34611.7 34764.0 22000.7 24510.1 24711.6 25189.6 25216.5 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34316.26 Median:2577.52 stddev:98001.92 (3802.2%) stddev-sans-first:98243.66 11:57:48 INFO - PROCESS | 1649 | Values: 351.4 371.5 375.8 377.6 403.8 15207.6 16003.6 20238.0 20715.8 20770.6 19893.8 20503.4 20862.7 21406.8 21423.9 5348.9 5528.4 5530.2 5569.1 6066.9 3867.3 6009.4 6109.1 6113.2 6117.8 5881.9 6151.0 6187.8 6229.8 6239.2 6205.8 6224.7 6239.5 6751.1 6831.2 5985.3 5986.3 5987.2 5990.2 6005.2 13047.5 13203.1 13345.6 13374.3 15840.7 2295.9 2346.9 2477.8 2677.3 2907.6 8051.2 9942.4 10067.7 10396.4 10469.6 1819.3 1881.0 1883.1 1884.4 1913.1 85066.7 100593.0 115027.4 136963.3 140186.3 11200.7 11307.4 11496.0 11583.2 11715.4 14418.8 14443.7 14448.1 14452.3 14458.6 8245.2 8309.0 8352.5 8398.0 8530.5 6315.6 8191.6 8374.9 8407.7 8415.7 4405.4 4507.5 4517.8 4532.3 4545.7 1868.5 1920.0 1934.7 1962.4 1972.7 990.6 990.8 991.2 1034.1 1051.9 20438.9 21394.4 21766.5 21798.0 21825.7 910.8 917.8 954.7 979.7 1017.8 9118.7 11313.8 11338.9 11502.2 11560.0 572739.0 578934.0 582156.0 596211.0 596309.0 125697.0 125945.4 132367.0 133327.0 136477.0 834.0 905.5 998.0 1022.4 1040.4 19016.7 19272.9 19334.2 19350.9 19367.2 53080.0 53335.5 53569.7 54493.0 55249.7 76700.0 78795.0 79273.6 79402.0 84216.0 7637.3 7834.9 7905.4 7906.4 8048.4 11244.4 12033.3 12036.6 12424.8 12455.9 10302.5 10312.7 10709.0 10776.0 10845.3 10443.5 10457.3 10652.6 10666.1 10692.3 10603.8 10647.3 11040.1 11043.3 11086.4 7754.1 7809.5 7881.6 7889.1 7898.9 7854.1 9233.0 9295.9 10379.8 10854.0 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5600.80 Median:4994.22 stddev:3160.22 (63.3%) stddev-sans-first:3152.68 11:57:48 INFO - PROCESS | 1649 | Values: 1317.2 1367.7 1369.8 1653.7 1898.7 7721.4 8607.7 8784.2 8848.3 8857.2 8608.1 8719.4 8729.0 8733.2 8739.3 2342.8 2367.0 2367.4 2373.9 2377.7 2333.3 2353.7 2381.3 2382.5 2382.9 2331.6 2361.6 2369.9 2377.5 2377.6 2282.6 2310.4 2319.6 2324.0 2334.6 2314.5 2316.1 2318.2 2322.1 2324.0 8706.5 8712.4 8763.6 8780.2 8805.0 5133.2 5306.9 5321.3 5344.5 5348.8 3629.2 3651.6 3686.1 3717.8 3725.3 1071.0 1080.4 1085.7 1103.6 1108.9 10573.9 11154.8 11795.5 11819.3 11835.3 3864.9 4400.1 4467.5 4622.8 4631.9 9173.5 9178.1 9180.7 9186.1 9198.0 5203.0 5205.1 5208.1 5214.7 5221.7 4340.1 4499.8 4696.4 4846.8 4889.0 3677.5 3728.0 3790.6 3831.4 3842.9 950.8 985.6 1143.7 1148.5 1153.9 851.3 892.8 904.2 906.5 918.0 4439.8 5737.2 6027.9 6115.3 6188.1 423.7 424.1 438.0 438.1 453.6 3849.2 3942.7 4054.3 4100.2 4149.3 9995.4 10613.3 10764.2 10775.6 10812.1 10522.0 10617.9 10725.9 10728.9 10765.9 8082.9 8270.0 8278.4 8279.0 8286.5 5714.9 5798.8 5899.6 5903.5 5983.6 8404.7 9927.4 10010.4 10016.1 10037.9 8065.9 10042.0 10059.5 10216.1 10217.4 5778.8 6979.1 7179.1 7499.6 7669.7 7193.6 8112.1 8488.0 8490.3 8505.0 7415.6 7472.0 7510.6 7566.1 7638.8 5839.3 6189.8 7160.9 7344.3 7443.7 6917.9 6986.8 7074.8 7083.9 7102.9 5032.5 5050.1 5103.7 5105.5 5183.6 4955.9 5035.2 5094.4 5260.8 5265.3 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4231.42 Median:4557.95 stddev:1327.96 (29.1%) stddev-sans-first:1297.63 11:57:48 INFO - PROCESS | 1649 | Values: 250.4 303.3 309.0 313.7 316.8 5242.5 5275.5 5291.0 5295.2 5295.3 4171.2 5057.8 5166.5 5242.5 5251.8 3101.3 3505.7 3957.0 3981.8 3985.4 3442.9 3987.8 3993.0 4006.9 4014.9 3976.8 3984.8 3986.5 3999.6 4010.4 3832.4 3974.0 4003.0 4015.9 4027.2 2710.0 2920.7 3273.7 3449.9 3505.3 4609.6 5016.9 5031.1 5088.7 5092.2 3797.6 3798.5 4544.3 4586.5 4600.6 4742.8 5318.9 5393.8 5542.8 5548.8 3130.9 3523.4 3806.7 3841.7 3842.3 5456.8 5594.3 5668.1 5700.4 5724.2 3089.2 3634.9 3901.0 3908.7 3920.6 3317.6 3521.0 3786.4 3796.5 3999.3 3769.6 3907.2 4254.8 4513.0 4578.6 4287.3 4564.4 5450.9 5455.7 5472.8 1344.5 1345.5 1363.4 1377.5 1383.8 763.5 768.7 790.8 811.7 850.5 3643.7 3918.2 3954.9 4229.5 4282.5 3813.6 4661.7 4661.7 4727.7 4737.6 3634.6 4136.5 4281.4 4300.7 4360.8 2263.8 2470.6 2484.9 2496.6 2502.8 4216.6 4331.5 4560.6 4822.5 4853.7 4206.6 4292.5 4772.9 4794.5 4803.7 4081.8 4617.6 5288.5 5934.9 5954.9 4452.7 4979.7 5730.7 5737.7 5750.2 4418.1 5446.6 5611.3 5649.2 5677.5 4555.3 4932.6 4977.2 5895.3 5937.0 2889.0 3327.9 3662.3 3745.0 3867.3 4728.5 4771.6 5244.5 5772.5 5774.9 4784.5 5400.3 5506.7 5548.6 5554.3 5101.3 5256.8 5536.3 5536.9 5545.2 5142.7 5451.7 5470.3 5507.4 5539.6 4446.8 4960.7 5542.4 5542.6 5570.5 4347.7 4482.4 4962.7 5424.7 5500.4 11:57:48 INFO - PROCESS | 1649 | 11:57:48 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:23732.73 Median:4462.45 stddev:38045.29 (852.6%) stddev-sans-first:38112.50 11:57:48 INFO - PROCESS | 1649 | Values: 640.2 726.2 761.1 781.2 808.7 14596.7 15853.3 15868.4 15926.4 15937.7 14628.5 14777.9 14787.6 14797.9 14819.9 678.4 698.6 819.0 824.3 850.3 832.5 834.5 847.6 849.1 851.1 906.9 908.4 909.6 909.6 911.2 715.3 870.3 902.9 903.1 903.7 659.5 662.9 663.6 665.2 667.2 14695.4 14733.0 14753.5 14766.2 14796.9 13566.9 13768.0 13828.4 13859.5 14028.8 12378.9 12403.7 12441.0 12463.5 12753.0 4447.0 4509.5 4541.8 4704.5 4710.0 108992.0 109003.0 109423.0 109672.0 112591.0 9264.3 9401.8 9419.2 9524.1 9554.0 16939.3 17187.5 17299.8 17415.5 17470.6 7639.2 7732.5 7801.3 7813.4 7887.0 4479.6 4480.6 4501.3 4550.8 4558.7 3313.2 3365.3 3415.1 3417.9 3421.9 627.6 631.2 637.1 641.1 643.6 519.0 545.8 552.7 560.8 565.7 8552.5 8733.6 8886.1 8929.7 8933.3 589.3 628.9 634.4 635.3 651.0 3540.6 4333.0 4352.0 4386.3 4477.9 147916.0 150597.0 151282.0 153369.0 157591.0 86071.0 115205.0 120716.0 124719.8 126982.4 97165.0 98499.5 107281.5 109644.5 111631.4 9237.9 9734.2 9767.4 9779.2 9794.3 47308.8 47499.8 52721.2 52953.7 53174.4 79329.6 83390.0 83924.5 84001.0 85536.1 12759.9 12935.7 13084.8 13179.5 13585.0 14795.6 14820.8 15109.7 15355.0 15357.0 13621.8 13917.2 13922.2 14360.9 14399.4 12503.6 12763.2 12893.6 12920.2 13178.5 13436.8 13472.9 13542.1 13961.9 14015.3 11417.2 11427.5 11449.3 11598.0 12020.4 9525.0 10147.9 10777.1 11395.5 11588.9 11:57:48 INFO - PROCESS | 1649 | -------- Summary: end -------- 11:57:48 INFO - PROCESS | 1649 | 11:57:49 INFO - TEST-INFO | 1649: exit 0 11:57:49 INFO - TEST-OK | dromaeo_css | took 1117434ms 11:57:49 INFO - TEST-START | kraken 11:57:49 INFO - operating with platform_type : mac_ 11:57:49 INFO - Initialising browser for kraken test... 11:57:49 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpnGcSL_/profile http://localhost:49263/getInfo.html) 11:57:51 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:57:51 INFO - PROCESS | 1656 | colorDepth:24 11:57:51 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:57:51 INFO - PROCESS | 1656 | __metrics 11:57:51 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:57:51 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:57:52 INFO - TEST-INFO | 1656: exit 0 11:57:52 INFO - Browser initialized. 11:57:52 INFO - Running cycle 1/1 for kraken test... 11:57:52 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpnGcSL_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:04 INFO - PROCESS | 1657 | RSS: Main: 243699712 11:58:04 INFO - PROCESS | 1657 | 11:58:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 11:58:08 INFO - PROCESS | 1657 | RSS: Main: 293167104 11:58:08 INFO - PROCESS | 1657 | 11:58:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 11:58:11 INFO - PROCESS | 1657 | RSS: Main: 301158400 11:58:11 INFO - PROCESS | 1657 | 11:58:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 11:58:15 INFO - PROCESS | 1657 | RSS: Main: 394973184 11:58:15 INFO - PROCESS | 1657 | 11:58:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 11:58:18 INFO - PROCESS | 1657 | RSS: Main: 297369600 11:58:18 INFO - PROCESS | 1657 | 11:58:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:58:20 INFO - PROCESS | 1657 | RSS: Main: 302858240 11:58:20 INFO - PROCESS | 1657 | 11:58:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 11:58:24 INFO - PROCESS | 1657 | RSS: Main: 340320256 11:58:24 INFO - PROCESS | 1657 | 11:58:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 11:58:27 INFO - PROCESS | 1657 | RSS: Main: 342081536 11:58:27 INFO - PROCESS | 1657 | 11:58:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 11:58:31 INFO - PROCESS | 1657 | RSS: Main: 341880832 11:58:31 INFO - PROCESS | 1657 | 11:58:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:58:33 INFO - PROCESS | 1657 | RSS: Main: 313999360 11:58:33 INFO - PROCESS | 1657 | 11:58:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:58:36 INFO - PROCESS | 1657 | RSS: Main: 318914560 11:58:36 INFO - PROCESS | 1657 | 11:58:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:58:39 INFO - PROCESS | 1657 | RSS: Main: 326995968 11:58:39 INFO - PROCESS | 1657 | 11:58:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:58:42 INFO - PROCESS | 1657 | RSS: Main: 344567808 11:58:42 INFO - PROCESS | 1657 | 11:58:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:58:46 INFO - PROCESS | 1657 | RSS: Main: 338223104 11:58:46 INFO - PROCESS | 1657 | 11:58:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 11:58:48 INFO - PROCESS | 1657 | RSS: Main: 382017536 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | __start_tp_report 11:58:48 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 11:58:48 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 11:58:48 INFO - PROCESS | 1657 | |i|pagename|runs| 11:58:48 INFO - PROCESS | 1657 | |0;ai-astar;103;113;114;112;112;113;113;113;112;113 11:58:48 INFO - PROCESS | 1657 | |1;audio-beat-detection;150;141;140;163;106;133;127;109;159;104 11:58:48 INFO - PROCESS | 1657 | |2;audio-dft;185;185;185;183;186;182;184;184;185;185 11:58:48 INFO - PROCESS | 1657 | |3;audio-fft;77;58;62;86;59;57;66;63;83;57 11:58:48 INFO - PROCESS | 1657 | |4;audio-oscillator;84;69;103;72;70;68;97;70;68;96 11:58:48 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;89;74;125;133;96;89;87;87;85 11:58:48 INFO - PROCESS | 1657 | |6;imaging-darkroom;92;93;93;94;93;94;94;94;94;94 11:58:48 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;104;134;135;86;85;86;84;86;84 11:58:48 INFO - PROCESS | 1657 | |8;json-parse-financial;54;54;52;54;53;53;52;53;54;54 11:58:48 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;67;72;75;74;78;71;73;75;74;72 11:58:48 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;86;87;85;84;86;86;85;86;81;104 11:58:48 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;144;148;148;144;142;151;124;154;146;146 11:58:48 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;168;165;166;168;166;167;165;164;163;167 11:58:48 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;66;66;66;66;68;64;66;66;66 11:58:48 INFO - PROCESS | 1657 | __end_tp_report 11:58:48 INFO - PROCESS | 1657 | __start_cc_report 11:58:48 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2081 11:58:48 INFO - PROCESS | 1657 | __end_cc_report 11:58:48 INFO - PROCESS | 1657 | __startTimestamp1471028328021__endTimestamp 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | ------- Summary: start ------- 11:58:48 INFO - PROCESS | 1657 | Number of tests: 14 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.16 (2.8%) stddev-sans-first:0.67 11:58:48 INFO - PROCESS | 1657 | Values: 103.0 113.0 114.0 112.0 112.0 113.0 113.0 113.0 112.0 113.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:140.50 stddev:21.50 (15.3%) stddev-sans-first:21.93 11:58:48 INFO - PROCESS | 1657 | Values: 150.0 141.0 140.0 163.0 106.0 133.0 127.0 109.0 159.0 104.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.17 (0.6%) stddev-sans-first:1.22 11:58:48 INFO - PROCESS | 1657 | Values: 185.0 185.0 185.0 183.0 186.0 182.0 184.0 184.0 185.0 185.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:66.80 Median:64.50 stddev:11.07 (17.2%) stddev-sans-first:11.11 11:58:48 INFO - PROCESS | 1657 | Values: 77.0 58.0 62.0 86.0 59.0 57.0 66.0 63.0 83.0 57.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:71.00 stddev:13.99 (19.7%) stddev-sans-first:14.75 11:58:48 INFO - PROCESS | 1657 | Values: 84.0 69.0 103.0 72.0 70.0 68.0 97.0 70.0 68.0 96.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:88.00 stddev:18.62 (21.2%) stddev-sans-first:19.61 11:58:48 INFO - PROCESS | 1657 | Values: 89.0 89.0 74.0 125.0 133.0 96.0 89.0 87.0 87.0 85.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.71 (0.8%) stddev-sans-first:0.50 11:58:48 INFO - PROCESS | 1657 | Values: 92.0 93.0 93.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:85.50 stddev:20.62 (24.1%) stddev-sans-first:21.49 11:58:48 INFO - PROCESS | 1657 | Values: 86.0 104.0 134.0 135.0 86.0 85.0 86.0 84.0 86.0 84.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 11:58:48 INFO - PROCESS | 1657 | Values: 54.0 54.0 52.0 54.0 53.0 53.0 52.0 53.0 54.0 54.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:74.00 stddev:2.92 (4.0%) stddev-sans-first:2.11 11:58:48 INFO - PROCESS | 1657 | Values: 67.0 72.0 75.0 74.0 78.0 71.0 73.0 75.0 74.0 72.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:86.00 stddev:6.20 (7.2%) stddev-sans-first:6.57 11:58:48 INFO - PROCESS | 1657 | Values: 86.0 87.0 85.0 84.0 86.0 86.0 85.0 86.0 81.0 104.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:147.00 stddev:8.08 (5.5%) stddev-sans-first:8.57 11:58:48 INFO - PROCESS | 1657 | Values: 144.0 148.0 148.0 144.0 142.0 151.0 124.0 154.0 146.0 146.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:166.50 stddev:1.66 (1.0%) stddev-sans-first:1.58 11:58:48 INFO - PROCESS | 1657 | Values: 168.0 165.0 166.0 168.0 166.0 167.0 165.0 164.0 163.0 167.0 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 11:58:48 INFO - PROCESS | 1657 | Values: 67.0 66.0 66.0 66.0 66.0 68.0 64.0 66.0 66.0 66.0 11:58:48 INFO - PROCESS | 1657 | -------- Summary: end -------- 11:58:48 INFO - PROCESS | 1657 | 11:58:48 INFO - TEST-INFO | 1657: exit 0 11:58:48 INFO - TEST-OK | kraken | took 59373ms 11:58:48 INFO - SUITE-END | took 1176s 11:58:49 INFO - Completed test suite (00:19:37) 11:58:49 INFO - javascript benchmark 11:58:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.056183145057, 3266.00386882001, 3308.768386212965, 3326.7025859914625, 3367.612724536679, 5445.482679145475, 5499.147632117023, 5538.529225015868, 5549.112142057266, 5601.551875849932, 5498.917516237259, 5555.111182210847, 5567.554595632349, 5568.693721845297, 5568.777248910039, 5033.496650334965, 5066.18940969445, 5091.9236211456855, 5112.284280200774, 5173.042987047389, 5045.596352291808, 5061.620378471611, 5099.541041306282, 5179.663321884065, 5184.2223666450145, 5169.586433085363, 5173.6119791015635, 5181.715005674687, 5193.195054766498, 5224.712640804754, 4368.191884501377, 4963.854472393483, 5161.993411284801, 5165.483451654842, 5175.223716442526, 5063.949360506384, 5068.873278168038, 5080.695158290478, 5126.666766660155, 5141.126008578551, 4528.366028755977, 5434.864128396782, 5446.8910621787345, 5497.0, 5561.805336813192, 5948.0, 5976.940230597682, 5980.8504787380225, 5982.461578457916, 6014.8496287592725, 6105.389461053859, 6172.1667574877265, 6200.162977997958, 6205.317415084337, 6218.0, 5521.503064724154, 5585.1901474286005, 5587.0, 5645.379008309083, 5715.485606295411, 6278.372162783685, 6295.213098362705, 6316.115743795872, 6319.96840015797, 6345.873082538324, 5952.791652292149, 5968.552358573038, 5969.492593129633, 6016.2479690038745, 6016.879662406727, 5947.197128387612, 6000.0999850022845, 6046.09308603713, 6130.478909292671, 6143.662098584531, 6030.27636683592, 6061.909071363933, 6083.452489275986, 6084.5132389408745, 6126.23422072241, 6036.698165091728, 6079.908801367983, 6085.5435842312, 6106.267247930276, 6131.877362452726, 4791.664583479123, 4852.2479015752615, 4867.294242334841, 4897.877553061202, 4906.435759887588, 4340.153670034376, 4365.869023929287, 4368.541303163186, 4405.361222622766, 4425.867223983286, 5508.256385388001, 5514.889702205934, 5522.22688823565, 5533.22534845122, 5576.804811831566, 5972.731227094791, 6035.486983606443, 6061.454469097803, 6139.877202456015, 6162.137300777895, 5443.972780136074, 5490.286262785892, 5509.925564514881, 5518.91721624176, 5554.111342185231, 5301.019311427398, 5304.549113325334, 5311.256424100629, 5324.440933701983, 5349.170878513836, 5594.832155035356, 6144.907826382697, 6193.690315484298, 6309.432151106423, 6321.841953951004, 6122.479589234965, 6168.414000670109, 6181.443670069715, 6189.597676151036, 6197.938020619917, 6332.430081292706, 6348.93651063502, 6352.968235158794, 6362.713677884413, 6364.07720880479, 5830.62100963436, 5871.853203669943, 5930.881382372415, 5999.610025348338, 6008.369121242382, 6046.969765151146, 6104.9139292562195, 6112.807455516588, 6119.776756597957, 6144.2319710036245, 5850.327212370632, 5861.531077513874, 5864.120381942572, 5874.94125058744, 5916.171735956884, 5753.9136912945505, 5809.128630705428, 5810.302763668386, 5890.4993075587345, 5892.5285977122585, 4692.882677932942, 5738.081906894877, 5800.8259752207505, 5863.355030946678, 5865.677387743715, 5883.94116058834, 5937.406259374028, 5942.702864856654, 5943.257092863392, 5969.940300596939, 4730.2431610942085, 5665.49010589049, 5726.656400615976, 5768.50967667753, 5791.913121303268], "value": 5550.839136474447, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.4958794913544, 526.4551189518849, 527.6332948600722, 538.7306346826587, 553.5184389581065, 31227.531587026184, 40774.0, 42594.36108458372, 42653.57346426535, 42696.14607707844, 44291.33562996558, 44704.55295447045, 44775.77612111943, 44813.0, 44827.0, 8639.65441382344, 9731.124198822123, 9917.305788594802, 9928.205743540535, 9929.907710151878, 8686.739397818075, 8957.731268061969, 8981.461112333249, 9009.954950225272, 11867.643970680894, 11916.40417979102, 12221.877781222207, 12231.571894983677, 12253.326067066353, 12297.262164270172, 11972.760544789098, 12090.33503157326, 12108.878911210952, 12110.212836165709, 12133.090018248578, 12048.759024819454, 12131.0, 12134.939325303318, 12138.45376958039, 12140.3322817245, 41772.582274177475, 42082.0, 42177.0, 42191.78904105491, 42209.36685949713, 3411.5053317268857, 3466.3933811583124, 3949.4668219790246, 4127.484064491939, 4311.1593239318345, 33277.50083748746, 33398.49902251468, 33425.66574334274, 33467.6653233467, 33595.83202083974, 3142.6385965614018, 3361.680640339164, 3401.4557670772806, 3402.4896265560114, 3403.0471467989005, 488827.0, 488971.11028889613, 489531.0, 490268.0, 490697.0, 61759.0, 62235.37764622386, 62339.830919443935, 62353.0, 62618.68690656609, 67028.0, 67162.66418667973, 67244.0, 67285.6635716828, 67348.32651673518, 16261.59346016359, 16541.503754887373, 16541.58646033835, 16554.337826487048, 16572.17139143038, 12566.931810796003, 12644.925181359687, 12660.620181394574, 12666.92331151844, 12674.49302027927, 12994.805077923838, 13951.0, 15694.231469793238, 17243.56891077708, 18631.161597728136, 7012.439004879598, 7033.0, 7034.6482675866, 7042.190148132928, 7108.943154443639, 898.3711402018482, 900.7162743735702, 925.2829057480507, 1021.7445638590352, 1041.099448976645, 25271.368215794384, 30237.395252095273, 31736.682633173834, 33522.832385837915, 33602.66397336044, 1015.9949200253951, 1038.127972503101, 1060.1571750458404, 1090.5855774805523, 1091.8198497247865, 15702.9214853925, 15741.921290393704, 16079.276432560562, 16106.953048051839, 16176.838231617769, 657010.0, 662628.0, 662919.0, 664756.0, 668446.657766708, 51924.480755191966, 58285.125723114186, 58741.0, 59058.0, 59858.401415986154, 1360.4694169273992, 1376.8072469854228, 1402.628303499553, 1408.3591965655603, 1410.8730214280763, 27559.86220068887, 28019.43961120807, 29879.12158713037, 32093.681765157446, 32136.19659508531, 54279.45720542902, 55127.724361377936, 55196.72401637966, 55209.0, 55894.720526397105, 596708.0, 598878.0056099691, 837477.0, 869077.0, 872115.7547910232, 43617.78191109151, 43732.34401483917, 43786.56213437952, 43952.34071488994, 44550.3317450232, 47407.52592474031, 47775.0, 48563.27155092606, 48653.5134648649, 48777.2683409742, 31903.202419939687, 37594.43608345932, 37978.62021379861, 38181.23637527179, 39642.60357396389, 34904.47643285403, 35541.22293885347, 39033.2193356137, 39757.80121099376, 40230.798846005586, 37517.2496550062, 37628.0, 37690.623093768714, 38240.0, 38325.4251186238, 29968.400631986802, 32910.0, 33678.32643347168, 34611.65388346185, 34764.0, 22000.669989950482, 24510.142145024838, 24711.62932555977, 25189.62215566731, 25216.49567008613], "value": 20994.865948795017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.35701665951314, 371.4558172277614, 375.75012616609956, 377.5789994156515, 403.7819577428187, 15207.619809504768, 16003.599910002256, 20238.0, 20715.792842071576, 20770.584588308226, 19893.801061989376, 20503.384898453034, 20862.68705969408, 21406.785932140672, 21423.892880535575, 5348.9197662035, 5528.364238112608, 5530.170474428827, 5569.136783798518, 6066.878662426749, 3867.2652196082795, 6009.429104235112, 6109.114178444123, 6113.174721412619, 6117.847053823645, 5881.8823623527505, 6150.96924515379, 6187.752489900398, 6229.844253893666, 6239.220097487814, 6205.7517699291975, 6224.657643829588, 6239.532035097386, 6751.122354093936, 6831.2144103428245, 5985.281766188042, 5986.28164620244, 5987.161797348374, 5990.2212712347555, 6005.159277701126, 13047.54333598329, 13203.075784695075, 13345.599632011055, 13374.264415457146, 15840.683186336324, 2295.8622482650926, 2346.941326466835, 2477.7866427477747, 2677.250369896432, 2907.5638654201825, 8051.194880511961, 9942.403455792602, 10067.748306292327, 10396.376217426978, 10469.63356282534, 1819.2904767140826, 1880.9717854232197, 1883.0961138653522, 1884.406411980222, 1913.062599326334, 85066.73531625023, 100593.0, 115027.42486287598, 136963.31518342346, 140186.29906850503, 11200.663980080612, 11307.378094204898, 11495.965363117359, 11583.189176757714, 11715.4142292885, 14418.783718244234, 14443.711125777425, 14448.133112013313, 14452.277386130652, 14458.638534036732, 8245.216704413071, 8309.042222068525, 8352.540610266493, 8397.95801020991, 8530.530820804792, 6315.643179065589, 8191.590420478952, 8374.874376884352, 8407.747767566983, 8415.705450309331, 4405.383246345515, 4507.504174540798, 4517.81928722854, 4532.27483602622, 4545.704419268249, 1868.467486766265, 1919.9712004319947, 1934.6807776716967, 1962.4308950404222, 1972.6646470100118, 990.6235630460379, 990.8117457683018, 991.2317953586029, 1034.095166729097, 1051.8632577764995, 20438.89780551088, 21394.35816925495, 21766.45583860416, 21798.0, 21825.708300628525, 910.7996240827008, 917.8164367126468, 954.7040417470606, 979.6571200079915, 1017.7557386227252, 9118.726438206864, 11313.773724525463, 11338.943305283421, 11502.194846360842, 11560.0, 572739.0, 578934.0, 582156.0, 596211.0, 596309.0, 125697.0, 125945.37027314988, 132367.0, 133327.0, 136477.0, 833.9916600834035, 905.4702998745807, 998.0069830518536, 1022.3559157730582, 1040.3965699894043, 19016.714749278493, 19272.903635482293, 19334.226630934605, 19350.90324548368, 19367.225310987404, 53080.0, 53335.4666453346, 53569.73215133899, 54493.0, 55249.72375138099, 76700.0, 78795.0, 79273.60363198147, 79402.0, 84216.0, 7637.274458926393, 7834.921650783647, 7905.367570594453, 7906.446548741763, 8048.39637027237, 11244.437778111225, 12033.338166400932, 12036.578719744766, 12424.813627795771, 12455.875441245471, 10302.484875756332, 10312.742181445525, 10709.036186743231, 10775.976282253174, 10845.295055821347, 10443.530041148319, 10457.26799124054, 10652.62715804943, 10666.093382062612, 10692.305000174963, 10603.840942385716, 10647.254692171473, 11040.116790656568, 11043.282186657842, 11086.390248536085, 7754.069511658637, 7809.492382995087, 7881.6453259604605, 7889.053313602404, 7898.921010790048, 7854.096778870503, 9232.95383523078, 9295.88449386078, 10379.792404151724, 10854.0], "value": 9362.037276918178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.2162563274849, 1367.6991061966366, 1369.8493165751772, 1653.6609994951036, 1898.686716691745, 7721.382289416847, 8607.74176774697, 8784.209421152094, 8848.29213662907, 8857.202851743341, 8608.096149904264, 8719.389642725011, 8728.956355218232, 8733.170348816868, 8739.344549158806, 2342.8477148985303, 2366.976330236693, 2367.443650742074, 2373.8931748071377, 2377.738448770634, 2333.3466629343725, 2353.694019777435, 2381.297517232412, 2382.5473160099614, 2382.857028578282, 2331.5803155432013, 2361.633946738258, 2369.8933547990296, 2377.4888398994212, 2377.607694730376, 2282.577723121228, 2310.3646497213317, 2319.5940710375703, 2324.0, 2334.568104900599, 2314.5486630107134, 2316.100797346177, 2318.188633978111, 2322.082777302956, 2324.0239099578216, 8706.521141337224, 8712.433691810013, 8763.649454021832, 8780.165884240987, 8804.955975220082, 5133.178691409394, 5306.946930530722, 5321.255024296602, 5344.465553444663, 5348.759305831247, 3629.201575653352, 3651.5618125824803, 3686.078480379905, 3717.7583457075475, 3725.329440700673, 1070.9678709638724, 1080.4327727942814, 1085.7122862441458, 1103.6027030269097, 1108.9279196852178, 10573.894261057369, 11154.828742981952, 11795.528178872835, 11819.349935753617, 11835.349055801928, 3864.9227015459533, 4400.119976004812, 4467.508574056851, 4622.815087396534, 4631.884202894955, 9173.495457749888, 9178.082191780768, 9180.6786762463, 9186.127317904788, 9198.03420640823, 5202.985417843484, 5205.115130427836, 5208.140656791663, 5214.661047031931, 5221.738913054332, 4340.088581397878, 4499.775011249424, 4696.412948381452, 4846.80612775485, 4888.95111048885, 3677.5403074615674, 3727.962720372816, 3790.583035866108, 3831.3678243090135, 3842.884713458601, 950.8193443245899, 985.6008316631776, 1143.7369405036889, 1148.5405837664835, 1153.9076873850072, 851.2721623012359, 892.7857314244535, 904.2449554621965, 906.5150144672568, 917.9632814687471, 4439.844605438859, 5737.196792449061, 6027.939720602738, 6115.296740874856, 6188.0717892315615, 423.6674210744551, 424.1114864359163, 437.9846705365297, 438.1368703653797, 453.6302913125792, 3849.2109117630816, 3942.6845852331744, 4054.3310353791885, 4100.227790432749, 4149.294619914622, 9995.350302230332, 10613.25707200504, 10764.192685548458, 10775.6228532001, 10812.080973117216, 10522.0, 10617.878509545, 10725.946370268099, 10728.892711072946, 10765.892341076646, 8082.9191708083345, 8270.0, 8278.379121565908, 8279.006519217612, 8286.544240066854, 5714.857128571819, 5798.768049277982, 5899.643082091057, 5903.498202652822, 5983.640981541122, 8404.663813447394, 9927.40435573868, 10010.44942528168, 10016.148627366756, 10037.949810250902, 8065.919340806517, 10042.0, 10059.547320370748, 10216.131628811338, 10217.386956782617, 5778.768849245983, 6979.127609048869, 7179.138503379419, 7499.5875226863045, 7669.654865531176, 7193.640317983975, 8112.148224436349, 8487.957560212159, 8490.27832634233, 8504.979402471743, 7415.629218539159, 7472.0286362772495, 7510.624468776648, 7566.1298950621385, 7638.8090297743, 5839.299284085937, 6189.783357582463, 7160.928390716234, 7344.265573442613, 7443.739469118555, 6917.861642767217, 6986.790396288119, 7074.752383666547, 7083.858322833617, 7102.857942841218, 5032.521910418504, 5050.065737838511, 5103.693778373309, 5105.489451055013, 5183.611229157753, 4955.925661115014, 5035.16919708244, 5094.414142373675, 5260.789568417221, 5265.262863199078], "value": 4417.579003466978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.44901217321893, 303.2721468475658, 309.02194554235837, 313.7270574600098, 316.78775220602193, 5242.457408157045, 5275.472452754732, 5290.973545132274, 5295.173165257985, 5295.258663787064, 4171.228322760291, 5057.84826455206, 5166.509181627749, 5242.501962313584, 5251.763670634821, 3101.302207003423, 3505.684488396044, 3956.960430395688, 3981.7610943343493, 3985.382265748799, 3442.8967130985984, 3987.8006099695044, 3992.980035099835, 4006.91813210433, 4014.9397759033636, 3976.76139431635, 3984.78464068459, 3986.5415477220054, 3999.560048394674, 4010.3783913493307, 3832.4478586172645, 3973.9801300993454, 4002.959970400317, 4015.879523614267, 4027.1945610877797, 2709.972900270992, 2920.678725340211, 3273.7053665170247, 3449.87925422609, 3505.333986542574, 4609.608183304423, 5016.871203979075, 5031.119554078058, 5088.745562721849, 5092.236164575306, 3797.6012518685693, 3798.4682144499793, 4544.318352247156, 4586.54134586542, 4600.5629465200755, 4742.814296425893, 5318.867028324323, 5393.811216607439, 5542.780588270574, 5548.750306236229, 3130.8591113399953, 3523.4362501999813, 3806.695464362844, 3841.654251117413, 3842.3275926712713, 5456.781728730846, 5594.300712410949, 5668.093105103164, 5700.37295897451, 5724.227229324029, 3089.2431354317887, 3634.945475817865, 3901.044244160199, 3908.7068469864876, 3920.5883382245024, 3317.6350601433824, 3521.049316684481, 3786.3563194257044, 3796.5064541609413, 3999.320115580358, 3769.6418840210135, 3907.2258696325357, 4254.808533615933, 4512.962018735643, 4578.5650363215445, 4287.2711639021445, 4564.42944631921, 5450.91823622646, 5455.727213639302, 5472.835814925558, 1344.4823742859069, 1345.4954392102961, 1363.352407606379, 1377.4778869349425, 1383.8270216222973, 763.5189830406808, 768.7194174126404, 790.8260182759784, 811.736185739637, 850.4514588090647, 3643.7449378543247, 3918.2359439909515, 3954.9011274718364, 4229.534751177368, 4282.522529727239, 3813.5995720448955, 4661.743604101739, 4661.743604101739, 4727.659808394867, 4737.620990320766, 3634.618365071682, 4136.482939632546, 4281.443412356436, 4300.6989510734575, 4360.847370342023, 2263.7849404306567, 2470.6047032475076, 2484.9378765530646, 2496.613024981131, 2502.810041213938, 4216.557261487561, 4331.45856767904, 4560.566746159109, 4822.493638168012, 4853.713765851981, 4206.558311377261, 4292.531794218337, 4772.873767682174, 4794.513700752777, 4803.670219943325, 4081.8367265309057, 4617.58441740245, 5288.471152884681, 5934.8813023739285, 5954.910676339945, 4452.710573812692, 4979.6763210392655, 5730.656160630376, 5737.74180161902, 5750.223719797774, 4418.09429067034, 5446.58388818902, 5611.3266408031295, 5649.2373529574625, 5677.523843800559, 4555.33947577606, 4932.556069953722, 4977.178765503796, 5895.322037965689, 5937.0, 2888.9855550722114, 3327.8835240766457, 3662.340778659867, 3744.9625503744614, 3867.3232184368185, 4728.503507131702, 4771.618270538418, 5244.475552444725, 5772.480476757112, 5774.939282180451, 4784.545468180518, 5400.297961265011, 5506.669599824023, 5548.585110796721, 5554.305711786027, 5101.260317254051, 5256.763445644879, 5536.280283563111, 5536.91694624589, 5545.195946587803, 5142.665726727751, 5451.672899626035, 5470.343558772973, 5507.394186639386, 5539.639923404966, 4446.755428451466, 4960.685418364209, 5542.445755424425, 5542.61201715876, 5570.5265052470995, 4347.739135651871, 4482.394876691605, 4962.727050012284, 5424.728763561885, 5500.367457742411], "value": 3820.9238980465616, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.1965533255764, 726.2193142371951, 761.1361105145658, 781.238292664652, 808.7452452477471, 14596.70806583869, 15853.286602102904, 15868.444604438848, 15926.442574509909, 15937.68124637508, 14628.4880029199, 14777.852221477782, 14787.556373308791, 14797.92601036996, 14819.851801481982, 678.4165617568887, 698.5564166754123, 819.0253598218117, 824.2828738997081, 850.3239924260197, 832.4589017138878, 834.5493433545909, 847.6439895243982, 849.0575461238021, 851.1445996773234, 906.8639704044413, 908.3505293714992, 909.6088681866795, 909.6270529083077, 911.2209061252632, 715.2525610736792, 870.3385427075405, 902.8826252587154, 903.1104362203224, 903.6566104880136, 659.4757168051362, 662.8541720821412, 663.6151032401195, 665.1785045468843, 667.1893649216225, 14695.412183512646, 14733.04235224707, 14753.483628072965, 14766.187859667714, 14796.852031479762, 13566.864331356757, 13768.0, 13828.446862125504, 13859.514916977858, 14028.789568156486, 12378.938105309506, 12403.689907752288, 12441.0, 12463.501459941592, 12753.0, 4446.9555304447185, 4509.503954564995, 4541.84103556374, 4704.482506924235, 4710.034442939189, 108991.99999999921, 109003.0, 109423.0, 109672.0, 112591.0, 9264.305177111744, 9401.764955876159, 9419.199368053793, 9524.095210955087, 9553.952230238943, 16939.322427103023, 17187.484375468757, 17299.82700172982, 17415.47753567395, 17470.563235919202, 7639.197884222188, 7732.503610188903, 7801.29788319054, 7813.413993950477, 7887.0, 4479.641628669699, 4480.641548676098, 4501.279795232747, 4550.795214215368, 4558.703684260512, 3313.1551454378978, 3365.3269346130874, 3415.0949998250485, 3417.9145521362166, 3421.9315613687586, 627.6077451592755, 631.210986267166, 637.1175921348872, 641.0928536121429, 643.5655932245716, 518.99740501298, 545.8007827143064, 552.7043031977872, 560.8289471711167, 565.7227958300404, 8552.529610871461, 8733.563321833883, 8886.066962968924, 8929.73210803677, 8933.330000250007, 589.3399392680226, 628.9245290565161, 634.3888307916471, 635.2948227467507, 650.9544331896722, 3540.5751309842985, 4332.956670433318, 4352.020795321027, 4386.342048692722, 4477.944122141927, 147916.0, 150597.0, 151282.0, 153369.0, 157591.0, 86071.0, 115205.0, 120716.0, 124719.75280247263, 126982.36508817397, 97165.0, 98499.5075024649, 107281.46359268154, 109644.4517777406, 111631.44184279189, 9237.861432078658, 9734.221262298857, 9767.36512126738, 9779.168770654574, 9794.314397992071, 47308.763456182496, 47499.81250468766, 52721.20918186154, 52953.7352313236, 53174.40476785703, 79329.60335198288, 83390.0, 83924.50943632046, 84001.0, 85536.14463855281, 12759.93620031894, 12935.736246157074, 13084.803727943898, 13179.472821087049, 13585.0, 14795.556133316019, 14820.777688334469, 15109.655582125422, 15355.0, 15357.0, 13621.79567306511, 13917.164970101827, 13922.164670119826, 14360.85639143637, 14399.42402303938, 12503.624891252914, 12763.234205947672, 12893.613191604267, 12920.160189587647, 13178.472861085449, 13436.798448023092, 13472.932635336761, 13542.119762215818, 13961.860381396462, 14015.299235038412, 11417.200795944536, 11427.542898283975, 11449.313041217554, 11598.0, 12020.398980051137, 9524.999875013023, 10147.949260253652, 10777.13782897382, 11395.544178232778, 11588.884111159117], "value": 7609.219089929437, "unit": "score"}], "name": "dromaeo_css", "value": 7207.1675473687565, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 112.0, 112.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 141.0, 140.0, 163.0, 106.0, 133.0, 127.0, 109.0, 159.0, 104.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 183.0, 186.0, 182.0, 184.0, 184.0, 185.0, 185.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 62.0, 86.0, 59.0, 57.0, 66.0, 63.0, 83.0, 57.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 103.0, 72.0, 70.0, 68.0, 97.0, 70.0, 68.0, 96.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 74.0, 125.0, 133.0, 96.0, 89.0, 87.0, 87.0, 85.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 104.0, 134.0, 135.0, 86.0, 85.0, 86.0, 84.0, 86.0, 84.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 52.0, 54.0, 53.0, 53.0, 52.0, 53.0, 54.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 75.0, 74.0, 78.0, 71.0, 73.0, 75.0, 74.0, 72.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 84.0, 86.0, 86.0, 85.0, 86.0, 81.0, 104.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 148.0, 148.0, 144.0, 142.0, 151.0, 124.0, 154.0, 146.0, 146.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 166.0, 168.0, 166.0, 167.0, 165.0, 164.0, 163.0, 167.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 68.0, 64.0, 66.0, 66.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1451.8999999999999, "alertThreshold": 2.0}]} 11:58:49 INFO - Return code: 0 11:58:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:58:49 INFO - # TBPL SUCCESS # 11:58:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:58:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:58:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:58:49 INFO - 2016-08-12 11:58:49.333 screenresolution[1661:9743] starting screenresolution argv=screenresolution get 11:58:49 INFO - 2016-08-12 11:58:49.336 screenresolution[1661:9743] Display 0: 1600x1200x32@60 11:58:49 INFO - 2016-08-12 11:58:49.342 screenresolution[1662:9746] starting screenresolution argv=screenresolution list 11:58:49 INFO - Available Modes on Display 0 11:58:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:58:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:58:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:58:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:58:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:58:49 INFO - Intel Iris: 11:58:49 INFO - Chipset Model: Intel Iris 11:58:49 INFO - Type: GPU 11:58:49 INFO - Bus: Built-In 11:58:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:58:49 INFO - Vendor: Intel (0x8086) 11:58:49 INFO - Device ID: 0x0a2e 11:58:49 INFO - Revision ID: 0x0009 11:58:49 INFO - Displays: 11:58:49 INFO - Display: 11:58:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:58:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:58:49 INFO - Display Serial Number: 1600x1200 60 11:58:49 INFO - Main Display: Yes 11:58:49 INFO - Mirror: Off 11:58:49 INFO - Online: Yes 11:58:49 INFO - Rotation: Supported 11:58:49 INFO - Return code: 0 11:58:49 INFO - Running post-action listener: _resource_record_post_action 11:58:49 INFO - [mozharness: 2016-08-12 18:58:49.575006Z] Finished run-tests step (success) 11:58:49 INFO - Running post-run listener: _resource_record_post_run 11:58:49 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 36856832; Write bytes: 705067008; Read time: 383; Write time: 2037 11:58:49 INFO - TinderboxPrint: CPU usage
53.4% 11:58:49 INFO - TinderboxPrint: I/O read bytes / time
36,856,832 / 383 11:58:49 INFO - TinderboxPrint: I/O write bytes / time
705,067,008 / 2,037 11:58:49 INFO - TinderboxPrint: CPU idle
2,259.6 (46.6%) 11:58:49 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 11:58:49 INFO - TinderboxPrint: CPU user
1,621.8 (33.4%) 11:58:49 INFO - TinderboxPrint: Swap in / out
440,971,264 / 0 11:58:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398656000; Write bytes: 389096960; Read time: 29763; Write time: 704 11:58:49 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29625344; Write bytes: 295936512; Read time: 293; Write time: 1288 11:58:50 INFO - Running post-run listener: _upload_blobber_files 11:58:50 INFO - Blob upload gear active. 11:58:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:58:50 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 11:58:50 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:58:50 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:58:50 INFO - (blobuploader) - INFO - Open directory for files ... 11:58:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:58:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:58:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:51 INFO - (blobuploader) - INFO - Done attempting. 11:58:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:58:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:58:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:52 INFO - (blobuploader) - INFO - Done attempting. 11:58:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:58:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:58:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:55 INFO - (blobuploader) - INFO - Done attempting. 11:58:55 INFO - (blobuploader) - INFO - Iteration through files over. 11:58:55 INFO - Return code: 0 11:58:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:58:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:58:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368e1d48713fbb4ef5c58d9eb6d5ae45a09263580f81758471acdcd1b01e02c3eeb7e36d4857c4ae1f67995fb70888ffdea1c04dd8aa5e80764af67a3370b7d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9baadda07b579e7f6681ae2ded67f0357a62de2a2b05c55a659e2fb02647fa2a94ccf758cef70b8dcdcab0b09b35bd4df3edc853e10dedecda0ea11e0d51216", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a2fdb26c7ae7a4cc63a7e57830d42c39f1e6e71784eada19c17068f3028e7bc04219f115e2942172e394c24b6d4364d4eff627f1ec5ef37062a5b1c95e015f"} 11:58:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:58:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:58:55 INFO - Contents: 11:58:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368e1d48713fbb4ef5c58d9eb6d5ae45a09263580f81758471acdcd1b01e02c3eeb7e36d4857c4ae1f67995fb70888ffdea1c04dd8aa5e80764af67a3370b7d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9baadda07b579e7f6681ae2ded67f0357a62de2a2b05c55a659e2fb02647fa2a94ccf758cef70b8dcdcab0b09b35bd4df3edc853e10dedecda0ea11e0d51216", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a2fdb26c7ae7a4cc63a7e57830d42c39f1e6e71784eada19c17068f3028e7bc04219f115e2942172e394c24b6d4364d4eff627f1ec5ef37062a5b1c95e015f"} 11:58:55 INFO - Running post-run listener: copy_logs_to_upload_dir 11:58:55 INFO - Copying logs to upload dir... 11:58:55 INFO - mkdir: /builds/slave/test/build/upload/logs 11:58:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1304.762744 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-08-12 11:58:55.373864) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 11:58:55.380485) ========= 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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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/Firefox/sha512/368e1d48713fbb4ef5c58d9eb6d5ae45a09263580f81758471acdcd1b01e02c3eeb7e36d4857c4ae1f67995fb70888ffdea1c04dd8aa5e80764af67a3370b7d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9baadda07b579e7f6681ae2ded67f0357a62de2a2b05c55a659e2fb02647fa2a94ccf758cef70b8dcdcab0b09b35bd4df3edc853e10dedecda0ea11e0d51216", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a2fdb26c7ae7a4cc63a7e57830d42c39f1e6e71784eada19c17068f3028e7bc04219f115e2942172e394c24b6d4364d4eff627f1ec5ef37062a5b1c95e015f"} build_url:https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011134 build_url: 'https://queue.taskcluster.net/v1/task/C7JUPVozSvWNEugC1tnc2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/368e1d48713fbb4ef5c58d9eb6d5ae45a09263580f81758471acdcd1b01e02c3eeb7e36d4857c4ae1f67995fb70888ffdea1c04dd8aa5e80764af67a3370b7d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9baadda07b579e7f6681ae2ded67f0357a62de2a2b05c55a659e2fb02647fa2a94ccf758cef70b8dcdcab0b09b35bd4df3edc853e10dedecda0ea11e0d51216", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a4a2fdb26c7ae7a4cc63a7e57830d42c39f1e6e71784eada19c17068f3028e7bc04219f115e2942172e394c24b6d4364d4eff627f1ec5ef37062a5b1c95e015f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 11:58:55.420610) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:58:55.420896) ========= 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.wOv0WmXmSE/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.B4kFsRVrDd/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.004631 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:58:55.469803) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 11:58:55.470134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 11:58:55.470476) ========= ========= Total master_lag: 0.18 =========