builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0236 starttime: 1481538445.1 results: success (0) buildid: 20161212004004 builduid: b72bb07459af424692b6be19567489a7 revision: 327307b7933666ef32b964865f2d853ece5744e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.100647) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.101118) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.101399) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.278758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.279100) ========= 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.NwAiPa9ALk/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.iXpHESp6j3/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-12-12 02:27:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.96M=0.001s 2016-12-12 02:27:25 (8.96 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.253601 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.582711) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.583074) ========= 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.NwAiPa9ALk/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.iXpHESp6j3/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.019451 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:25.684967) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-12 02:27:25.685635) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 327307b7933666ef32b964865f2d853ece5744e1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 327307b7933666ef32b964865f2d853ece5744e1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NwAiPa9ALk/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.iXpHESp6j3/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-12-12 02:27:25,782 truncating revision to first 12 chars 2016-12-12 02:27:25,782 Setting DEBUG logging. 2016-12-12 02:27:25,783 attempt 1/10 2016-12-12 02:27:25,783 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/327307b79336?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-12 02:27:26,961 unpacking tar archive at: mozilla-aurora-327307b79336/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458084 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-12 02:27:27.198897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:27.199228) ========= script_repo_revision: 327307b7933666ef32b964865f2d853ece5744e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:27.199636) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:27.199919) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 02:27:27.359836) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-12 02:27:27.360309) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NwAiPa9ALk/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.iXpHESp6j3/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:27:27 INFO - MultiFileLogger online at 20161212 02:27:27 in /builds/slave/test 02:27:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:27:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:27 INFO - {'append_to_log': False, 02:27:27 INFO - 'base_work_dir': '/builds/slave/test', 02:27:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:27:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:27 INFO - 'branch': 'Mozilla-Aurora', 02:27:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:27 INFO - 'config_files': ('talos/mac_config.py',), 02:27:27 INFO - 'default_actions': ('clobber', 02:27:27 INFO - 'read-buildbot-config', 02:27:27 INFO - 'download-and-extract', 02:27:27 INFO - 'populate-webroot', 02:27:27 INFO - 'create-virtualenv', 02:27:27 INFO - 'install', 02:27:27 INFO - 'run-tests'), 02:27:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:27 INFO - 'download_minidump_stackwalk': True, 02:27:27 INFO - 'download_symbols': 'ondemand', 02:27:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:27 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:27 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:27 INFO - 'installer_path': 'installer.exe', 02:27:27 INFO - 'log_level': 'info', 02:27:27 INFO - 'log_name': 'talos', 02:27:27 INFO - 'log_to_console': True, 02:27:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:27 INFO - 'opt_config_files': (), 02:27:27 INFO - 'pip_index': False, 02:27:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:27 INFO - 'cmd': ('bash', 02:27:27 INFO - '-c', 02:27:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:27 INFO - 'enabled': True, 02:27:27 INFO - 'halt_on_failure': False, 02:27:27 INFO - 'name': 'check_screen_resolution'},), 02:27:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:27 INFO - 'cmd': ('bash', 02:27:27 INFO - '-c', 02:27:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:27 INFO - 'enabled': True, 02:27:27 INFO - 'halt_on_failure': False, 02:27:27 INFO - 'name': 'check_screen_resolution'},), 02:27:27 INFO - 'run_cmd_checks_enabled': True, 02:27:27 INFO - 'sps_profile': False, 02:27:27 INFO - 'sps_profile_interval': 0, 02:27:27 INFO - 'suite': 'dromaeojs-e10s', 02:27:27 INFO - 'system_bits': '32', 02:27:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:27 INFO - 'title': 't-yosemite-r7-0236', 02:27:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:27 INFO - 'use_talos_json': True, 02:27:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:27 INFO - 'work_dir': 'build'} 02:27:27 INFO - [mozharness: 2016-12-12 10:27:27.582094Z] Running clobber step. 02:27:27 INFO - Running pre-action listener: _resource_record_pre_action 02:27:27 INFO - Running main action method: clobber 02:27:27 INFO - rmtree: /builds/slave/test/build 02:27:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:28 INFO - Running post-action listener: _resource_record_post_action 02:27:28 INFO - [mozharness: 2016-12-12 10:27:28.492343Z] Finished clobber step (success) 02:27:28 INFO - [mozharness: 2016-12-12 10:27:28.492531Z] Running read-buildbot-config step. 02:27:28 INFO - Running pre-action listener: _resource_record_pre_action 02:27:28 INFO - Running main action method: read_buildbot_config 02:27:28 INFO - Using buildbot properties: 02:27:28 INFO - { 02:27:28 INFO - "project": "", 02:27:28 INFO - "product": "firefox", 02:27:28 INFO - "script_repo_revision": "production", 02:27:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:27:28 INFO - "repository": "", 02:27:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:27:28 INFO - "buildid": "20161212004004", 02:27:28 INFO - "stage_platform": "macosx64", 02:27:28 INFO - "basedir": "/builds/slave/test", 02:27:28 INFO - "nightly_build": "True", 02:27:28 INFO - "buildnumber": 37, 02:27:28 INFO - "slavename": "t-yosemite-r7-0236", 02:27:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:27:28 INFO - "platform": "yosemite_r7", 02:27:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:27:28 INFO - "branch": "mozilla-aurora", 02:27:28 INFO - "repo_path": "releases/mozilla-aurora", 02:27:28 INFO - "revision": "327307b7933666ef32b964865f2d853ece5744e1", 02:27:28 INFO - "pgo_build": "False", 02:27:28 INFO - "builduid": "b72bb07459af424692b6be19567489a7", 02:27:28 INFO - "slavebuilddir": "test" 02:27:28 INFO - } 02:27:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:27:28 INFO - Running post-action listener: _resource_record_post_action 02:27:28 INFO - [mozharness: 2016-12-12 10:27:28.497846Z] Finished read-buildbot-config step (success) 02:27:28 INFO - [mozharness: 2016-12-12 10:27:28.498076Z] Running download-and-extract step. 02:27:28 INFO - Running pre-action listener: _resource_record_pre_action 02:27:28 INFO - Running main action method: download_and_extract 02:27:28 INFO - mkdir: /builds/slave/test/build/tests 02:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:28 INFO - trying https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:27:28 INFO - Downloading https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:27:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:27:29 INFO - Downloaded 1199 bytes. 02:27:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:27:29 INFO - Using the following test package requirements: 02:27:29 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:27:29 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:27:29 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:27:29 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'jsshell-mac.zip'], 02:27:29 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:27:29 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:27:29 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:27:29 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:27:29 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:27:29 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:27:29 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:27:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:27:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:27:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:27:29 INFO - Fetch https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:27:29 INFO - Http code: 200 02:27:29 INFO - via: 1.1 4a7b695b8ae560fe9087da065a2b7812.cloudfront.net (CloudFront) 02:27:29 INFO - x-amz-cf-id: WvoTk6lQGLN16kJ4ZFf-K-97h_T4RfE52u5whHDXlpxyqFbzwZ7JMg== 02:27:29 INFO - x-amz-version-id: KNB0Sqw9z2HMG3ArrY7bC2e6Ozjx9RRM 02:27:30 INFO - Expected file size: 16281023 02:27:30 INFO - Obtained file size: 16281023 02:27:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:27:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:27:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:27:36 INFO - Fetch https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:27:37 INFO - Http code: 200 02:27:37 INFO - via: 1.1 1c6cc78b8ca6319854350cf7a741a470.cloudfront.net (CloudFront) 02:27:37 INFO - x-amz-cf-id: t_n1ozKPQWNeXTrZcnBQjhTMNtt8bmB5X5S5x7uva6rZVDlKBfHOlA== 02:27:37 INFO - x-amz-version-id: KNB0Sqw9z2HMG3ArrY7bC2e6Ozjx9RRM 02:27:39 INFO - Expected file size: 16281023 02:27:39 INFO - Obtained file size: 16281023 02:27:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:27:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:27:45 INFO - Fetch https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:27:46 INFO - Http code: 200 02:27:46 INFO - via: 1.1 2a47832c458ab2a6b20c8363f5aa35ea.cloudfront.net (CloudFront) 02:27:46 INFO - x-amz-cf-id: Y_JDhjB_W-A3X4alnXJyamSe76Hqf1ZzyoOjYL5hbgaTWVIu9QtqFg== 02:27:46 INFO - x-amz-version-id: R8yGNtNIHx53P_SQDHZ0zfk2lZEqzggh 02:27:46 INFO - Expected file size: 14409038 02:27:46 INFO - Obtained file size: 14409038 02:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:47 INFO - trying https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:27:47 INFO - Downloading https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:27:50 INFO - Downloaded 109851379 bytes. 02:27:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:27:50 INFO - mkdir: /builds/slave/test/properties 02:27:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:27:50 INFO - Writing to file /builds/slave/test/properties/build_url 02:27:50 INFO - Contents: 02:27:50 INFO - build_url:https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:27:51 INFO - Running post-action listener: _resource_record_post_action 02:27:51 INFO - Running post-action listener: set_extra_try_arguments 02:27:51 INFO - [mozharness: 2016-12-12 10:27:51.396840Z] Finished download-and-extract step (success) 02:27:51 INFO - [mozharness: 2016-12-12 10:27:51.396965Z] Running populate-webroot step. 02:27:51 INFO - Running pre-action listener: _resource_record_pre_action 02:27:51 INFO - Running main action method: populate_webroot 02:27:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:27:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:27:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:27:51 INFO - '--tppagecycles', 02:27:51 INFO - '7'], 02:27:51 INFO - 'tests': ['tsvgm']}}, 02:27:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:27:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:27:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:27:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:27:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['damp', 'tps']}, 02:27:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'tests': ['damp', 'tps']}, 02:27:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['dromaeo_dom']}, 02:27:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:27:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['basic_compositor_video']}, 02:27:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:27:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['a11yr', 02:27:51 INFO - 'ts_paint', 02:27:51 INFO - 'tpaint', 02:27:51 INFO - 'sessionrestore', 02:27:51 INFO - 'sessionrestore_no_auto_restore', 02:27:51 INFO - 'tabpaint']}, 02:27:51 INFO - 'other-e10s': {'tests': ['a11yr', 02:27:51 INFO - 'ts_paint', 02:27:51 INFO - 'tpaint', 02:27:51 INFO - 'sessionrestore', 02:27:51 INFO - 'sessionrestore_no_auto_restore', 02:27:51 INFO - 'tabpaint']}, 02:27:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:27:51 INFO - 'ts_paint', 02:27:51 INFO - 'tpaint', 02:27:51 INFO - 'sessionrestore', 02:27:51 INFO - 'sessionrestore_no_auto_restore', 02:27:51 INFO - 'tabpaint']}, 02:27:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:27:51 INFO - 'ts_paint', 02:27:51 INFO - 'tpaint', 02:27:51 INFO - 'sessionrestore', 02:27:51 INFO - 'sessionrestore_no_auto_restore', 02:27:51 INFO - 'tabpaint']}, 02:27:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['a11yr', 02:27:51 INFO - 'ts_paint', 02:27:51 INFO - 'tpaint', 02:27:51 INFO - 'sessionrestore', 02:27:51 INFO - 'sessionrestore_no_auto_restore', 02:27:51 INFO - 'tabpaint']}, 02:27:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['a11yr', 02:27:51 INFO - 'ts_paint', 02:27:51 INFO - 'tpaint', 02:27:51 INFO - 'sessionrestore', 02:27:51 INFO - 'sessionrestore_no_auto_restore', 02:27:51 INFO - 'tabpaint']}, 02:27:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['tsvgx', 02:27:51 INFO - 'tsvgr_opacity', 02:27:51 INFO - 'tart', 02:27:51 INFO - 'tscrollx', 02:27:51 INFO - 'cart']}, 02:27:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:27:51 INFO - 'tsvgr_opacity', 02:27:51 INFO - 'tart', 02:27:51 INFO - 'tscrollx', 02:27:51 INFO - 'cart']}, 02:27:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'talos_options': ['--disable-e10s'], 02:27:51 INFO - 'tests': ['tp5o']}, 02:27:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'tests': ['tp5o']}, 02:27:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'talos_options': ['--disable-e10s', 02:27:51 INFO - '--xperf_path', 02:27:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:51 INFO - 'tests': ['tp5n']}, 02:27:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:51 INFO - 'talos_options': ['--xperf_path', 02:27:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:51 INFO - 'tests': ['tp5n']}}, 02:27:51 INFO - 'talos.zip': {'path': '', 02:27:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:27:51 INFO - Running post-action listener: _resource_record_post_action 02:27:51 INFO - [mozharness: 2016-12-12 10:27:51.415943Z] Finished populate-webroot step (success) 02:27:51 INFO - [mozharness: 2016-12-12 10:27:51.416054Z] Running create-virtualenv step. 02:27:51 INFO - Running pre-action listener: _resource_record_pre_action 02:27:51 INFO - Running main action method: create_virtualenv 02:27:51 INFO - Creating virtualenv /builds/slave/test/build/venv 02:27:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:27:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:27:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:27:51 INFO - Using real prefix '/tools/python27' 02:27:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:27:52 INFO - Installing distribute.............................................................................................................................................................................................done. 02:27:55 INFO - Installing pip.................done. 02:27:55 INFO - Return code: 0 02:27:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:27:55 INFO - Reading from file tmpfile_stdout 02:27:55 INFO - Output received: 02:27:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:27:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:55 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:55 INFO - 'HOME': '/Users/cltbld', 02:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:55 INFO - 'LOGNAME': 'cltbld', 02:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:55 INFO - 'MOZ_NO_REMOTE': '1', 02:27:55 INFO - 'NO_EM_RESTART': '1', 02:27:55 INFO - 'PAGER': '/bin/cat', 02:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:55 INFO - 'PWD': '/builds/slave/test', 02:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:55 INFO - 'SHELL': '/bin/bash', 02:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:27:55 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:55 INFO - 'USER': 'cltbld', 02:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:55 INFO - 'XPC_FLAGS': '0x0', 02:27:55 INFO - 'XPC_SERVICE_NAME': '0', 02:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:27:55 INFO - Cleaning up... 02:27:55 INFO - Return code: 0 02:27:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:55 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:55 INFO - 'HOME': '/Users/cltbld', 02:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:55 INFO - 'LOGNAME': 'cltbld', 02:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:55 INFO - 'MOZ_NO_REMOTE': '1', 02:27:55 INFO - 'NO_EM_RESTART': '1', 02:27:55 INFO - 'PAGER': '/bin/cat', 02:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:55 INFO - 'PWD': '/builds/slave/test', 02:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:55 INFO - 'SHELL': '/bin/bash', 02:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:27:55 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:55 INFO - 'USER': 'cltbld', 02:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:55 INFO - 'XPC_FLAGS': '0x0', 02:27:55 INFO - 'XPC_SERVICE_NAME': '0', 02:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:55 INFO - Downloading/unpacking psutil>=3.1.1 02:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:00 INFO - Installing collected packages: psutil 02:28:00 INFO - Running setup.py install for psutil 02:28:00 INFO - building 'psutil._psutil_osx' extension 02:28:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:28:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:28:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:28:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:28:00 INFO - building 'psutil._psutil_posix' extension 02:28:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:28:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:00 INFO - ^ 02:28:00 INFO - 1 warning generated. 02:28:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:28:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:00 INFO - Successfully installed psutil 02:28:00 INFO - Cleaning up... 02:28:01 INFO - Return code: 0 02:28:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:01 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:28:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:01 INFO - 'HOME': '/Users/cltbld', 02:28:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:01 INFO - 'LOGNAME': 'cltbld', 02:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:01 INFO - 'MOZ_NO_REMOTE': '1', 02:28:01 INFO - 'NO_EM_RESTART': '1', 02:28:01 INFO - 'PAGER': '/bin/cat', 02:28:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:01 INFO - 'PWD': '/builds/slave/test', 02:28:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:01 INFO - 'SHELL': '/bin/bash', 02:28:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:01 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:01 INFO - 'USER': 'cltbld', 02:28:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:01 INFO - 'XPC_FLAGS': '0x0', 02:28:01 INFO - 'XPC_SERVICE_NAME': '0', 02:28:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:28:04 INFO - Installing collected packages: mozsystemmonitor 02:28:04 INFO - Running setup.py install for mozsystemmonitor 02:28:04 INFO - Successfully installed mozsystemmonitor 02:28:04 INFO - Cleaning up... 02:28:04 INFO - Return code: 0 02:28:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:04 INFO - 'HOME': '/Users/cltbld', 02:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:04 INFO - 'LOGNAME': 'cltbld', 02:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:04 INFO - 'MOZ_NO_REMOTE': '1', 02:28:04 INFO - 'NO_EM_RESTART': '1', 02:28:04 INFO - 'PAGER': '/bin/cat', 02:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:04 INFO - 'PWD': '/builds/slave/test', 02:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:04 INFO - 'SHELL': '/bin/bash', 02:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:04 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:04 INFO - 'USER': 'cltbld', 02:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:04 INFO - 'XPC_FLAGS': '0x0', 02:28:04 INFO - 'XPC_SERVICE_NAME': '0', 02:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:05 INFO - Downloading/unpacking jsonschema==2.5.1 02:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:08 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:08 INFO - warning: no files found matching '*.txt' 02:28:08 INFO - no previously-included directories found matching 'build' 02:28:08 INFO - no previously-included directories found matching 'dist' 02:28:08 INFO - no previously-included directories found matching '.git*' 02:28:08 INFO - Installing collected packages: jsonschema, functools32 02:28:08 INFO - Running setup.py install for functools32 02:28:08 INFO - warning: no files found matching '*.txt' 02:28:08 INFO - no previously-included directories found matching 'build' 02:28:08 INFO - no previously-included directories found matching 'dist' 02:28:08 INFO - no previously-included directories found matching '.git*' 02:28:08 INFO - Successfully installed jsonschema functools32 02:28:08 INFO - Cleaning up... 02:28:08 INFO - Return code: 0 02:28:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:08 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:28:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:08 INFO - 'HOME': '/Users/cltbld', 02:28:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:08 INFO - 'LOGNAME': 'cltbld', 02:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:08 INFO - 'MOZ_NO_REMOTE': '1', 02:28:08 INFO - 'NO_EM_RESTART': '1', 02:28:08 INFO - 'PAGER': '/bin/cat', 02:28:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:08 INFO - 'PWD': '/builds/slave/test', 02:28:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:08 INFO - 'SHELL': '/bin/bash', 02:28:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:08 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:08 INFO - 'USER': 'cltbld', 02:28:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:08 INFO - 'XPC_FLAGS': '0x0', 02:28:08 INFO - 'XPC_SERVICE_NAME': '0', 02:28:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:28:09 INFO - Cleaning up... 02:28:09 INFO - Return code: 0 02:28:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:09 INFO - 'HOME': '/Users/cltbld', 02:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:09 INFO - 'LOGNAME': 'cltbld', 02:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:09 INFO - 'MOZ_NO_REMOTE': '1', 02:28:09 INFO - 'NO_EM_RESTART': '1', 02:28:09 INFO - 'PAGER': '/bin/cat', 02:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:09 INFO - 'PWD': '/builds/slave/test', 02:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:09 INFO - 'SHELL': '/bin/bash', 02:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:09 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:09 INFO - 'USER': 'cltbld', 02:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:09 INFO - 'XPC_FLAGS': '0x0', 02:28:09 INFO - 'XPC_SERVICE_NAME': '0', 02:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:09 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:13 INFO - Downloading docopt-0.6.1.tar.gz 02:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:13 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:13 INFO - Running setup.py install for blobuploader 02:28:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:13 INFO - Running setup.py install for requests 02:28:13 INFO - Running setup.py install for docopt 02:28:13 INFO - Successfully installed blobuploader requests docopt 02:28:13 INFO - Cleaning up... 02:28:14 INFO - Return code: 0 02:28:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:14 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:28:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:14 INFO - 'HOME': '/Users/cltbld', 02:28:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:14 INFO - 'LOGNAME': 'cltbld', 02:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:14 INFO - 'MOZ_NO_REMOTE': '1', 02:28:14 INFO - 'NO_EM_RESTART': '1', 02:28:14 INFO - 'PAGER': '/bin/cat', 02:28:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:14 INFO - 'PWD': '/builds/slave/test', 02:28:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:14 INFO - 'SHELL': '/bin/bash', 02:28:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:14 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:14 INFO - 'USER': 'cltbld', 02:28:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:14 INFO - 'XPC_FLAGS': '0x0', 02:28:14 INFO - 'XPC_SERVICE_NAME': '0', 02:28:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-D18Nii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-4aAhUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TlswsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8IJpfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-dm9EtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-exglW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:14 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-MBN5XT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-okr53U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-vWZckb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ZIqBvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Ghat06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-3a8Sv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7ppJbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-cbaU4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-DJBPlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-VZC0y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:15 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-5msd2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:16 INFO - Running setup.py install for manifestparser 02:28:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Running setup.py install for mozcrash 02:28:16 INFO - Running setup.py install for mozdebug 02:28:16 INFO - Running setup.py install for mozdevice 02:28:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Running setup.py install for mozfile 02:28:16 INFO - Running setup.py install for mozhttpd 02:28:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Running setup.py install for mozinfo 02:28:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Running setup.py install for mozInstall 02:28:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Running setup.py install for mozleak 02:28:17 INFO - Running setup.py install for mozlog 02:28:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Running setup.py install for moznetwork 02:28:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Running setup.py install for mozprocess 02:28:17 INFO - Running setup.py install for mozprofile 02:28:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for mozrunner 02:28:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Running setup.py install for mozscreenshot 02:28:18 INFO - Running setup.py install for moztest 02:28:18 INFO - Running setup.py install for mozversion 02:28:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:18 INFO - Cleaning up... 02:28:18 INFO - Return code: 0 02:28:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28: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')]} 02:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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')]} 02:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:18 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:28:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:18 INFO - 'HOME': '/Users/cltbld', 02:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:18 INFO - 'LOGNAME': 'cltbld', 02:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:18 INFO - 'MOZ_NO_REMOTE': '1', 02:28:18 INFO - 'NO_EM_RESTART': '1', 02:28:18 INFO - 'PAGER': '/bin/cat', 02:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:18 INFO - 'PWD': '/builds/slave/test', 02:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:18 INFO - 'SHELL': '/bin/bash', 02:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:18 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:18 INFO - 'USER': 'cltbld', 02:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:18 INFO - 'XPC_FLAGS': '0x0', 02:28:18 INFO - 'XPC_SERVICE_NAME': '0', 02:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:18 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-oXM60T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-cY1xPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-_CQBxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ATOJEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:19 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)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-eQxUK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Ec9WzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-PxJmT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-26e9po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-uzeLDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:19 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-3UPwCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-6kgc1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-YjIQNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-xgLIIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-DQRYEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TYgnFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Nhnwdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:20 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)) 02:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:20 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-b_Dk_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:28:20 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)) 02:28:20 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)) 02:28:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:23 INFO - Downloading blessings-1.6.tar.gz 02:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:23 INFO - Installing collected packages: blessings 02:28:23 INFO - Running setup.py install for blessings 02:28:23 INFO - Successfully installed blessings 02:28:23 INFO - Cleaning up... 02:28:23 INFO - Return code: 0 02:28:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:23 INFO - Reading from file tmpfile_stdout 02:28:23 INFO - Current package versions: 02:28:23 INFO - blessings == 1.6 02:28:23 INFO - blobuploader == 1.2.4 02:28:23 INFO - docopt == 0.6.1 02:28:24 INFO - functools32 == 3.2.3-2 02:28:24 INFO - jsonschema == 2.5.1 02:28:24 INFO - manifestparser == 1.1 02:28:24 INFO - mozInstall == 1.12 02:28:24 INFO - mozcrash == 1.0 02:28:24 INFO - mozdebug == 0.1 02:28:24 INFO - mozdevice == 0.48 02:28:24 INFO - mozfile == 1.2 02:28:24 INFO - mozhttpd == 0.7 02:28:24 INFO - mozinfo == 0.9 02:28:24 INFO - mozleak == 0.1 02:28:24 INFO - mozlog == 3.4 02:28:24 INFO - moznetwork == 0.27 02:28:24 INFO - mozprocess == 0.23 02:28:24 INFO - mozprofile == 0.28 02:28:24 INFO - mozrunner == 6.13 02:28:24 INFO - mozscreenshot == 0.1 02:28:24 INFO - mozsystemmonitor == 0.3 02:28:24 INFO - moztest == 0.7 02:28:24 INFO - mozversion == 1.4 02:28:24 INFO - psutil == 3.1.1 02:28:24 INFO - requests == 1.2.3 02:28:24 INFO - wsgiref == 0.1.2 02:28:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:24 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:24 INFO - 'HOME': '/Users/cltbld', 02:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:24 INFO - 'LOGNAME': 'cltbld', 02:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:24 INFO - 'MOZ_NO_REMOTE': '1', 02:28:24 INFO - 'NO_EM_RESTART': '1', 02:28:24 INFO - 'PAGER': '/bin/cat', 02:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:24 INFO - 'PWD': '/builds/slave/test', 02:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:24 INFO - 'SHELL': '/bin/bash', 02:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:24 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:24 INFO - 'USER': 'cltbld', 02:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:24 INFO - 'XPC_FLAGS': '0x0', 02:28:24 INFO - 'XPC_SERVICE_NAME': '0', 02:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:28:24 INFO - Cleaning up... 02:28:24 INFO - Return code: 0 02:28:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:24 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 0x107e11490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bcac7cb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, '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.NwAiPa9ALk/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.iXpHESp6j3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:24 INFO - 'HOME': '/Users/cltbld', 02:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:24 INFO - 'LOGNAME': 'cltbld', 02:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:24 INFO - 'MOZ_NO_REMOTE': '1', 02:28:24 INFO - 'NO_EM_RESTART': '1', 02:28:24 INFO - 'PAGER': '/bin/cat', 02:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:24 INFO - 'PWD': '/builds/slave/test', 02:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:24 INFO - 'SHELL': '/bin/bash', 02:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:24 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:24 INFO - 'USER': 'cltbld', 02:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:24 INFO - 'XPC_FLAGS': '0x0', 02:28:24 INFO - 'XPC_SERVICE_NAME': '0', 02:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:28:24 INFO - Cleaning up... 02:28:24 INFO - Return code: 0 02:28:24 INFO - Running post-action listener: _resource_record_post_action 02:28:24 INFO - Running post-action listener: _start_resource_monitoring 02:28:24 INFO - Starting resource monitoring. 02:28:24 INFO - [mozharness: 2016-12-12 10:28:24.564389Z] Finished create-virtualenv step (success) 02:28:24 INFO - [mozharness: 2016-12-12 10:28:24.565411Z] Running install step. 02:28:24 INFO - Running pre-action listener: _resource_record_pre_action 02:28:24 INFO - Running main action method: install 02:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:24 INFO - Reading from file tmpfile_stdout 02:28:24 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:24 INFO - Reading from file tmpfile_stdout 02:28:24 INFO - Output received: 02:28:24 INFO - Usage: mozinstall [options] installer 02:28:24 INFO - Options: 02:28:24 INFO - -h, --help show this help message and exit 02:28:24 INFO - -d DEST, --destination=DEST 02:28:24 INFO - Directory to install application into. [default: 02:28:24 INFO - "/builds/slave/test"] 02:28:24 INFO - --app=APP Application being installed. [default: firefox] 02:28:24 INFO - mkdir: /builds/slave/test/build/application 02:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:28:56 INFO - Reading from file tmpfile_stdout 02:28:56 INFO - Output received: 02:28:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:28:56 INFO - Running post-action listener: _resource_record_post_action 02:28:56 INFO - [mozharness: 2016-12-12 10:28:56.151162Z] Finished install step (success) 02:28:56 INFO - [mozharness: 2016-12-12 10:28:56.151348Z] Running run-tests step. 02:28:56 INFO - Running pre-action listener: _resource_record_pre_action 02:28:56 INFO - Running main action method: run_tests 02:28:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:28:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:28:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:28:56 INFO - 2016-12-12 02:28:56.165 screenresolution[1921:14517] starting screenresolution argv=screenresolution get 02:28:56 INFO - 2016-12-12 02:28:56.168 screenresolution[1921:14517] Display 0: 1600x1200x32@60 02:28:56 INFO - 2016-12-12 02:28:56.174 screenresolution[1922:14519] starting screenresolution argv=screenresolution list 02:28:56 INFO - Available Modes on Display 0 02:28:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:28:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:28:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:28:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:28:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:28:56 INFO - Intel Iris: 02:28:56 INFO - Chipset Model: Intel Iris 02:28:56 INFO - Type: GPU 02:28:56 INFO - Bus: Built-In 02:28:56 INFO - VRAM (Dynamic, Max): 1536 MB 02:28:56 INFO - Vendor: Intel (0x8086) 02:28:56 INFO - Device ID: 0x0a2e 02:28:56 INFO - Revision ID: 0x0009 02:28:56 INFO - Displays: 02:28:56 INFO - Display: 02:28:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:28:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:28:56 INFO - Display Serial Number: 1600x1200 60 02:28:56 INFO - Main Display: Yes 02:28:56 INFO - Mirror: Off 02:28:56 INFO - Online: Yes 02:28:56 INFO - Rotation: Supported 02:28:56 INFO - Return code: 0 02:28:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:28:56 INFO - Python 2.7.3 02:28:56 INFO - Return code: 0 02:28:56 INFO - grabbing minidump binary from tooltool 02:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c25d30>, '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 0x107a8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c1db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:28:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:28:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:28:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:28:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:28:56 INFO - Return code: 0 02:28:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:28:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:28:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:28:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:28:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:28:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:28:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NwAiPa9ALk/Render', 02:28:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:56 INFO - 'HOME': '/Users/cltbld', 02:28:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:56 INFO - 'LOGNAME': 'cltbld', 02:28:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:28:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:56 INFO - 'MOZ_NO_REMOTE': '1', 02:28:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:28:56 INFO - 'NO_EM_RESTART': '1', 02:28:56 INFO - 'PAGER': '/bin/cat', 02:28:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:56 INFO - 'PWD': '/builds/slave/test', 02:28:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:28:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:56 INFO - 'SHELL': '/bin/bash', 02:28:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iXpHESp6j3/Listeners', 02:28:56 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:28:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:56 INFO - 'USER': 'cltbld', 02:28:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:56 INFO - 'XPC_FLAGS': '0x0', 02:28:56 INFO - 'XPC_SERVICE_NAME': '0', 02:28:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:28:56 INFO - mozversion application_buildid: 20161212004004 02:28:56 INFO - mozversion application_changeset: 327307b7933666ef32b964865f2d853ece5744e1 02:28:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:28:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:28:56 INFO - mozversion application_name: Firefox 02:28:56 INFO - mozversion application_remotingname: firefox-dev 02:28:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:28:56 INFO - mozversion application_vendor: Mozilla 02:28:56 INFO - mozversion application_version: 52.0a2 02:28:56 INFO - mozversion platform_buildid: 20161212004004 02:28:56 INFO - mozversion platform_changeset: 327307b7933666ef32b964865f2d853ece5744e1 02:28:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:28:56 INFO - mozversion platform_version: 52.0a2 02:28:56 INFO - using testdate: 1481538536 02:28:56 INFO - actual date: 1481538536 02:28:56 INFO - starting webserver on 'localhost:49496' 02:28:56 INFO - SUITE-START | Running 2 tests 02:28:56 INFO - TEST-START | dromaeo_css 02:28:56 INFO - operating with platform_type : mac_ 02:28:56 INFO - Initialising browser for dromaeo_css test... 02:28:56 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpPkAipf/profile http://localhost:49496/getInfo.html) 02:28:59 INFO - PROCESS | 1932 | 2016-12-12 02:28:59.923 plugin-container[1935:14686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 02:28:59 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:59 INFO - PROCESS | 1932 | 2016-12-12 02:28:59.923 plugin-container[1935:14686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 02:28:59 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:59 INFO - PROCESS | 1932 | 2016-12-12 02:28:59.923 plugin-container[1935:14686] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:59 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 02:28:59 INFO - PROCESS | 1932 | colorDepth:24 02:28:59 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 02:28:59 INFO - PROCESS | 1932 | __metrics 02:29:00 ERROR - PROCESS | 1932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:29:00 INFO - TEST-INFO | 1932: exit 0 02:29:00 INFO - Browser initialized. 02:29:00 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:00 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpPkAipf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:03 INFO - PROCESS | 1936 | 2016-12-12 02:29:03.413 plugin-container[1937:14820] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:29:03 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:03 INFO - PROCESS | 1936 | 2016-12-12 02:29:03.413 plugin-container[1937:14820] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:29:03 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:29:03 INFO - PROCESS | 1936 | 2016-12-12 02:29:03.413 plugin-container[1937:14820] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:29:13 INFO - PROCESS | 1936 | RSS: Main: 229416960 02:29:13 INFO - PROCESS | 1936 | 02:30:02 INFO - PROCESS | 1936 | 1481538602731 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] 02:32:17 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49496/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49496/tests/dromaeo/cssquery-ext.html) 02:32:19 INFO - PROCESS | 1936 | RSS: Main: 232071168 02:32:19 INFO - PROCESS | 1936 | 02:35:21 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49496/tests/dromaeo/cssquery-ext.html (next: http://localhost:49496/tests/dromaeo/cssquery-jquery.html) 02:35:21 INFO - PROCESS | 1936 | RSS: Main: 243171328 02:35:21 INFO - PROCESS | 1936 | 02:38:23 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49496/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49496/tests/dromaeo/cssquery-mootools.html) 02:38:24 INFO - PROCESS | 1936 | RSS: Main: 247209984 02:38:24 INFO - PROCESS | 1936 | 02:41:26 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49496/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49496/tests/dromaeo/cssquery-prototype.html) 02:41:26 INFO - PROCESS | 1936 | RSS: Main: 246460416 02:41:26 INFO - PROCESS | 1936 | 02:44:29 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49496/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49496/tests/dromaeo/cssquery-yui.html) 02:44:30 INFO - PROCESS | 1936 | RSS: Main: 249917440 02:44:30 INFO - PROCESS | 1936 | 02:47:32 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:49496/tests/dromaeo/cssquery-yui.html (next: http://localhost:49496/tests/dromaeo/cssquery-dojo.html) 02:47:33 INFO - PROCESS | 1936 | RSS: Main: 251371520 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | __start_tp_report 02:47:33 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 02:47:33 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 02:47:33 INFO - PROCESS | 1936 | |i|pagename|runs| 02:47:33 INFO - PROCESS | 1936 | |0;dojo.html;3422.9828850855747;3432.5022871683605;3438.8108654024036;3446.2590543033243;3467.9653203467974;5793.33376661684;5864.266966629171;5888.5877988540815;5894.734736936837;5916.911246331309;4845.903081938368;5816.592838501307;5822.883542329151;5848.415158484149;5870.88258234835;5296.3379577552805;5330.866728331784;5332.600054995872;5345.679259244438;5394.703291318976;5399.595030372718;5402.405735369106;5402.621816472849;5428.158635411518;5434.83695489134;5240.502152295526;5266.157414813631;5384.865378365533;5386.165144402605;5402.054640437927;5311.814086506973;5321.680699158062;5354.250404943311;5358.3837858645975;5373.086575282211;5203.739813009334;5296.205569164617;5325.840224793263;5339.492748188916;5384.246205531229;5841.0362290222065;5864.736086876101;5886.117082437669;5887.440693134188;5899.2920849497905;6101.60339577926;6190.659513726743;6192.256929168483;6267.592606480564;6271.6864156792435;6272.059191121369;6284.088807122942;6286.296892336293;6289.905651415233;6305.369463053704;5920.526357891358;5923;5951.732172052225;5971.0744834550715;6000.039993601046;6212.06818977157;6258.998560230432;6287;6388.776392826322;6428.132202152743;6147.262328520606;6149.938500614937;6198;6209.348018458088;6254.186955695731;6263.311035786151;6273.811785646438;6299.559030867796;6309;6324.241091069009;6147.231596050494;6212.06818977148;6270.874582508324;6288.968555157195;6304.684765761694;6111.743676692077;6172.629642221481;6228.22147231596;6230.345813689497;6281.780137695158;4928.112939670822;4946.554810067111;4955.628327875352;4967.875803104879;4982.078315511642;4372.715773474778;4374.956250437455;4428.225060614444;4429.981104346019;4458.777061146994;4886.706797592155;4954.950450495521;5671.035923892948;5935.258092738408;5971.611845230046;6291.213598300213;6298.086777417248;6303.3066362700065;6314.71583778731;6370.299267080617;5981.401859813984;6041.939580604226;6060.484858786965;6069.514438844882;6092;5369.8991706700035;5377.78488860449;5481.75332110056;5537.36320323168;5550.694711790809;5638.407967163471;6394.360563943568;6471.611703297817;6487.967560162169;6501.707423166064;6192.783252586138;6292.150559674568;6296.496280297473;6299.779507717208;6333.429991300805;6488.610683358825;6503.25212600557;6560.015997600398;6585.967070164618;6599.670016499252;5569.331680198402;6140.723667434887;6165;6247.875042499216;6274.309825919237;6140.938590614215;6159.19930409044;6174.475169610454;6227.532935029982;6252.905741495311;6074.088886666858;6148.938510614837;6178.289496707936;6185.752569897154;6188.628682278898;6092.756289748538;6199.411055949678;6201.131841542278;6210.099535567408;6225.190725205694;4659.301104834167;5995.370486098896;6022.969885150546;6034.69826508664;6074.939250607614;6198.566100372931;6227.750889964351;6235.812925612239;6236.719347629459;6248.094026366242;6087.086936959491;6102.420270074336;6117.357677443804;6128.63228206309;6132 02:47:33 INFO - PROCESS | 1936 | |1;ext.html;481.54012917663636;501.51603702427127;503.6323483856784;508.80156738871824;510.95254727807986;38810.41784373233;40698.38952415716;40738;40816.97957551059;40828.591714082926;42394.788026059825;42900.3564946526;42915;42938;42940.71177864669;9193.402428842104;9346.859797103049;9400.482973436458;9404.059594040575;9408.059194080606;9407.764805879839;9478.763030924212;9481.905180948172;9489.620415183384;9500.952495237514;9461.621535138585;9473.715788526355;9513;9523.619055237818;9524.142827145555;7608.619569021582;8304.543250121242;8736.737897863075;9396.48319342436;9490.383125096847;9339.3462457628;9346.813063738688;9431.952840235756;9434.245260379153;9438.197753190918;39942;40102.799486002674;40394.59605403938;40492.19015619701;40728;3364.3248812430934;3545.911352216215;3569.446735755962;3617.801020943847;4017.13631569214;29889;30082.84958575215;30197.547036794465;30238.54642180369;30576.388472230654;3139.6389415217313;3198.3763166182603;3229.6447390786993;3257.4950882612998;3313.96686033139;368226;370519;370895;371060;371129;62081.75836483245;62309.68845155836;62486.68756656188;62544;62958.68520657459;64905.35094648993;65377;65411.672941634984;65415.67292163509;65573;15939.681206376039;15975.920120399323;16151.838481615268;16185.514434566981;16195.919020404823;10216.233782466345;12155.209911355734;12356.505739770308;12365.505379784709;12437.191582547106;16717.49847504577;17975.460736177934;18057.54856128581;18099.909500452413;18155.909220453814;6235.282942461675;6257.8748425031245;6313.2424109107205;6320.778772742932;6392.4886009119155;835.7325655790211;934.6728644974205;945.394947233771;971.4608392342318;988.7775250568565;29706.851465742966;30343.544846826873;30439.543406848916;30630.54054189189;30739.9241026563;991.3258983891023;1008.6721815410021;1013.8580598716186;1065.019064539955;1088.9128869690405;15296.388144474318;15659;15771.842281577037;15845;15857.524274271791;654177;654269;659934;660625;660870;58723.70638146782;58782.70608647015;58813.70593147007;58875.41124588785;59009.40990590125;1336.251699048536;1376.4700590272816;1377.0360747476807;1381.1298881704463;1390.7218556288783;28479.28801779889;29488.82044718273;30236.092917212518;30428.847855761465;30999.690003100583;54970;56598;57015.714921426785;59828.8034239304;59875;498960;504186.479067617;505095;505301;506043;26033.609495857956;26036.958521658922;26348.4730305389;26396.472070558866;26413.735862641126;45442.31836522389;45682.54317456916;45774.77112614416;46652.300215496114;46856.7657161712;30313.696863031088;37783.24433511259;37997.86006419701;38366;39151.021224469616;38143.80928095342;38238.6176138235;39205;39249.8037509822;39300;36303.45544816777;36786.63213367832;37495.62504374921;37633.81183094067;37666.811665941495;33167.668323317426;34577.13557161091;34587.48118778271;34757.65242347645;34913;25410.872945635154;25572.84922178469;25649.615255770805;25933.092341767948;26062.739372606033 02:47:33 INFO - PROCESS | 1936 | |2;jquery.html;326.28055138419785;370.30752492838394;379.09396542263994;386.11194253217593;402.80367309091986;18360;18836.869787812724;19236;20038;20377.4905627359;18450.723239151423;19236.80763192371;19561.90219048903;19688.818670879722;19703;4756.429228492586;5066.873328166788;5362.812301569436;5886.822635472901;5889.263842019747;5621.915671264935;5905.970470147643;5952.970235148819;6030.547708921827;6035.517158627299;4533.50131485538;5795.304563452391;5848.502877255417;5979.551533634973;6263.436290733833;5949.46454819064;6013.939860601404;6096.939030609704;6103.664298463583;6109.908351374733;4932.950670493285;5544.334679838405;5818.679972601505;5825.766969321222;5898.380670029671;12977.480900763958;13037.674058148623;13228.338583070808;13450;24038.918248678852;2125.840561957844;2264.8980795864227;2316.6872472216205;2686.7716244119274;2926.5961894523034;9514.213245841758;9844.114029737286;9865;10242.180625550012;10325.690229293057;1841.9815801841944;1860.3581764291298;1889.9905500472546;1911.5221194701326;1933.468296218537;118604;119326;119821.20268195984;120047.39976300062;120295.3985230077;11496.367699776425;11523.366214858263;11668.008219301455;11780.88219117815;11838;14609;14730.779038314435;14797.92601036988;14812.111273323635;14819.703605927822;8281.21328473794;8397.034341050736;8416.410851240355;8457.53483558398;8572.528510931834;7847.489913155627;7959.920400795918;8022.638981245858;8095.392845536609;8363.456375335705;4391.912161756747;4431;4565.155446242456;4574.336721175478;4640.79116439761;1681.5459825847197;1769.566456218209;1981.0787983587702;1989.7265749920064;2029.6042271756867;754.1711349976285;835.6281454752698;894.8926128864578;955.9150364336529;1032.385730490357;17909.28362865497;18717.064146792607;18719.876807391327;20579.382618521468;20946.79053209479;934.2992755433424;938.6151677812201;956.2254573795109;970.6020531582159;979.4368238263028;10413.062824345845;10820.4048777318;10835.945820271007;10857.40284284372;10871.130309575216;568496;576882;587008;587996;597575;107830.304242393;114574.42712786383;122051;127609;128303.35848320699;920.558132096584;996.9501524923725;1027.3630349183554;1032.8863824979246;1035.1615191694752;13796.586102416944;18250.817491824913;18865.528361791065;18996.002307126477;19125.713114303016;37381;48139.5186048135;51924.480755191966;53596.732016339665;53790.19314710204;76746;78412;81354.59322703349;81402;81502.59248703718;7536.171021187555;7693.4614576979075;7813.648385822538;8018.398620103399;8179.836403271782;10271.686965689963;12305;12321.383930803244;12709.4280757368;12764.936175319064;9121.589528471368;9546.761330966503;9797.65708200238;9956.506524021164;10518.368897866152;4971.502849715;9019.872515935507;9192.770180745534;9305.86041209396;10408.947955260175;8908.376413650982;10561.947190264307;10985.176111791488;10990.450477476254;11093.389863557582;5998.940010599838;6742.292059333896;7894.723684671009;8143.104258531675;8377.45546539473;8626.439281446686;9235.907640923504;10405.479726013518;10564.947175264075;10892.019718225398 02:47:33 INFO - PROCESS | 1936 | |3;mootools.html;1457.118443341778;1496.2593516209479;1620.3194658243538;1731.6882961067004;1750.4398592450414;6304.59020163689;6663.86672266555;7625.6949722011195;8202.343812495;8268.958655206723;7822.10045844728;8099.149589293128;8142.511449313045;8162.6326815293305;8196.590170491467;2307.238611258289;2329.1847853251397;2330.8951097200584;2333.6849525314037;2338.941526461835;2328.138588722175;2360.7875291223786;2371.739108698042;2377.667126602277;2380.809535237177;2285.051703543036;2285.828562857784;2295.7130358705163;2307.9307620771406;2314.733805612351;2079.812816846476;2288.9356449251095;2292.1404473322505;2320.0487800002;2324.1516846351046;2075.3151460018157;2299.344686764281;2307.8846057697046;2335.637976113705;2364.5861974154454;8015.47899386538;8071.757847264592;8184.2224988625985;8188.14024527422;8196.795080122985;4213.705040647156;5088.262201980692;5148.639595228298;5160.8193713220235;5193.584513238932;3230.2085988932627;3437.9828100859586;3492.358591462009;3585.8386372613295;3603.855845766166;1022.8823685276215;1043.7234132954773;1092.9344239345664;1106.9889301106966;1111.066703968662;10410.843837342445;10471.424071676054;10519.474026298654;10565.207609429324;10585.84121238182;4438.201123797683;4541.841035563805;4601.217792975135;4632.444106707217;4671.462781780138;9053.728388148234;9175.128362805523;9175.816483670289;9207.493587852732;9219.585118669676;5026.396832380138;5073.188289873602;5141.845744627667;5156;5156.1750119980625;4248.702590818613;4383.123375324948;4567.177907976529;4717.976410117927;4737.502562230985;3796.4495148203355;3839.404892241707;3890.2414029264587;3918.8628398006504;3924.4505769192338;1272.3193091695898;1275.171138759795;1281.9807702884464;1288.5940928607458;1297.209850406452;754.8754455514888;794.682127149145;884.7788052986754;907.4691305586174;917.8760867282831;5320.973395133077;5490.670559766427;5545;5623.156526520973;5755.079187330007;882.2633101360308;887.4098724348236;907.8365894138987;920.3741455810114;942.5711301357866;3779.905502362408;3791.33651610967;3810.3903375459795;3818.980905095457;3842.8078596070086;9686;9980.700578982642;10014.29899907014;10024.54889529973;10105.343152695052;9899.65351212704;9940.055694708992;10020.549075291628;10023.097921186982;10024.248181386425;6298.653574053471;7494.437917156235;7545.358644515278;7777.72777952769;7788.649510772028;4692.577668009895;5559.916601251065;5662.971685141548;5665.178549110438;5933.762649493972;7381.741639042608;7969.681212751657;8622.525761083201;9009.144131307516;9128;9123.087691230823;9151.63393464254;9258.53707314645;9325.813483730151;9377.624895004123;6417.550771445954;6609.4712423005085;6788.049673045679;6797.660116994031;7048.788536343918;7571.886421703569;7688.807779805549;7697.230276972258;7712.691492340244;7758.88361674564;5318.308619879391;6336.493080553637;6832;7029.26192749754;7048;6829.795106146824;6872.759453419107;6909.481788865754;6929.3070692930305;6930.549514281555;6345.206849143857;6503.122078519528;6534.8366290843105;6589.27517973013;6602.1747281589805;4794.328793968777;4831.855044348676;4837.322774811459;4856.538628830255;4871.561559459666;4880.1215781159735;4880.902381952412;4915.21356582945;4942.555170034714;4947.035328110984 02:47:33 INFO - PROCESS | 1936 | |4;prototype.html;268.67221989173225;298.19784778944637;300.79395614004403;311.3912301450664;317.21805748829144;5175.870603234921;5283.547024568245;5396.947595218934;5517.641353312037;5523.420040895703;5077.111505486534;5156.948430515694;5306.70813105279;5308;5318.840434786963;4146.606072423123;4178.101708132751;4194.874153775391;4217.388478670591;4218;3839.308924393608;4098.774567398792;4217.599328063844;4224.704270701052;4245.299525578276;4046.8785936421955;4069.450624165744;4070.5929407059352;4081.938770918424;4129.380592911058;4062.045419326456;4087.7736678996184;4104.794760261975;4119.876403707908;4124.1957818225455;3542.291541691672;3543.7342199335153;3569.5716514018486;3584.6953008994265;3586.8923932282073;4519.706219095749;4884.096442158212;5197.974010129963;5282.0756367635895;5339.252504649352;3795.070207799079;4627.375304333906;4648.418947631546;4670.789814458358;4704.952950470486;4482.977585112086;4864.343313652683;4943.678660887031;5655.773769049233;5745.770169193227;2977.5087110626937;3256.5929258842643;3733.711869405051;3790.2798468290935;3881.1849511602313;4744.804439139137;5180.8445746627285;5263.578913686896;6036.8188954331445;6047.879042419171;3528.1708798432346;3684.797336146538;3927.980360098238;3952;3959.208158368338;3329.933401332008;3898.1618951924906;3941.940870886939;3943.7795299062336;4013.3578627419474;3899.963537738427;4537.863864084083;4653.045720797273;4695.3426520287185;4701.741404222732;5070.923936140961;5142.30578871855;5419.701916394557;5520.1167813149705;5851.795187168429;1051.0015485289007;1217.8721234270452;1239.6157191270736;1297.0544183724414;1299.6750812296925;823.4482896459386;841.9789505262418;852.424613385962;869.452245085592;871.9084496127942;4707.529247075265;4763.023580166057;5009.348784658044;5059.569936555434;5080.644354895196;4818.963922756624;5477.4796394342475;5479.589030822704;5487.686155474459;5579.609427340129;4391.385206071152;4597.908041839144;4731.787069581877;4741.976290118528;5058;2637.7757890579132;2695.622233643893;2767.6955534891144;2819;2914.499032998008;5353.9732301338245;5661.405552417019;5709;5724.1968779834215;5803;4774.904501909942;5116;5349.384820745664;5723.427657234243;5742.483176514133;5163.948360516422;5562.972185139049;5842.415758424208;5849.707514624167;5856.297244330707;4708.387909571734;5317.069512835315;5469.917951230815;5508.697021663847;5614.719264036864;4868.780904859361;5218.6868787872845;5319.792286201338;5369.791796845679;5557.138643510262;4558.6808923376375;5460.5085542301385;5808.050525642604;5845.106672197289;5857.882842343044;3301.5377847100945;3465.2896156287397;3688.95263128966;3752.1557649528636;3759.71802114837;5335.626506144533;5443.755031023693;5830.737616807169;5864.179014937827;5988.341282458839;4920.581750551243;5476.972615136899;5636.549076073986;5666.829995099989;5768.278965129359;4818.566329030404;5329.866753331043;5401.243825864303;5423.60015182089;5569.749361278671;4637.07258548285;4749.810007599795;5109.156989096758;5472.440354498949;5577.972110139423;5125.538701516882;5289.804731036576;5380.865478363072;5827.854303642443;5838.737256823369;5377.704226267437;5668.688222147656;5690.715464226855;5748.798792042258;5764.596478246483 02:47:33 INFO - PROCESS | 1936 | |5;yui.html;692.8579641173557;709.3686618909172;772.9420293477987;794.5034353529045;796.6255859745918;14484;14950.177740224284;15062.397504099823;15170.089794612331;15180.924095379522;13410.329483525833;13442.39509222085;13502.392392342344;13630.454781808741;13648.454061837514;801.0507548554951;802.8434455281223;821.704186492862;827.7599496146111;827.9089300176976;659.877922584321;690.7237105157927;751.7030772844723;780.4926797581561;829.5934991853978;777.0597576931891;852.9019162796264;859.5272600069968;859.6346552715103;864.2265172670457;828.3414685325181;842.1872892658583;847.0809172048304;848.3594885861197;851.3146916732028;631.6936285901335;637.8118455055769;638.9968050159766;639.6002498438476;640.7437025189914;9718.659846905324;10784.784304313947;12459.12786104973;12580.370981450891;12622.431990560446;11139.610113646064;11444.484998175101;11510.769784604261;11588.6523404298;11621.535138594447;8628.22345988864;10501.369917804956;11568.24806387582;11916.165868389216;12035.699107522294;4327.112941846914;4355.7822108894425;4376.102898905749;4394.780260986938;4419.071994881079;41631;76279.09302267432;79437.60281198614;79891;85802.57098714529;10508.579656813641;10578.260417646145;10579.4710264488;10923.672289831318;11013.063889569316;15977;18699.532511687317;20318.898405507876;20447;20700.275490357766;7547;7682.500637458547;7782.610869456617;7881.763547093597;8050.5169689818795;3639.6360363963922;4106.733062350938;4199.286121359376;4278.700490965664;4297.419848320499;2857.557078652812;3301.6698330167274;3379.864805407756;3422.435298175823;3425.9486107708403;577.4225774225774;623.4451338308909;626.858956734731;632.9651869147149;698.9860202795916;586.633354153654;618.5144661440837;670.6713710281927;673.4881490067584;684.7261095561715;8044.798880028046;9197.908020919705;9208.217301529445;9258.398204116711;9265.95367023174;650.2132419772034;656.3993945539778;661.3584822721951;672.1396612336217;681.5944513014748;4387.627051700578;4463.732176069446;4488.910221795546;4498.865034048984;4522.66080043998;70631.64684176649;87260.81847953725;99863.50068249612;110381;114803;58667;85712;94246.0575394251;95454;97933;65028.8588145613;80808;83122;85475.57262213774;85701;9221.400608960665;9251.074892510695;9424.717258482257;9582.281328900499;9629;50077.74961125171;51364;54340.72829635826;55479.445205547425;57099.14351284651;74138.62930685528;76062.61968690343;76652.61673691595;78458.25396111108;79385;11866.584669536525;12184.756304873676;12270.079744019056;12671.366431678563;12737.490500380252;13706.971977101557;13744;13931.512397066053;14081.436742530594;14408.20754858493;12956.714451144016;13150.539731109366;13217.801732974205;13393.866061339262;13426.462941482621;11090.334579925231;11243.49404276826;12201.840825121599;13087.41106650184;13418.597442076752;11159.330440173615;12284.32436215981;12536.185147965352;12872.485100595872;13285.402156903156;9513.952430238081;10570.78858422812;11223.831642525533;11430.199886008233;11508.13688973347;9655.758606034906;11468.713282168013;11581.478833452345;11588.88411115878;11642 02:47:33 INFO - PROCESS | 1936 | __end_tp_report 02:47:33 INFO - PROCESS | 1936 | __start_cc_report 02:47:33 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,366 02:47:33 INFO - PROCESS | 1936 | __end_cc_report 02:47:33 INFO - PROCESS | 1936 | __startTimestamp1481539653249__endTimestamp 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | ------- Summary: start ------- 02:47:33 INFO - PROCESS | 1936 | Number of tests: 6 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | [#0] dojo.html Cycles:165 Average:5811.85 Median:6087.09 stddev:653.08 (10.7%) stddev-sans-first:627.62 02:47:33 INFO - PROCESS | 1936 | Values: 3423.0 3432.5 3438.8 3446.3 3468.0 5793.3 5864.3 5888.6 5894.7 5916.9 4845.9 5816.6 5822.9 5848.4 5870.9 5296.3 5330.9 5332.6 5345.7 5394.7 5399.6 5402.4 5402.6 5428.2 5434.8 5240.5 5266.2 5384.9 5386.2 5402.1 5311.8 5321.7 5354.3 5358.4 5373.1 5203.7 5296.2 5325.8 5339.5 5384.2 5841.0 5864.7 5886.1 5887.4 5899.3 6101.6 6190.7 6192.3 6267.6 6271.7 6272.1 6284.1 6286.3 6289.9 6305.4 5920.5 5923.0 5951.7 5971.1 6000.0 6212.1 6259.0 6287.0 6388.8 6428.1 6147.3 6149.9 6198.0 6209.3 6254.2 6263.3 6273.8 6299.6 6309.0 6324.2 6147.2 6212.1 6270.9 6289.0 6304.7 6111.7 6172.6 6228.2 6230.3 6281.8 4928.1 4946.6 4955.6 4967.9 4982.1 4372.7 4375.0 4428.2 4430.0 4458.8 4886.7 4955.0 5671.0 5935.3 5971.6 6291.2 6298.1 6303.3 6314.7 6370.3 5981.4 6041.9 6060.5 6069.5 6092.0 5369.9 5377.8 5481.8 5537.4 5550.7 5638.4 6394.4 6471.6 6488.0 6501.7 6192.8 6292.2 6296.5 6299.8 6333.4 6488.6 6503.3 6560.0 6586.0 6599.7 5569.3 6140.7 6165.0 6247.9 6274.3 6140.9 6159.2 6174.5 6227.5 6252.9 6074.1 6148.9 6178.3 6185.8 6188.6 6092.8 6199.4 6201.1 6210.1 6225.2 4659.3 5995.4 6023.0 6034.7 6074.9 6198.6 6227.8 6235.8 6236.7 6248.1 6087.1 6102.4 6117.4 6128.6 6132.0 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | [#1] ext.html Cycles:180 Average:65574.47 Median:22059.97 stddev:140072.56 (635.0%) stddev-sans-first:140380.24 02:47:33 INFO - PROCESS | 1936 | Values: 481.5 501.5 503.6 508.8 511.0 38810.4 40698.4 40738.0 40817.0 40828.6 42394.8 42900.4 42915.0 42938.0 42940.7 9193.4 9346.9 9400.5 9404.1 9408.1 9407.8 9478.8 9481.9 9489.6 9501.0 9461.6 9473.7 9513.0 9523.6 9524.1 7608.6 8304.5 8736.7 9396.5 9490.4 9339.3 9346.8 9432.0 9434.2 9438.2 39942.0 40102.8 40394.6 40492.2 40728.0 3364.3 3545.9 3569.4 3617.8 4017.1 29889.0 30082.8 30197.5 30238.5 30576.4 3139.6 3198.4 3229.6 3257.5 3314.0 368226.0 370519.0 370895.0 371060.0 371129.0 62081.8 62309.7 62486.7 62544.0 62958.7 64905.4 65377.0 65411.7 65415.7 65573.0 15939.7 15975.9 16151.8 16185.5 16195.9 10216.2 12155.2 12356.5 12365.5 12437.2 16717.5 17975.5 18057.5 18099.9 18155.9 6235.3 6257.9 6313.2 6320.8 6392.5 835.7 934.7 945.4 971.5 988.8 29706.9 30343.5 30439.5 30630.5 30739.9 991.3 1008.7 1013.9 1065.0 1088.9 15296.4 15659.0 15771.8 15845.0 15857.5 654177.0 654269.0 659934.0 660625.0 660870.0 58723.7 58782.7 58813.7 58875.4 59009.4 1336.3 1376.5 1377.0 1381.1 1390.7 28479.3 29488.8 30236.1 30428.8 30999.7 54970.0 56598.0 57015.7 59828.8 59875.0 498960.0 504186.5 505095.0 505301.0 506043.0 26033.6 26037.0 26348.5 26396.5 26413.7 45442.3 45682.5 45774.8 46652.3 46856.8 30313.7 37783.2 37997.9 38366.0 39151.0 38143.8 38238.6 39205.0 39249.8 39300.0 36303.5 36786.6 37495.6 37633.8 37666.8 33167.7 34577.1 34587.5 34757.7 34913.0 25410.9 25572.8 25649.6 25933.1 26062.7 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | [#2] jquery.html Cycles:180 Average:33771.76 Median:2397.36 stddev:97523.12 (4067.9%) stddev-sans-first:97764.37 02:47:33 INFO - PROCESS | 1936 | Values: 326.3 370.3 379.1 386.1 402.8 18360.0 18836.9 19236.0 20038.0 20377.5 18450.7 19236.8 19561.9 19688.8 19703.0 4756.4 5066.9 5362.8 5886.8 5889.3 5621.9 5906.0 5953.0 6030.5 6035.5 4533.5 5795.3 5848.5 5979.6 6263.4 5949.5 6013.9 6096.9 6103.7 6109.9 4933.0 5544.3 5818.7 5825.8 5898.4 12977.5 13037.7 13228.3 13450.0 24038.9 2125.8 2264.9 2316.7 2686.8 2926.6 9514.2 9844.1 9865.0 10242.2 10325.7 1842.0 1860.4 1890.0 1911.5 1933.5 118604.0 119326.0 119821.2 120047.4 120295.4 11496.4 11523.4 11668.0 11780.9 11838.0 14609.0 14730.8 14797.9 14812.1 14819.7 8281.2 8397.0 8416.4 8457.5 8572.5 7847.5 7959.9 8022.6 8095.4 8363.5 4391.9 4431.0 4565.2 4574.3 4640.8 1681.5 1769.6 1981.1 1989.7 2029.6 754.2 835.6 894.9 955.9 1032.4 17909.3 18717.1 18719.9 20579.4 20946.8 934.3 938.6 956.2 970.6 979.4 10413.1 10820.4 10835.9 10857.4 10871.1 568496.0 576882.0 587008.0 587996.0 597575.0 107830.3 114574.4 122051.0 127609.0 128303.4 920.6 997.0 1027.4 1032.9 1035.2 13796.6 18250.8 18865.5 18996.0 19125.7 37381.0 48139.5 51924.5 53596.7 53790.2 76746.0 78412.0 81354.6 81402.0 81502.6 7536.2 7693.5 7813.6 8018.4 8179.8 10271.7 12305.0 12321.4 12709.4 12764.9 9121.6 9546.8 9797.7 9956.5 10518.4 4971.5 9019.9 9192.8 9305.9 10408.9 8908.4 10561.9 10985.2 10990.5 11093.4 5998.9 6742.3 7894.7 8143.1 8377.5 8626.4 9235.9 10405.5 10564.9 10892.0 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | [#3] mootools.html Cycles:180 Average:5306.42 Median:5115.05 stddev:2856.41 (55.8%) stddev-sans-first:2849.77 02:47:33 INFO - PROCESS | 1936 | Values: 1457.1 1496.3 1620.3 1731.7 1750.4 6304.6 6663.9 7625.7 8202.3 8269.0 7822.1 8099.1 8142.5 8162.6 8196.6 2307.2 2329.2 2330.9 2333.7 2338.9 2328.1 2360.8 2371.7 2377.7 2380.8 2285.1 2285.8 2295.7 2307.9 2314.7 2079.8 2288.9 2292.1 2320.0 2324.2 2075.3 2299.3 2307.9 2335.6 2364.6 8015.5 8071.8 8184.2 8188.1 8196.8 4213.7 5088.3 5148.6 5160.8 5193.6 3230.2 3438.0 3492.4 3585.8 3603.9 1022.9 1043.7 1092.9 1107.0 1111.1 10410.8 10471.4 10519.5 10565.2 10585.8 4438.2 4541.8 4601.2 4632.4 4671.5 9053.7 9175.1 9175.8 9207.5 9219.6 5026.4 5073.2 5141.8 5156.0 5156.2 4248.7 4383.1 4567.2 4718.0 4737.5 3796.4 3839.4 3890.2 3918.9 3924.5 1272.3 1275.2 1282.0 1288.6 1297.2 754.9 794.7 884.8 907.5 917.9 5321.0 5490.7 5545.0 5623.2 5755.1 882.3 887.4 907.8 920.4 942.6 3779.9 3791.3 3810.4 3819.0 3842.8 9686.0 9980.7 10014.3 10024.5 10105.3 9899.7 9940.1 10020.5 10023.1 10024.2 6298.7 7494.4 7545.4 7777.7 7788.6 4692.6 5559.9 5663.0 5665.2 5933.8 7381.7 7969.7 8622.5 9009.1 9128.0 9123.1 9151.6 9258.5 9325.8 9377.6 6417.6 6609.5 6788.0 6797.7 7048.8 7571.9 7688.8 7697.2 7712.7 7758.9 5318.3 6336.5 6832.0 7029.3 7048.0 6829.8 6872.8 6909.5 6929.3 6930.5 6345.2 6503.1 6534.8 6589.3 6602.2 4794.3 4831.9 4837.3 4856.5 4871.6 4880.1 4880.9 4915.2 4942.6 4947.0 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | [#4] prototype.html Cycles:180 Average:4474.53 Median:4976.51 stddev:1371.96 (27.6%) stddev-sans-first:1339.00 02:47:33 INFO - PROCESS | 1936 | Values: 268.7 298.2 300.8 311.4 317.2 5175.9 5283.5 5396.9 5517.6 5523.4 5077.1 5156.9 5306.7 5308.0 5318.8 4146.6 4178.1 4194.9 4217.4 4218.0 3839.3 4098.8 4217.6 4224.7 4245.3 4046.9 4069.5 4070.6 4081.9 4129.4 4062.0 4087.8 4104.8 4119.9 4124.2 3542.3 3543.7 3569.6 3584.7 3586.9 4519.7 4884.1 5198.0 5282.1 5339.3 3795.1 4627.4 4648.4 4670.8 4705.0 4483.0 4864.3 4943.7 5655.8 5745.8 2977.5 3256.6 3733.7 3790.3 3881.2 4744.8 5180.8 5263.6 6036.8 6047.9 3528.2 3684.8 3928.0 3952.0 3959.2 3329.9 3898.2 3941.9 3943.8 4013.4 3900.0 4537.9 4653.0 4695.3 4701.7 5070.9 5142.3 5419.7 5520.1 5851.8 1051.0 1217.9 1239.6 1297.1 1299.7 823.4 842.0 852.4 869.5 871.9 4707.5 4763.0 5009.3 5059.6 5080.6 4819.0 5477.5 5479.6 5487.7 5579.6 4391.4 4597.9 4731.8 4742.0 5058.0 2637.8 2695.6 2767.7 2819.0 2914.5 5354.0 5661.4 5709.0 5724.2 5803.0 4774.9 5116.0 5349.4 5723.4 5742.5 5163.9 5563.0 5842.4 5849.7 5856.3 4708.4 5317.1 5469.9 5508.7 5614.7 4868.8 5218.7 5319.8 5369.8 5557.1 4558.7 5460.5 5808.1 5845.1 5857.9 3301.5 3465.3 3689.0 3752.2 3759.7 5335.6 5443.8 5830.7 5864.2 5988.3 4920.6 5477.0 5636.5 5666.8 5768.3 4818.6 5329.9 5401.2 5423.6 5569.7 4637.1 4749.8 5109.2 5472.4 5578.0 5125.5 5289.8 5380.9 5827.9 5838.7 5377.7 5668.7 5690.7 5748.8 5764.6 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - PROCESS | 1936 | [#5] yui.html Cycles:180 Average:19276.18 Median:52852.36 stddev:27634.84 (52.3%) stddev-sans-first:27677.13 02:47:33 INFO - PROCESS | 1936 | Values: 692.9 709.4 772.9 794.5 796.6 14484.0 14950.2 15062.4 15170.1 15180.9 13410.3 13442.4 13502.4 13630.5 13648.5 801.1 802.8 821.7 827.8 827.9 659.9 690.7 751.7 780.5 829.6 777.1 852.9 859.5 859.6 864.2 828.3 842.2 847.1 848.4 851.3 631.7 637.8 639.0 639.6 640.7 9718.7 10784.8 12459.1 12580.4 12622.4 11139.6 11444.5 11510.8 11588.7 11621.5 8628.2 10501.4 11568.2 11916.2 12035.7 4327.1 4355.8 4376.1 4394.8 4419.1 41631.0 76279.1 79437.6 79891.0 85802.6 10508.6 10578.3 10579.5 10923.7 11013.1 15977.0 18699.5 20318.9 20447.0 20700.3 7547.0 7682.5 7782.6 7881.8 8050.5 3639.6 4106.7 4199.3 4278.7 4297.4 2857.6 3301.7 3379.9 3422.4 3425.9 577.4 623.4 626.9 633.0 699.0 586.6 618.5 670.7 673.5 684.7 8044.8 9197.9 9208.2 9258.4 9266.0 650.2 656.4 661.4 672.1 681.6 4387.6 4463.7 4488.9 4498.9 4522.7 70631.6 87260.8 99863.5 110381.0 114803.0 58667.0 85712.0 94246.1 95454.0 97933.0 65028.9 80808.0 83122.0 85475.6 85701.0 9221.4 9251.1 9424.7 9582.3 9629.0 50077.7 51364.0 54340.7 55479.4 57099.1 74138.6 76062.6 76652.6 78458.3 79385.0 11866.6 12184.8 12270.1 12671.4 12737.5 13707.0 13744.0 13931.5 14081.4 14408.2 12956.7 13150.5 13217.8 13393.9 13426.5 11090.3 11243.5 12201.8 13087.4 13418.6 11159.3 12284.3 12536.2 12872.5 13285.4 9514.0 10570.8 11223.8 11430.2 11508.1 9655.8 11468.7 11581.5 11588.9 11642.0 02:47:33 INFO - PROCESS | 1936 | -------- Summary: end -------- 02:47:33 INFO - PROCESS | 1936 | 02:47:33 INFO - TEST-INFO | 1936: exit 0 02:47:33 INFO - TEST-OK | dromaeo_css | took 1117197ms 02:47:33 INFO - TEST-START | kraken 02:47:33 INFO - operating with platform_type : mac_ 02:47:33 INFO - Initialising browser for kraken test... 02:47:33 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp7Y5rQY/profile http://localhost:49496/getInfo.html) 02:47:36 INFO - PROCESS | 1941 | 2016-12-12 02:47:36.882 plugin-container[1943:16859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 02:47:36 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:36 INFO - PROCESS | 1941 | 2016-12-12 02:47:36.882 plugin-container[1943:16859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:47:36 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:36 INFO - PROCESS | 1941 | 2016-12-12 02:47:36.882 plugin-container[1943:16859] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:47:36 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 02:47:36 INFO - PROCESS | 1941 | colorDepth:24 02:47:36 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 02:47:36 INFO - PROCESS | 1941 | __metrics 02:47:37 ERROR - PROCESS | 1941 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:47:37 INFO - TEST-INFO | 1941: exit 0 02:47:37 INFO - Browser initialized. 02:47:37 INFO - Running cycle 1/1 for kraken test... 02:47:37 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp7Y5rQY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:40 INFO - PROCESS | 1944 | 2016-12-12 02:47:40.644 plugin-container[1945:16988] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:47:40 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:40 INFO - PROCESS | 1944 | 2016-12-12 02:47:40.645 plugin-container[1945:16988] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:47:40 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:40 INFO - PROCESS | 1944 | 2016-12-12 02:47:40.645 plugin-container[1945:16988] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:47:50 INFO - PROCESS | 1944 | RSS: Main: 229486592 02:47:50 INFO - PROCESS | 1944 | 02:47:53 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49496/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:54 INFO - PROCESS | 1944 | RSS: Main: 241528832 02:47:54 INFO - PROCESS | 1944 | 02:47:57 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49496/tests/kraken/driver.html?testName=audio-dft) 02:47:57 INFO - PROCESS | 1944 | RSS: Main: 249233408 02:47:57 INFO - PROCESS | 1944 | 02:48:01 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49496/tests/kraken/driver.html?testName=audio-fft) 02:48:02 INFO - PROCESS | 1944 | RSS: Main: 251031552 02:48:02 INFO - PROCESS | 1944 | 02:48:03 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49496/tests/kraken/driver.html?testName=audio-oscillator) 02:48:04 INFO - PROCESS | 1944 | RSS: Main: 253681664 02:48:04 INFO - PROCESS | 1944 | 02:48:06 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49496/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:07 INFO - PROCESS | 1944 | RSS: Main: 254324736 02:48:07 INFO - PROCESS | 1944 | 02:48:09 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49496/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:10 INFO - PROCESS | 1944 | RSS: Main: 257552384 02:48:10 INFO - PROCESS | 1944 | 02:48:13 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49496/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:14 INFO - PROCESS | 1944 | RSS: Main: 257654784 02:48:14 INFO - PROCESS | 1944 | 02:48:17 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49496/tests/kraken/driver.html?testName=json-parse-financial) 02:48:17 INFO - PROCESS | 1944 | RSS: Main: 257785856 02:48:17 INFO - PROCESS | 1944 | 02:48:19 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49496/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:20 INFO - PROCESS | 1944 | RSS: Main: 257134592 02:48:20 INFO - PROCESS | 1944 | 02:48:22 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:22 INFO - PROCESS | 1944 | RSS: Main: 257019904 02:48:22 INFO - PROCESS | 1944 | 02:48:25 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:25 INFO - PROCESS | 1944 | RSS: Main: 256999424 02:48:25 INFO - PROCESS | 1944 | 02:48:28 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:29 INFO - PROCESS | 1944 | RSS: Main: 257282048 02:48:29 INFO - PROCESS | 1944 | 02:48:32 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:33 INFO - PROCESS | 1944 | RSS: Main: 257187840 02:48:33 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:49496/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49496/tests/kraken/driver.html?testName=ai-astar) 02:48:35 INFO - PROCESS | 1944 | RSS: Main: 260202496 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | __start_tp_report 02:48:35 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 02:48:35 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 02:48:35 INFO - PROCESS | 1944 | |i|pagename|runs| 02:48:35 INFO - PROCESS | 1944 | |0;ai-astar;106;118;102;173;173;169;117;122;156;176 02:48:35 INFO - PROCESS | 1944 | |1;audio-beat-detection;148;116;116;149;117;116;118;117;144;118 02:48:35 INFO - PROCESS | 1944 | |2;audio-dft;261;253;192;194;193;253;278;193;276;279 02:48:35 INFO - PROCESS | 1944 | |3;audio-fft;82;58;61;82;62;60;61;63;80;59 02:48:35 INFO - PROCESS | 1944 | |4;audio-oscillator;85;69;88;72;70;71;87;69;70;87 02:48:35 INFO - PROCESS | 1944 | |5;imaging-gaussian-blur;89;86;88;87;90;85;92;88;89;87 02:48:35 INFO - PROCESS | 1944 | |6;imaging-darkroom;151;117;96;95;95;94;96;97;134;103 02:48:35 INFO - PROCESS | 1944 | |7;imaging-desaturate;100;93;87;87;92;132;116;136;138;142 02:48:35 INFO - PROCESS | 1944 | |8;json-parse-financial;56;56;56;61;61;77;71;79;89;88 02:48:35 INFO - PROCESS | 1944 | |9;json-stringify-tinderbox;75;78;80;82;75;72;85;101;102;73 02:48:35 INFO - PROCESS | 1944 | |10;stanford-crypto-aes;83;87;87;82;101;78;89;106;105;107 02:48:35 INFO - PROCESS | 1944 | |11;stanford-crypto-ccm;150;165;151;146;160;169;166;179;194;194 02:48:35 INFO - PROCESS | 1944 | |12;stanford-crypto-pbkdf2;187;230;190;150;209;189;148;231;226;181 02:48:35 INFO - PROCESS | 1944 | |13;stanford-crypto-sha256-iterative;71;71;68;70;74;62;79;87;90;88 02:48:35 INFO - PROCESS | 1944 | __end_tp_report 02:48:35 INFO - PROCESS | 1944 | __start_cc_report 02:48:35 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,985 02:48:35 INFO - PROCESS | 1944 | __end_cc_report 02:48:35 INFO - PROCESS | 1944 | __startTimestamp1481539715553__endTimestamp 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | ------- Summary: start ------- 02:48:35 INFO - PROCESS | 1944 | Number of tests: 14 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#0] ai-astar Cycles:10 Average:141.20 Median:162.50 stddev:30.72 (18.9%) stddev-sans-first:29.83 02:48:35 INFO - PROCESS | 1944 | Values: 106.0 118.0 102.0 173.0 173.0 169.0 117.0 122.0 156.0 176.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:118.00 stddev:14.63 (12.4%) stddev-sans-first:13.15 02:48:35 INFO - PROCESS | 1944 | Values: 148.0 116.0 116.0 149.0 117.0 116.0 118.0 117.0 144.0 118.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#2] audio-dft Cycles:10 Average:237.20 Median:257.00 stddev:39.16 (15.2%) stddev-sans-first:40.57 02:48:35 INFO - PROCESS | 1944 | Values: 261.0 253.0 192.0 194.0 193.0 253.0 278.0 193.0 276.0 279.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.50 stddev:10.14 (16.2%) stddev-sans-first:9.14 02:48:35 INFO - PROCESS | 1944 | Values: 82.0 58.0 61.0 82.0 62.0 60.0 61.0 63.0 80.0 59.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:78.50 stddev:8.64 (11.0%) stddev-sans-first:8.64 02:48:35 INFO - PROCESS | 1944 | Values: 85.0 69.0 88.0 72.0 70.0 71.0 87.0 69.0 70.0 87.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:88.50 stddev:2.02 (2.3%) stddev-sans-first:2.12 02:48:35 INFO - PROCESS | 1944 | Values: 89.0 86.0 88.0 87.0 90.0 85.0 92.0 88.0 89.0 87.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:95.00 stddev:19.93 (21.0%) stddev-sans-first:13.69 02:48:35 INFO - PROCESS | 1944 | Values: 151.0 117.0 96.0 95.0 95.0 94.0 96.0 97.0 134.0 103.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#7] imaging-desaturate Cycles:10 Average:112.30 Median:114.50 stddev:22.91 (20.0%) stddev-sans-first:23.86 02:48:35 INFO - PROCESS | 1944 | Values: 100.0 93.0 87.0 87.0 92.0 132.0 116.0 136.0 138.0 142.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:74.00 stddev:13.18 (17.8%) stddev-sans-first:13.05 02:48:35 INFO - PROCESS | 1944 | Values: 56.0 56.0 56.0 61.0 61.0 77.0 71.0 79.0 89.0 88.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#9] json-stringify-tinderbox Cycles:10 Average:82.30 Median:76.50 stddev:10.89 (14.2%) stddev-sans-first:11.23 02:48:35 INFO - PROCESS | 1944 | Values: 75.0 78.0 80.0 82.0 75.0 72.0 85.0 101.0 102.0 73.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:82.50 stddev:11.08 (13.4%) stddev-sans-first:11.20 02:48:35 INFO - PROCESS | 1944 | Values: 83.0 87.0 87.0 82.0 101.0 78.0 89.0 106.0 105.0 107.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#11] stanford-crypto-ccm Cycles:10 Average:167.40 Median:167.50 stddev:17.14 (10.2%) stddev-sans-first:16.99 02:48:35 INFO - PROCESS | 1944 | Values: 150.0 165.0 151.0 146.0 160.0 169.0 166.0 179.0 194.0 194.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.10 Median:199.50 stddev:30.23 (15.2%) stddev-sans-first:31.95 02:48:35 INFO - PROCESS | 1944 | Values: 187.0 230.0 190.0 150.0 209.0 189.0 148.0 231.0 226.0 181.0 02:48:35 INFO - PROCESS | 1944 | 02:48:35 INFO - PROCESS | 1944 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:76.50 stddev:9.55 (12.5%) stddev-sans-first:9.95 02:48:35 INFO - PROCESS | 1944 | Values: 71.0 71.0 68.0 70.0 74.0 62.0 79.0 87.0 90.0 88.0 02:48:35 INFO - PROCESS | 1944 | -------- Summary: end -------- 02:48:35 INFO - PROCESS | 1944 | 02:48:36 INFO - TEST-INFO | 1944: exit 0 02:48:36 INFO - TEST-OK | kraken | took 62817ms 02:48:36 INFO - SUITE-END | took 1180s 02:48:37 INFO - Completed test suite (00:19:40) 02:48:37 INFO - javascript benchmark 02:48:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7107.564052682305, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3422.9828850855747, 3432.5022871683605, 3438.8108654024036, 3446.2590543033243, 3467.9653203467974, 5793.33376661684, 5864.266966629171, 5888.5877988540815, 5894.734736936837, 5916.911246331309, 4845.903081938368, 5816.592838501307, 5822.883542329151, 5848.415158484149, 5870.88258234835, 5296.3379577552805, 5330.866728331784, 5332.600054995872, 5345.679259244438, 5394.703291318976, 5399.595030372718, 5402.405735369106, 5402.621816472849, 5428.158635411518, 5434.83695489134, 5240.502152295526, 5266.157414813631, 5384.865378365533, 5386.165144402605, 5402.054640437927, 5311.814086506973, 5321.680699158062, 5354.250404943311, 5358.3837858645975, 5373.086575282211, 5203.739813009334, 5296.205569164617, 5325.840224793263, 5339.492748188916, 5384.246205531229, 5841.0362290222065, 5864.736086876101, 5886.117082437669, 5887.440693134188, 5899.2920849497905, 6101.60339577926, 6190.659513726743, 6192.256929168483, 6267.592606480564, 6271.6864156792435, 6272.059191121369, 6284.088807122942, 6286.296892336293, 6289.905651415233, 6305.369463053704, 5920.526357891358, 5923.0, 5951.732172052225, 5971.0744834550715, 6000.039993601046, 6212.06818977157, 6258.998560230432, 6287.0, 6388.776392826322, 6428.132202152743, 6147.262328520606, 6149.938500614937, 6198.0, 6209.348018458088, 6254.186955695731, 6263.311035786151, 6273.811785646438, 6299.559030867796, 6309.0, 6324.241091069009, 6147.231596050494, 6212.06818977148, 6270.874582508324, 6288.968555157195, 6304.684765761694, 6111.743676692077, 6172.629642221481, 6228.22147231596, 6230.345813689497, 6281.780137695158, 4928.112939670822, 4946.554810067111, 4955.628327875352, 4967.875803104879, 4982.078315511642, 4372.715773474778, 4374.956250437455, 4428.225060614444, 4429.981104346019, 4458.777061146994, 4886.706797592155, 4954.950450495521, 5671.035923892948, 5935.258092738408, 5971.611845230046, 6291.213598300213, 6298.086777417248, 6303.3066362700065, 6314.71583778731, 6370.299267080617, 5981.401859813984, 6041.939580604226, 6060.484858786965, 6069.514438844882, 6092.0, 5369.8991706700035, 5377.78488860449, 5481.75332110056, 5537.36320323168, 5550.694711790809, 5638.407967163471, 6394.360563943568, 6471.611703297817, 6487.967560162169, 6501.707423166064, 6192.783252586138, 6292.150559674568, 6296.496280297473, 6299.779507717208, 6333.429991300805, 6488.610683358825, 6503.25212600557, 6560.015997600398, 6585.967070164618, 6599.670016499252, 5569.331680198402, 6140.723667434887, 6165.0, 6247.875042499216, 6274.309825919237, 6140.938590614215, 6159.19930409044, 6174.475169610454, 6227.532935029982, 6252.905741495311, 6074.088886666858, 6148.938510614837, 6178.289496707936, 6185.752569897154, 6188.628682278898, 6092.756289748538, 6199.411055949678, 6201.131841542278, 6210.099535567408, 6225.190725205694, 4659.301104834167, 5995.370486098896, 6022.969885150546, 6034.69826508664, 6074.939250607614, 6198.566100372931, 6227.750889964351, 6235.812925612239, 6236.719347629459, 6248.094026366242, 6087.086936959491, 6102.420270074336, 6117.357677443804, 6128.63228206309, 6132.0], "value": 5771.168504251479, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.54012917663636, 501.51603702427127, 503.6323483856784, 508.80156738871824, 510.95254727807986, 38810.41784373233, 40698.38952415716, 40738.0, 40816.97957551059, 40828.591714082926, 42394.788026059825, 42900.3564946526, 42915.0, 42938.0, 42940.71177864669, 9193.402428842104, 9346.859797103049, 9400.482973436458, 9404.059594040575, 9408.059194080606, 9407.764805879839, 9478.763030924212, 9481.905180948172, 9489.620415183384, 9500.952495237514, 9461.621535138585, 9473.715788526355, 9513.0, 9523.619055237818, 9524.142827145555, 7608.619569021582, 8304.543250121242, 8736.737897863075, 9396.48319342436, 9490.383125096847, 9339.3462457628, 9346.813063738688, 9431.952840235756, 9434.245260379153, 9438.197753190918, 39942.0, 40102.799486002674, 40394.59605403938, 40492.19015619701, 40728.0, 3364.3248812430934, 3545.911352216215, 3569.446735755962, 3617.801020943847, 4017.13631569214, 29889.0, 30082.84958575215, 30197.547036794465, 30238.54642180369, 30576.388472230654, 3139.6389415217313, 3198.3763166182603, 3229.6447390786993, 3257.4950882612998, 3313.96686033139, 368226.0, 370519.0, 370895.0, 371060.0, 371129.0, 62081.75836483245, 62309.68845155836, 62486.68756656188, 62544.0, 62958.68520657459, 64905.35094648993, 65377.0, 65411.672941634984, 65415.67292163509, 65573.0, 15939.681206376039, 15975.920120399323, 16151.838481615268, 16185.514434566981, 16195.919020404823, 10216.233782466345, 12155.209911355734, 12356.505739770308, 12365.505379784709, 12437.191582547106, 16717.49847504577, 17975.460736177934, 18057.54856128581, 18099.909500452413, 18155.909220453814, 6235.282942461675, 6257.8748425031245, 6313.2424109107205, 6320.778772742932, 6392.4886009119155, 835.7325655790211, 934.6728644974205, 945.394947233771, 971.4608392342318, 988.7775250568565, 29706.851465742966, 30343.544846826873, 30439.543406848916, 30630.54054189189, 30739.9241026563, 991.3258983891023, 1008.6721815410021, 1013.8580598716186, 1065.019064539955, 1088.9128869690405, 15296.388144474318, 15659.0, 15771.842281577037, 15845.0, 15857.524274271791, 654177.0, 654269.0, 659934.0, 660625.0, 660870.0, 58723.70638146782, 58782.70608647015, 58813.70593147007, 58875.41124588785, 59009.40990590125, 1336.251699048536, 1376.4700590272816, 1377.0360747476807, 1381.1298881704463, 1390.7218556288783, 28479.28801779889, 29488.82044718273, 30236.092917212518, 30428.847855761465, 30999.690003100583, 54970.0, 56598.0, 57015.714921426785, 59828.8034239304, 59875.0, 498960.0, 504186.479067617, 505095.0, 505301.0, 506043.0, 26033.609495857956, 26036.958521658922, 26348.4730305389, 26396.472070558866, 26413.735862641126, 45442.31836522389, 45682.54317456916, 45774.77112614416, 46652.300215496114, 46856.7657161712, 30313.696863031088, 37783.24433511259, 37997.86006419701, 38366.0, 39151.021224469616, 38143.80928095342, 38238.6176138235, 39205.0, 39249.8037509822, 39300.0, 36303.45544816777, 36786.63213367832, 37495.62504374921, 37633.81183094067, 37666.811665941495, 33167.668323317426, 34577.13557161091, 34587.48118778271, 34757.65242347645, 34913.0, 25410.872945635154, 25572.84922178469, 25649.615255770805, 25933.092341767948, 26062.739372606033], "value": 19604.823825891963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.28055138419785, 370.30752492838394, 379.09396542263994, 386.11194253217593, 402.80367309091986, 18360.0, 18836.869787812724, 19236.0, 20038.0, 20377.4905627359, 18450.723239151423, 19236.80763192371, 19561.90219048903, 19688.818670879722, 19703.0, 4756.429228492586, 5066.873328166788, 5362.812301569436, 5886.822635472901, 5889.263842019747, 5621.915671264935, 5905.970470147643, 5952.970235148819, 6030.547708921827, 6035.517158627299, 4533.50131485538, 5795.304563452391, 5848.502877255417, 5979.551533634973, 6263.436290733833, 5949.46454819064, 6013.939860601404, 6096.939030609704, 6103.664298463583, 6109.908351374733, 4932.950670493285, 5544.334679838405, 5818.679972601505, 5825.766969321222, 5898.380670029671, 12977.480900763958, 13037.674058148623, 13228.338583070808, 13450.0, 24038.918248678852, 2125.840561957844, 2264.8980795864227, 2316.6872472216205, 2686.7716244119274, 2926.5961894523034, 9514.213245841758, 9844.114029737286, 9865.0, 10242.180625550012, 10325.690229293057, 1841.9815801841944, 1860.3581764291298, 1889.9905500472546, 1911.5221194701326, 1933.468296218537, 118604.0, 119326.0, 119821.20268195984, 120047.39976300062, 120295.3985230077, 11496.367699776425, 11523.366214858263, 11668.008219301455, 11780.88219117815, 11838.0, 14609.0, 14730.779038314435, 14797.92601036988, 14812.111273323635, 14819.703605927822, 8281.21328473794, 8397.034341050736, 8416.410851240355, 8457.53483558398, 8572.528510931834, 7847.489913155627, 7959.920400795918, 8022.638981245858, 8095.392845536609, 8363.456375335705, 4391.912161756747, 4431.0, 4565.155446242456, 4574.336721175478, 4640.79116439761, 1681.5459825847197, 1769.566456218209, 1981.0787983587702, 1989.7265749920064, 2029.6042271756867, 754.1711349976285, 835.6281454752698, 894.8926128864578, 955.9150364336529, 1032.385730490357, 17909.28362865497, 18717.064146792607, 18719.876807391327, 20579.382618521468, 20946.79053209479, 934.2992755433424, 938.6151677812201, 956.2254573795109, 970.6020531582159, 979.4368238263028, 10413.062824345845, 10820.4048777318, 10835.945820271007, 10857.40284284372, 10871.130309575216, 568496.0, 576882.0, 587008.0, 587996.0, 597575.0, 107830.304242393, 114574.42712786383, 122051.0, 127609.0, 128303.35848320699, 920.558132096584, 996.9501524923725, 1027.3630349183554, 1032.8863824979246, 1035.1615191694752, 13796.586102416944, 18250.817491824913, 18865.528361791065, 18996.002307126477, 19125.713114303016, 37381.0, 48139.5186048135, 51924.480755191966, 53596.732016339665, 53790.19314710204, 76746.0, 78412.0, 81354.59322703349, 81402.0, 81502.59248703718, 7536.171021187555, 7693.4614576979075, 7813.648385822538, 8018.398620103399, 8179.836403271782, 10271.686965689963, 12305.0, 12321.383930803244, 12709.4280757368, 12764.936175319064, 9121.589528471368, 9546.761330966503, 9797.65708200238, 9956.506524021164, 10518.368897866152, 4971.502849715, 9019.872515935507, 9192.770180745534, 9305.86041209396, 10408.947955260175, 8908.376413650982, 10561.947190264307, 10985.176111791488, 10990.450477476254, 11093.389863557582, 5998.940010599838, 6742.292059333896, 7894.723684671009, 8143.104258531675, 8377.45546539473, 8626.439281446686, 9235.907640923504, 10405.479726013518, 10564.947175264075, 10892.019718225398], "value": 9141.454123922318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.118443341778, 1496.2593516209479, 1620.3194658243538, 1731.6882961067004, 1750.4398592450414, 6304.59020163689, 6663.86672266555, 7625.6949722011195, 8202.343812495, 8268.958655206723, 7822.10045844728, 8099.149589293128, 8142.511449313045, 8162.6326815293305, 8196.590170491467, 2307.238611258289, 2329.1847853251397, 2330.8951097200584, 2333.6849525314037, 2338.941526461835, 2328.138588722175, 2360.7875291223786, 2371.739108698042, 2377.667126602277, 2380.809535237177, 2285.051703543036, 2285.828562857784, 2295.7130358705163, 2307.9307620771406, 2314.733805612351, 2079.812816846476, 2288.9356449251095, 2292.1404473322505, 2320.0487800002, 2324.1516846351046, 2075.3151460018157, 2299.344686764281, 2307.8846057697046, 2335.637976113705, 2364.5861974154454, 8015.47899386538, 8071.757847264592, 8184.2224988625985, 8188.14024527422, 8196.795080122985, 4213.705040647156, 5088.262201980692, 5148.639595228298, 5160.8193713220235, 5193.584513238932, 3230.2085988932627, 3437.9828100859586, 3492.358591462009, 3585.8386372613295, 3603.855845766166, 1022.8823685276215, 1043.7234132954773, 1092.9344239345664, 1106.9889301106966, 1111.066703968662, 10410.843837342445, 10471.424071676054, 10519.474026298654, 10565.207609429324, 10585.84121238182, 4438.201123797683, 4541.841035563805, 4601.217792975135, 4632.444106707217, 4671.462781780138, 9053.728388148234, 9175.128362805523, 9175.816483670289, 9207.493587852732, 9219.585118669676, 5026.396832380138, 5073.188289873602, 5141.845744627667, 5156.0, 5156.1750119980625, 4248.702590818613, 4383.123375324948, 4567.177907976529, 4717.976410117927, 4737.502562230985, 3796.4495148203355, 3839.404892241707, 3890.2414029264587, 3918.8628398006504, 3924.4505769192338, 1272.3193091695898, 1275.171138759795, 1281.9807702884464, 1288.5940928607458, 1297.209850406452, 754.8754455514888, 794.682127149145, 884.7788052986754, 907.4691305586174, 917.8760867282831, 5320.973395133077, 5490.670559766427, 5545.0, 5623.156526520973, 5755.079187330007, 882.2633101360308, 887.4098724348236, 907.8365894138987, 920.3741455810114, 942.5711301357866, 3779.905502362408, 3791.33651610967, 3810.3903375459795, 3818.980905095457, 3842.8078596070086, 9686.0, 9980.700578982642, 10014.29899907014, 10024.54889529973, 10105.343152695052, 9899.65351212704, 9940.055694708992, 10020.549075291628, 10023.097921186982, 10024.248181386425, 6298.653574053471, 7494.437917156235, 7545.358644515278, 7777.72777952769, 7788.649510772028, 4692.577668009895, 5559.916601251065, 5662.971685141548, 5665.178549110438, 5933.762649493972, 7381.741639042608, 7969.681212751657, 8622.525761083201, 9009.144131307516, 9128.0, 9123.087691230823, 9151.63393464254, 9258.53707314645, 9325.813483730151, 9377.624895004123, 6417.550771445954, 6609.4712423005085, 6788.049673045679, 6797.660116994031, 7048.788536343918, 7571.886421703569, 7688.807779805549, 7697.230276972258, 7712.691492340244, 7758.88361674564, 5318.308619879391, 6336.493080553637, 6832.0, 7029.26192749754, 7048.0, 6829.795106146824, 6872.759453419107, 6909.481788865754, 6929.3070692930305, 6930.549514281555, 6345.206849143857, 6503.122078519528, 6534.8366290843105, 6589.27517973013, 6602.1747281589805, 4794.328793968777, 4831.855044348676, 4837.322774811459, 4856.538628830255, 4871.561559459666, 4880.1215781159735, 4880.902381952412, 4915.21356582945, 4942.555170034714, 4947.035328110984], "value": 4340.047842092076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.67221989173225, 298.19784778944637, 300.79395614004403, 311.3912301450664, 317.21805748829144, 5175.870603234921, 5283.547024568245, 5396.947595218934, 5517.641353312037, 5523.420040895703, 5077.111505486534, 5156.948430515694, 5306.70813105279, 5308.0, 5318.840434786963, 4146.606072423123, 4178.101708132751, 4194.874153775391, 4217.388478670591, 4218.0, 3839.308924393608, 4098.774567398792, 4217.599328063844, 4224.704270701052, 4245.299525578276, 4046.8785936421955, 4069.450624165744, 4070.5929407059352, 4081.938770918424, 4129.380592911058, 4062.045419326456, 4087.7736678996184, 4104.794760261975, 4119.876403707908, 4124.1957818225455, 3542.291541691672, 3543.7342199335153, 3569.5716514018486, 3584.6953008994265, 3586.8923932282073, 4519.706219095749, 4884.096442158212, 5197.974010129963, 5282.0756367635895, 5339.252504649352, 3795.070207799079, 4627.375304333906, 4648.418947631546, 4670.789814458358, 4704.952950470486, 4482.977585112086, 4864.343313652683, 4943.678660887031, 5655.773769049233, 5745.770169193227, 2977.5087110626937, 3256.5929258842643, 3733.711869405051, 3790.2798468290935, 3881.1849511602313, 4744.804439139137, 5180.8445746627285, 5263.578913686896, 6036.8188954331445, 6047.879042419171, 3528.1708798432346, 3684.797336146538, 3927.980360098238, 3952.0, 3959.208158368338, 3329.933401332008, 3898.1618951924906, 3941.940870886939, 3943.7795299062336, 4013.3578627419474, 3899.963537738427, 4537.863864084083, 4653.045720797273, 4695.3426520287185, 4701.741404222732, 5070.923936140961, 5142.30578871855, 5419.701916394557, 5520.1167813149705, 5851.795187168429, 1051.0015485289007, 1217.8721234270452, 1239.6157191270736, 1297.0544183724414, 1299.6750812296925, 823.4482896459386, 841.9789505262418, 852.424613385962, 869.452245085592, 871.9084496127942, 4707.529247075265, 4763.023580166057, 5009.348784658044, 5059.569936555434, 5080.644354895196, 4818.963922756624, 5477.4796394342475, 5479.589030822704, 5487.686155474459, 5579.609427340129, 4391.385206071152, 4597.908041839144, 4731.787069581877, 4741.976290118528, 5058.0, 2637.7757890579132, 2695.622233643893, 2767.6955534891144, 2819.0, 2914.499032998008, 5353.9732301338245, 5661.405552417019, 5709.0, 5724.1968779834215, 5803.0, 4774.904501909942, 5116.0, 5349.384820745664, 5723.427657234243, 5742.483176514133, 5163.948360516422, 5562.972185139049, 5842.415758424208, 5849.707514624167, 5856.297244330707, 4708.387909571734, 5317.069512835315, 5469.917951230815, 5508.697021663847, 5614.719264036864, 4868.780904859361, 5218.6868787872845, 5319.792286201338, 5369.791796845679, 5557.138643510262, 4558.6808923376375, 5460.5085542301385, 5808.050525642604, 5845.106672197289, 5857.882842343044, 3301.5377847100945, 3465.2896156287397, 3688.95263128966, 3752.1557649528636, 3759.71802114837, 5335.626506144533, 5443.755031023693, 5830.737616807169, 5864.179014937827, 5988.341282458839, 4920.581750551243, 5476.972615136899, 5636.549076073986, 5666.829995099989, 5768.278965129359, 4818.566329030404, 5329.866753331043, 5401.243825864303, 5423.60015182089, 5569.749361278671, 4637.07258548285, 4749.810007599795, 5109.156989096758, 5472.440354498949, 5577.972110139423, 5125.538701516882, 5289.804731036576, 5380.865478363072, 5827.854303642443, 5838.737256823369, 5377.704226267437, 5668.688222147656, 5690.715464226855, 5748.798792042258, 5764.596478246483], "value": 4029.739375035672, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.8579641173557, 709.3686618909172, 772.9420293477987, 794.5034353529045, 796.6255859745918, 14484.0, 14950.177740224284, 15062.397504099823, 15170.089794612331, 15180.924095379522, 13410.329483525833, 13442.39509222085, 13502.392392342344, 13630.454781808741, 13648.454061837514, 801.0507548554951, 802.8434455281223, 821.704186492862, 827.7599496146111, 827.9089300176976, 659.877922584321, 690.7237105157927, 751.7030772844723, 780.4926797581561, 829.5934991853978, 777.0597576931891, 852.9019162796264, 859.5272600069968, 859.6346552715103, 864.2265172670457, 828.3414685325181, 842.1872892658583, 847.0809172048304, 848.3594885861197, 851.3146916732028, 631.6936285901335, 637.8118455055769, 638.9968050159766, 639.6002498438476, 640.7437025189914, 9718.659846905324, 10784.784304313947, 12459.12786104973, 12580.370981450891, 12622.431990560446, 11139.610113646064, 11444.484998175101, 11510.769784604261, 11588.6523404298, 11621.535138594447, 8628.22345988864, 10501.369917804956, 11568.24806387582, 11916.165868389216, 12035.699107522294, 4327.112941846914, 4355.7822108894425, 4376.102898905749, 4394.780260986938, 4419.071994881079, 41631.0, 76279.09302267432, 79437.60281198614, 79891.0, 85802.57098714529, 10508.579656813641, 10578.260417646145, 10579.4710264488, 10923.672289831318, 11013.063889569316, 15977.0, 18699.532511687317, 20318.898405507876, 20447.0, 20700.275490357766, 7547.0, 7682.500637458547, 7782.610869456617, 7881.763547093597, 8050.5169689818795, 3639.6360363963922, 4106.733062350938, 4199.286121359376, 4278.700490965664, 4297.419848320499, 2857.557078652812, 3301.6698330167274, 3379.864805407756, 3422.435298175823, 3425.9486107708403, 577.4225774225774, 623.4451338308909, 626.858956734731, 632.9651869147149, 698.9860202795916, 586.633354153654, 618.5144661440837, 670.6713710281927, 673.4881490067584, 684.7261095561715, 8044.798880028046, 9197.908020919705, 9208.217301529445, 9258.398204116711, 9265.95367023174, 650.2132419772034, 656.3993945539778, 661.3584822721951, 672.1396612336217, 681.5944513014748, 4387.627051700578, 4463.732176069446, 4488.910221795546, 4498.865034048984, 4522.66080043998, 70631.64684176649, 87260.81847953725, 99863.50068249612, 110381.0, 114803.0, 58667.0, 85712.0, 94246.0575394251, 95454.0, 97933.0, 65028.8588145613, 80808.0, 83122.0, 85475.57262213774, 85701.0, 9221.400608960665, 9251.074892510695, 9424.717258482257, 9582.281328900499, 9629.0, 50077.74961125171, 51364.0, 54340.72829635826, 55479.445205547425, 57099.14351284651, 74138.62930685528, 76062.61968690343, 76652.61673691595, 78458.25396111108, 79385.0, 11866.584669536525, 12184.756304873676, 12270.079744019056, 12671.366431678563, 12737.490500380252, 13706.971977101557, 13744.0, 13931.512397066053, 14081.436742530594, 14408.20754858493, 12956.714451144016, 13150.539731109366, 13217.801732974205, 13393.866061339262, 13426.462941482621, 11090.334579925231, 11243.49404276826, 12201.840825121599, 13087.41106650184, 13418.597442076752, 11159.330440173615, 12284.32436215981, 12536.185147965352, 12872.485100595872, 13285.402156903156, 9513.952430238081, 10570.78858422812, 11223.831642525533, 11430.199886008233, 11508.13688973347, 9655.758606034906, 11468.713282168013, 11581.478833452345, 11588.88411115878, 11642.0], "value": 7126.30115696845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1637.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 102.0, 173.0, 173.0, 169.0, 117.0, 122.0, 156.0, 176.0], "value": 141.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 116.0, 149.0, 117.0, 116.0, 118.0, 117.0, 144.0, 118.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [261.0, 253.0, 192.0, 194.0, 193.0, 253.0, 278.0, 193.0, 276.0, 279.0], "value": 237.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 61.0, 82.0, 62.0, 60.0, 61.0, 63.0, 80.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 88.0, 72.0, 70.0, 71.0, 87.0, 69.0, 70.0, 87.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 87.0, 90.0, 85.0, 92.0, 88.0, 89.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 96.0, 95.0, 95.0, 94.0, 96.0, 97.0, 134.0, 103.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 93.0, 87.0, 87.0, 92.0, 132.0, 116.0, 136.0, 138.0, 142.0], "value": 112.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 61.0, 61.0, 77.0, 71.0, 79.0, 89.0, 88.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 80.0, 82.0, 75.0, 72.0, 85.0, 101.0, 102.0, 73.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 87.0, 82.0, 101.0, 78.0, 89.0, 106.0, 105.0, 107.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 165.0, 151.0, 146.0, 160.0, 169.0, 166.0, 179.0, 194.0, 194.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 230.0, 190.0, 150.0, 209.0, 189.0, 148.0, 231.0, 226.0, 181.0], "value": 194.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 68.0, 70.0, 74.0, 62.0, 79.0, 87.0, 90.0, 88.0], "value": 76.0, "unit": "score"}]}]} 02:48:37 INFO - Return code: 0 02:48:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:48:37 ERROR - # TBPL SUCCESS # 02:48:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:37 INFO - 2016-12-12 02:48:37.368 screenresolution[1949:17375] starting screenresolution argv=screenresolution get 02:48:37 INFO - 2016-12-12 02:48:37.370 screenresolution[1949:17375] Display 0: 1600x1200x32@60 02:48:37 INFO - 2016-12-12 02:48:37.376 screenresolution[1950:17378] starting screenresolution argv=screenresolution list 02:48:37 INFO - Available Modes on Display 0 02:48:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:37 INFO - Intel Iris: 02:48:37 INFO - Chipset Model: Intel Iris 02:48:37 INFO - Type: GPU 02:48:37 INFO - Bus: Built-In 02:48:37 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:37 INFO - Vendor: Intel (0x8086) 02:48:37 INFO - Device ID: 0x0a2e 02:48:37 INFO - Revision ID: 0x0009 02:48:37 INFO - Displays: 02:48:37 INFO - Display: 02:48:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:37 INFO - Display Serial Number: 1600x1200 60 02:48:37 INFO - Main Display: Yes 02:48:37 INFO - Mirror: Off 02:48:37 INFO - Online: Yes 02:48:37 INFO - Rotation: Supported 02:48:37 INFO - Return code: 0 02:48:37 INFO - Running post-action listener: _resource_record_post_action 02:48:37 INFO - [mozharness: 2016-12-12 10:48:37.611015Z] Finished run-tests step (success) 02:48:37 INFO - Running post-run listener: _resource_record_post_run 02:48:37 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 2388992; Write bytes: 662027264; Read time: 17; Write time: 1981 02:48:37 INFO - TinderboxPrint: CPU usage
54.3% 02:48:37 INFO - TinderboxPrint: I/O read bytes / time
2,388,992 / 17 02:48:37 INFO - TinderboxPrint: I/O write bytes / time
662,027,264 / 1,981 02:48:37 INFO - TinderboxPrint: CPU idle
2,217.6 (45.7%) 02:48:37 INFO - TinderboxPrint: CPU system
980.4 (20.2%) 02:48:37 INFO - TinderboxPrint: CPU user
1,651.8 (34.1%) 02:48:37 INFO - TinderboxPrint: Swap in / out
351,068,160 / 0 02:48:37 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 343976448; Write bytes: 345335296; Read time: 26991; Write time: 754 02:48:37 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 2388992; Write bytes: 309782016; Read time: 17; Write time: 1198 02:48:38 INFO - Running post-run listener: _upload_blobber_files 02:48:38 INFO - Blob upload gear active. 02:48:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:48:38 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:48:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:48:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:39 INFO - (blobuploader) - INFO - Done attempting. 02:48:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:48:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:48:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:40 INFO - (blobuploader) - INFO - Done attempting. 02:48:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:48:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:48:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:42 INFO - (blobuploader) - INFO - Done attempting. 02:48:42 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:42 INFO - Return code: 0 02:48:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3115de545e74b66ae358d91437cb33d38fd43352ae8fd93955575564f84fc32d4100c8ec224f1f5841efbe99f7bbf0a653d6b85eb1f909fb3405e3670caf7de5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99d3376b42957137e8d95b5a20b41dfef11bebbab321dda38a016cea487fadac850486e6e4d13ef9f7b820eceef70a4cb07bf38428655ba6d28b6d970760b7fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ef066ef13739582497969549c5fc6cbd21a53fd1f68e8a8ddb05b11e9ee7a484226073731e3143c8440a08bf58a98966acec1e2cf32cce5e50b65f0772e5b3e"} 02:48:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:42 INFO - Contents: 02:48:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3115de545e74b66ae358d91437cb33d38fd43352ae8fd93955575564f84fc32d4100c8ec224f1f5841efbe99f7bbf0a653d6b85eb1f909fb3405e3670caf7de5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99d3376b42957137e8d95b5a20b41dfef11bebbab321dda38a016cea487fadac850486e6e4d13ef9f7b820eceef70a4cb07bf38428655ba6d28b6d970760b7fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ef066ef13739582497969549c5fc6cbd21a53fd1f68e8a8ddb05b11e9ee7a484226073731e3143c8440a08bf58a98966acec1e2cf32cce5e50b65f0772e5b3e"} 02:48:42 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:42 INFO - Copying logs to upload dir... 02:48:42 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.410426 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-12 02:48:42.845508) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 02:48:42.849901) ========= 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.NwAiPa9ALk/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.iXpHESp6j3/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3115de545e74b66ae358d91437cb33d38fd43352ae8fd93955575564f84fc32d4100c8ec224f1f5841efbe99f7bbf0a653d6b85eb1f909fb3405e3670caf7de5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99d3376b42957137e8d95b5a20b41dfef11bebbab321dda38a016cea487fadac850486e6e4d13ef9f7b820eceef70a4cb07bf38428655ba6d28b6d970760b7fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ef066ef13739582497969549c5fc6cbd21a53fd1f68e8a8ddb05b11e9ee7a484226073731e3143c8440a08bf58a98966acec1e2cf32cce5e50b65f0772e5b3e"} build_url:https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011064 build_url: 'https://queue.taskcluster.net/v1/task/GIUaKK7nQbWZZs25jbIwKA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3115de545e74b66ae358d91437cb33d38fd43352ae8fd93955575564f84fc32d4100c8ec224f1f5841efbe99f7bbf0a653d6b85eb1f909fb3405e3670caf7de5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99d3376b42957137e8d95b5a20b41dfef11bebbab321dda38a016cea487fadac850486e6e4d13ef9f7b820eceef70a4cb07bf38428655ba6d28b6d970760b7fa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ef066ef13739582497969549c5fc6cbd21a53fd1f68e8a8ddb05b11e9ee7a484226073731e3143c8440a08bf58a98966acec1e2cf32cce5e50b65f0772e5b3e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 02:48:42.891502) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 02:48:42.892184) ========= 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.NwAiPa9ALk/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.iXpHESp6j3/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005212 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 02:48:42.943739) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 02:48:42.944316) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 02:48:42.944889) ========= ========= Total master_lag: 0.34 =========