builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0361 starttime: 1483549268.44 results: success (0) buildid: 20170104063707 builduid: 792fb026949d43a5a5c0948120355c47 revision: 6fc25451e804c71e053d15091d8ba1113395d4cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.440755) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.441419) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.441896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.468207) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.468635) ========= 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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 09:01:08-- 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.19M=0.001s 2017-01-04 09:01:08 (8.19 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075492 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.567859) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.568290) ========= 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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.019588 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:08.618035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 09:01:08.618560) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6fc25451e804c71e053d15091d8ba1113395d4cf --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 6fc25451e804c71e053d15091d8ba1113395d4cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 09:01:08,675 truncating revision to first 12 chars 2017-01-04 09:01:08,675 Setting DEBUG logging. 2017-01-04 09:01:08,675 attempt 1/10 2017-01-04 09:01:08,675 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6fc25451e804?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 09:01:09,463 unpacking tar archive at: mozilla-aurora-6fc25451e804/testing/mozharness/ program finished with exit code 0 elapsedTime=1.048008 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 09:01:09.690624) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:09.690973) ========= script_repo_revision: 6fc25451e804c71e053d15091d8ba1113395d4cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:09.691401) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:09.691727) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:01:09.712402) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 09:01:09.712832) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:09 INFO - MultiFileLogger online at 20170104 09:01:09 in /builds/slave/test 09:01:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:01:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:09 INFO - {'append_to_log': False, 09:01:09 INFO - 'base_work_dir': '/builds/slave/test', 09:01:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:01:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:09 INFO - 'branch': 'Mozilla-Aurora', 09:01:09 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:09 INFO - 'config_files': ('talos/mac_config.py',), 09:01:09 INFO - 'default_actions': ('clobber', 09:01:09 INFO - 'read-buildbot-config', 09:01:09 INFO - 'download-and-extract', 09:01:09 INFO - 'populate-webroot', 09:01:09 INFO - 'create-virtualenv', 09:01:09 INFO - 'install', 09:01:09 INFO - 'run-tests'), 09:01:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:09 INFO - 'download_minidump_stackwalk': True, 09:01:09 INFO - 'download_symbols': 'ondemand', 09:01:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:09 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:09 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:09 INFO - 'installer_path': 'installer.exe', 09:01:09 INFO - 'log_level': 'info', 09:01:09 INFO - 'log_name': 'talos', 09:01:09 INFO - 'log_to_console': True, 09:01:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:09 INFO - 'opt_config_files': (), 09:01:09 INFO - 'pip_index': False, 09:01:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:09 INFO - 'cmd': ('bash', 09:01:09 INFO - '-c', 09:01:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:09 INFO - 'enabled': True, 09:01:09 INFO - 'halt_on_failure': False, 09:01:09 INFO - 'name': 'check_screen_resolution'},), 09:01:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:09 INFO - 'cmd': ('bash', 09:01:09 INFO - '-c', 09:01:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:09 INFO - 'enabled': True, 09:01:09 INFO - 'halt_on_failure': False, 09:01:09 INFO - 'name': 'check_screen_resolution'},), 09:01:09 INFO - 'run_cmd_checks_enabled': True, 09:01:09 INFO - 'sps_profile': False, 09:01:09 INFO - 'sps_profile_interval': 0, 09:01:09 INFO - 'suite': 'dromaeojs', 09:01:09 INFO - 'system_bits': '32', 09:01:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:09 INFO - 'title': 't-yosemite-r7-0361', 09:01:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:09 INFO - 'use_talos_json': True, 09:01:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:09 INFO - 'work_dir': 'build'} 09:01:09 INFO - [mozharness: 2017-01-04 17:01:09.890390Z] Running clobber step. 09:01:09 INFO - Running pre-action listener: _resource_record_pre_action 09:01:09 INFO - Running main action method: clobber 09:01:09 INFO - rmtree: /builds/slave/test/build 09:01:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:11 INFO - Running post-action listener: _resource_record_post_action 09:01:11 INFO - [mozharness: 2017-01-04 17:01:11.509972Z] Finished clobber step (success) 09:01:11 INFO - [mozharness: 2017-01-04 17:01:11.510101Z] Running read-buildbot-config step. 09:01:11 INFO - Running pre-action listener: _resource_record_pre_action 09:01:11 INFO - Running main action method: read_buildbot_config 09:01:11 INFO - Using buildbot properties: 09:01:11 INFO - { 09:01:11 INFO - "project": "", 09:01:11 INFO - "product": "firefox", 09:01:11 INFO - "script_repo_revision": "production", 09:01:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:01:11 INFO - "repository": "", 09:01:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:01:11 INFO - "buildid": "20170104063707", 09:01:11 INFO - "pgo_build": "False", 09:01:11 INFO - "basedir": "/builds/slave/test", 09:01:11 INFO - "buildnumber": 12, 09:01:11 INFO - "slavename": "t-yosemite-r7-0361", 09:01:11 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:01:11 INFO - "platform": "yosemite_r7", 09:01:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:01:11 INFO - "branch": "mozilla-aurora", 09:01:11 INFO - "repo_path": "releases/mozilla-aurora", 09:01:11 INFO - "revision": "6fc25451e804c71e053d15091d8ba1113395d4cf", 09:01:11 INFO - "stage_platform": "macosx64", 09:01:11 INFO - "builduid": "792fb026949d43a5a5c0948120355c47", 09:01:11 INFO - "slavebuilddir": "test" 09:01:11 INFO - } 09:01:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:01:11 INFO - Running post-action listener: _resource_record_post_action 09:01:11 INFO - [mozharness: 2017-01-04 17:01:11.513499Z] Finished read-buildbot-config step (success) 09:01:11 INFO - [mozharness: 2017-01-04 17:01:11.513604Z] Running download-and-extract step. 09:01:11 INFO - Running pre-action listener: _resource_record_pre_action 09:01:11 INFO - Running main action method: download_and_extract 09:01:11 INFO - mkdir: /builds/slave/test/build/tests 09:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:11 INFO - trying https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:01:11 INFO - Downloading https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/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 09:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/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 09:01:12 INFO - Downloaded 1199 bytes. 09:01:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:01:12 INFO - Using the following test package requirements: 09:01:12 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:01:12 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:01:12 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:01:12 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'jsshell-mac.zip'], 09:01:12 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:01:12 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:01:12 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:01:12 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:01:12 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:01:12 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:01:12 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:01:12 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:01:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:01:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:01:12 INFO - Fetch https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:01:13 INFO - Http code: 200 09:01:13 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 09:01:13 INFO - x-amz-cf-id: WxcGBHz2EhWXShoQtvb1nzzRmilEPl3Vo99dXOPFGSmrenP37Z_JYw== 09:01:13 INFO - x-amz-version-id: yMkYaeGcpvsCdf9f9VkNZa3LVxtCwOTq 09:01:13 INFO - Expected file size: 16269875 09:01:13 INFO - Obtained file size: 16269875 09:01:20 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 09:01:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:01:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:01:20 INFO - Fetch https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:01:21 INFO - Http code: 200 09:01:21 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:01:21 INFO - x-amz-cf-id: Fnr9P-H_uxeI8TKAWWIeX8QD9D3XRpxBAW5PKcI3ZfoYxCMZsjD0lg== 09:01:21 INFO - x-amz-version-id: yMkYaeGcpvsCdf9f9VkNZa3LVxtCwOTq 09:01:21 INFO - Expected file size: 16269875 09:01:21 INFO - Obtained file size: 16269875 09:01:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:01:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:01:28 INFO - Fetch https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:01:28 INFO - Http code: 200 09:01:28 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 09:01:28 INFO - x-amz-cf-id: QKLzNBLTTs_LGzequLzLTcswHC83Sg-cu0BNGlKy_WGLacPhOhLYkg== 09:01:28 INFO - x-amz-version-id: Ehs5OV9guIJ2WCX.nAvYkUikYCjeOMAl 09:01:29 INFO - Expected file size: 14409038 09:01:29 INFO - Obtained file size: 14409038 09:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:29 INFO - trying https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:01:29 INFO - Downloading https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/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 09:01:32 INFO - Downloaded 109834910 bytes. 09:01:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:01:32 INFO - mkdir: /builds/slave/test/properties 09:01:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:32 INFO - Contents: 09:01:32 INFO - build_url:https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:01:33 INFO - Running post-action listener: _resource_record_post_action 09:01:33 INFO - Running post-action listener: set_extra_try_arguments 09:01:33 INFO - [mozharness: 2017-01-04 17:01:33.010805Z] Finished download-and-extract step (success) 09:01:33 INFO - [mozharness: 2017-01-04 17:01:33.010951Z] Running populate-webroot step. 09:01:33 INFO - Running pre-action listener: _resource_record_pre_action 09:01:33 INFO - Running main action method: populate_webroot 09:01:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:33 INFO - '--tppagecycles', 09:01:33 INFO - '7'], 09:01:33 INFO - 'tests': ['tsvgm']}}, 09:01:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['damp', 'tps']}, 09:01:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'tests': ['damp', 'tps']}, 09:01:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['dromaeo_dom']}, 09:01:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['basic_compositor_video']}, 09:01:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:01:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['a11yr', 09:01:33 INFO - 'ts_paint', 09:01:33 INFO - 'tpaint', 09:01:33 INFO - 'sessionrestore', 09:01:33 INFO - 'sessionrestore_no_auto_restore', 09:01:33 INFO - 'tabpaint']}, 09:01:33 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:33 INFO - 'ts_paint', 09:01:33 INFO - 'tpaint', 09:01:33 INFO - 'sessionrestore', 09:01:33 INFO - 'sessionrestore_no_auto_restore', 09:01:33 INFO - 'tabpaint']}, 09:01:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:33 INFO - 'ts_paint', 09:01:33 INFO - 'tpaint', 09:01:33 INFO - 'sessionrestore', 09:01:33 INFO - 'sessionrestore_no_auto_restore', 09:01:33 INFO - 'tabpaint']}, 09:01:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:33 INFO - 'ts_paint', 09:01:33 INFO - 'tpaint', 09:01:33 INFO - 'sessionrestore', 09:01:33 INFO - 'sessionrestore_no_auto_restore', 09:01:33 INFO - 'tabpaint']}, 09:01:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['a11yr', 09:01:33 INFO - 'ts_paint', 09:01:33 INFO - 'tpaint', 09:01:33 INFO - 'sessionrestore', 09:01:33 INFO - 'sessionrestore_no_auto_restore', 09:01:33 INFO - 'tabpaint']}, 09:01:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['a11yr', 09:01:33 INFO - 'ts_paint', 09:01:33 INFO - 'tpaint', 09:01:33 INFO - 'sessionrestore', 09:01:33 INFO - 'sessionrestore_no_auto_restore', 09:01:33 INFO - 'tabpaint']}, 09:01:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['tsvgx', 09:01:33 INFO - 'tsvgr_opacity', 09:01:33 INFO - 'tart', 09:01:33 INFO - 'tscrollx', 09:01:33 INFO - 'cart']}, 09:01:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:33 INFO - 'tsvgr_opacity', 09:01:33 INFO - 'tart', 09:01:33 INFO - 'tscrollx', 09:01:33 INFO - 'cart']}, 09:01:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'talos_options': ['--disable-e10s'], 09:01:33 INFO - 'tests': ['tp5o']}, 09:01:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'tests': ['tp5o']}, 09:01:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'talos_options': ['--disable-e10s', 09:01:33 INFO - '--xperf_path', 09:01:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:33 INFO - 'tests': ['tp5n']}, 09:01:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:33 INFO - 'talos_options': ['--xperf_path', 09:01:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:33 INFO - 'tests': ['tp5n']}}, 09:01:33 INFO - 'talos.zip': {'path': '', 09:01:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:33 INFO - Running post-action listener: _resource_record_post_action 09:01:33 INFO - [mozharness: 2017-01-04 17:01:33.029904Z] Finished populate-webroot step (success) 09:01:33 INFO - [mozharness: 2017-01-04 17:01:33.030021Z] Running create-virtualenv step. 09:01:33 INFO - Running pre-action listener: _resource_record_pre_action 09:01:33 INFO - Running main action method: create_virtualenv 09:01:33 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:33 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 09:01:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:33 INFO - Using real prefix '/tools/python27' 09:01:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:36 INFO - Installing pip.................done. 09:01:36 INFO - Return code: 0 09:01:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:01:36 INFO - Reading from file tmpfile_stdout 09:01:36 INFO - Output received: 09:01:36 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) 09:01:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:01:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:36 INFO - 'HOME': '/Users/cltbld', 09:01:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:36 INFO - 'LOGNAME': 'cltbld', 09:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:36 INFO - 'MOZ_NO_REMOTE': '1', 09:01:36 INFO - 'NO_EM_RESTART': '1', 09:01:36 INFO - 'PAGER': '/bin/cat', 09:01:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:36 INFO - 'PWD': '/builds/slave/test', 09:01:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:36 INFO - 'SHELL': '/bin/bash', 09:01:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:36 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:36 INFO - 'USER': 'cltbld', 09:01:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:36 INFO - 'XPC_FLAGS': '0x0', 09:01:36 INFO - 'XPC_SERVICE_NAME': '0', 09:01:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:37 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 09:01:37 INFO - Cleaning up... 09:01:37 INFO - Return code: 0 09:01:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:37 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')]} 09:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:37 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')]} 09:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:37 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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:37 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 09:01:37 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 09:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:37 INFO - 'HOME': '/Users/cltbld', 09:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:37 INFO - 'LOGNAME': 'cltbld', 09:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:37 INFO - 'MOZ_NO_REMOTE': '1', 09:01:37 INFO - 'NO_EM_RESTART': '1', 09:01:37 INFO - 'PAGER': '/bin/cat', 09:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:37 INFO - 'PWD': '/builds/slave/test', 09:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:37 INFO - 'SHELL': '/bin/bash', 09:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:37 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:37 INFO - 'USER': 'cltbld', 09:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:37 INFO - 'XPC_FLAGS': '0x0', 09:01:37 INFO - 'XPC_SERVICE_NAME': '0', 09:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:37 INFO - Downloading/unpacking psutil>=3.1.1 09:01:37 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 09:01:37 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 09:01:37 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 09:01:37 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 09:01:37 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 09:01:37 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 09:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:41 INFO - Installing collected packages: psutil 09:01:41 INFO - Running setup.py install for psutil 09:01:41 INFO - building 'psutil._psutil_osx' extension 09:01:41 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 09:01:41 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 09:01:41 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 09:01:41 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 09:01:41 INFO - building 'psutil._psutil_posix' extension 09:01:41 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 09:01:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:01:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:01:41 INFO - ^ 09:01:41 INFO - 1 warning generated. 09:01:41 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 09:01:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:41 INFO - Successfully installed psutil 09:01:41 INFO - Cleaning up... 09:01:41 INFO - Return code: 0 09:01:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01:41 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')]} 09:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:41 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')]} 09:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:41 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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:41 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 09:01:41 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 09:01:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:41 INFO - 'HOME': '/Users/cltbld', 09:01:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:41 INFO - 'LOGNAME': 'cltbld', 09:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:41 INFO - 'MOZ_NO_REMOTE': '1', 09:01:41 INFO - 'NO_EM_RESTART': '1', 09:01:41 INFO - 'PAGER': '/bin/cat', 09:01:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:41 INFO - 'PWD': '/builds/slave/test', 09:01:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:41 INFO - 'SHELL': '/bin/bash', 09:01:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:41 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:41 INFO - 'USER': 'cltbld', 09:01:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:41 INFO - 'XPC_FLAGS': '0x0', 09:01:41 INFO - 'XPC_SERVICE_NAME': '0', 09:01:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:42 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 09:01:42 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 09:01:42 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 09:01:42 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 09:01:42 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 09:01:42 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 09:01:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:01:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:01:44 INFO - Installing collected packages: mozsystemmonitor 09:01:44 INFO - Running setup.py install for mozsystemmonitor 09:01:45 INFO - Successfully installed mozsystemmonitor 09:01:45 INFO - Cleaning up... 09:01:45 INFO - Return code: 0 09:01:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:45 INFO - 'HOME': '/Users/cltbld', 09:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:45 INFO - 'LOGNAME': 'cltbld', 09:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:45 INFO - 'MOZ_NO_REMOTE': '1', 09:01:45 INFO - 'NO_EM_RESTART': '1', 09:01:45 INFO - 'PAGER': '/bin/cat', 09:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:45 INFO - 'PWD': '/builds/slave/test', 09:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:45 INFO - 'SHELL': '/bin/bash', 09:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:45 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:45 INFO - 'USER': 'cltbld', 09:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:45 INFO - 'XPC_FLAGS': '0x0', 09:01:45 INFO - 'XPC_SERVICE_NAME': '0', 09:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:45 INFO - Downloading/unpacking jsonschema==2.5.1 09:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:01:47 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 09:01:47 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 09:01:47 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 09:01:47 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 09:01:47 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 09:01:47 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 09:01:48 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:48 INFO - warning: no files found matching '*.txt' 09:01:48 INFO - no previously-included directories found matching 'build' 09:01:48 INFO - no previously-included directories found matching 'dist' 09:01:48 INFO - no previously-included directories found matching '.git*' 09:01:48 INFO - Installing collected packages: jsonschema, functools32 09:01:48 INFO - Running setup.py install for functools32 09:01:48 INFO - warning: no files found matching '*.txt' 09:01:48 INFO - no previously-included directories found matching 'build' 09:01:48 INFO - no previously-included directories found matching 'dist' 09:01:48 INFO - no previously-included directories found matching '.git*' 09:01:48 INFO - Successfully installed jsonschema functools32 09:01:48 INFO - Cleaning up... 09:01:48 INFO - Return code: 0 09:01:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:48 INFO - 'HOME': '/Users/cltbld', 09:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PAGER': '/bin/cat', 09:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test', 09:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:48 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - 'XPC_FLAGS': '0x0', 09:01:48 INFO - 'XPC_SERVICE_NAME': '0', 09:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:01:48 INFO - Cleaning up... 09:01:48 INFO - Return code: 0 09:01:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:48 INFO - 'HOME': '/Users/cltbld', 09:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:48 INFO - 'LOGNAME': 'cltbld', 09:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:48 INFO - 'MOZ_NO_REMOTE': '1', 09:01:48 INFO - 'NO_EM_RESTART': '1', 09:01:48 INFO - 'PAGER': '/bin/cat', 09:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:48 INFO - 'PWD': '/builds/slave/test', 09:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:48 INFO - 'SHELL': '/bin/bash', 09:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:48 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:48 INFO - 'USER': 'cltbld', 09:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:48 INFO - 'XPC_FLAGS': '0x0', 09:01:48 INFO - 'XPC_SERVICE_NAME': '0', 09:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:49 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:49 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 09:01:49 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 09:01:49 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 09:01:49 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 09:01:49 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 09:01:49 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 09:01:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:53 INFO - Downloading docopt-0.6.1.tar.gz 09:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:53 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:53 INFO - Running setup.py install for blobuploader 09:01:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:53 INFO - Running setup.py install for requests 09:01:53 INFO - Running setup.py install for docopt 09:01:53 INFO - Successfully installed blobuploader requests docopt 09:01:53 INFO - Cleaning up... 09:01:54 INFO - Return code: 0 09:01:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:54 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')]} 09:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:54 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')]} 09:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:54 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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:54 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 09:01:54 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 09:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:54 INFO - 'HOME': '/Users/cltbld', 09:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:54 INFO - 'LOGNAME': 'cltbld', 09:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:54 INFO - 'MOZ_NO_REMOTE': '1', 09:01:54 INFO - 'NO_EM_RESTART': '1', 09:01:54 INFO - 'PAGER': '/bin/cat', 09:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:54 INFO - 'PWD': '/builds/slave/test', 09:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:54 INFO - 'SHELL': '/bin/bash', 09:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:54 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:54 INFO - 'USER': 'cltbld', 09:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:54 INFO - 'XPC_FLAGS': '0x0', 09:01:54 INFO - 'XPC_SERVICE_NAME': '0', 09:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-z4OoE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-voOGwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-iSXKbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-y6oeLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-vQrS9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Hr6mNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-L4ljKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-ghvLUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-YSfkqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-H5PDqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-HRjuBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-m9fNxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-HoDZYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-95zTsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-WBLF0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-CpJF_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-fg7rZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:56 INFO - Running setup.py install for manifestparser 09:01:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:56 INFO - Running setup.py install for mozcrash 09:01:56 INFO - Running setup.py install for mozdebug 09:01:56 INFO - Running setup.py install for mozdevice 09:01:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:56 INFO - Running setup.py install for mozfile 09:01:56 INFO - Running setup.py install for mozhttpd 09:01:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:56 INFO - Running setup.py install for mozinfo 09:01:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Running setup.py install for mozInstall 09:01:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Running setup.py install for mozleak 09:01:57 INFO - Running setup.py install for mozlog 09:01:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Running setup.py install for moznetwork 09:01:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Running setup.py install for mozprocess 09:01:57 INFO - Running setup.py install for mozprofile 09:01:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:58 INFO - Running setup.py install for mozrunner 09:01:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:58 INFO - Running setup.py install for mozscreenshot 09:01:58 INFO - Running setup.py install for moztest 09:01:58 INFO - Running setup.py install for mozversion 09:01:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:58 INFO - Cleaning up... 09:01:58 INFO - Return code: 0 09:01:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:01:58 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 09:01:58 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 09:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:58 INFO - 'HOME': '/Users/cltbld', 09:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:58 INFO - 'LOGNAME': 'cltbld', 09:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:58 INFO - 'MOZ_NO_REMOTE': '1', 09:01:58 INFO - 'NO_EM_RESTART': '1', 09:01:58 INFO - 'PAGER': '/bin/cat', 09:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:58 INFO - 'PWD': '/builds/slave/test', 09:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:58 INFO - 'SHELL': '/bin/bash', 09:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:01:58 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:58 INFO - 'USER': 'cltbld', 09:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:58 INFO - 'XPC_FLAGS': '0x0', 09:01:58 INFO - 'XPC_SERVICE_NAME': '0', 09:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:58 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-LnAR7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-QoEqHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-SpJx5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-9_RYg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-_mU0XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-x7Mex4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-eE2G_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-y_LeZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-dcl4OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:59 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)) 09:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:59 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-VHL60m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:00 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)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-eRPjKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:00 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)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-91z46R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:00 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)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-QpVasy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:00 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)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-TN7reF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:00 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)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Q5ZViB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:00 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)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-Vw0m_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:00 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-BdGg3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:00 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)) 09:02:00 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)) 09:02:00 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)) 09:02:00 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)) 09:02:00 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)) 09:02:00 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)) 09:02:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:00 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 09:02:00 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 09:02:00 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 09:02:00 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 09:02:00 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 09:02:00 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 09:02:03 INFO - Downloading blessings-1.6.tar.gz 09:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:03 INFO - Installing collected packages: blessings 09:02:03 INFO - Running setup.py install for blessings 09:02:03 INFO - Successfully installed blessings 09:02:03 INFO - Cleaning up... 09:02:04 INFO - Return code: 0 09:02:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:04 INFO - Reading from file tmpfile_stdout 09:02:04 INFO - Current package versions: 09:02:04 INFO - blessings == 1.6 09:02:04 INFO - blobuploader == 1.2.4 09:02:04 INFO - docopt == 0.6.1 09:02:04 INFO - functools32 == 3.2.3-2 09:02:04 INFO - jsonschema == 2.5.1 09:02:04 INFO - manifestparser == 1.1 09:02:04 INFO - mozInstall == 1.12 09:02:04 INFO - mozcrash == 1.0 09:02:04 INFO - mozdebug == 0.1 09:02:04 INFO - mozdevice == 0.48 09:02:04 INFO - mozfile == 1.2 09:02:04 INFO - mozhttpd == 0.7 09:02:04 INFO - mozinfo == 0.9 09:02:04 INFO - mozleak == 0.1 09:02:04 INFO - mozlog == 3.4 09:02:04 INFO - moznetwork == 0.27 09:02:04 INFO - mozprocess == 0.23 09:02:04 INFO - mozprofile == 0.28 09:02:04 INFO - mozrunner == 6.13 09:02:04 INFO - mozscreenshot == 0.1 09:02:04 INFO - mozsystemmonitor == 0.3 09:02:04 INFO - moztest == 0.8 09:02:04 INFO - mozversion == 1.4 09:02:04 INFO - psutil == 3.1.1 09:02:04 INFO - requests == 1.2.3 09:02:04 INFO - wsgiref == 0.1.2 09:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:04 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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:02:04 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 09:02:04 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 09:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:04 INFO - 'HOME': '/Users/cltbld', 09:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:04 INFO - 'LOGNAME': 'cltbld', 09:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:04 INFO - 'MOZ_NO_REMOTE': '1', 09:02:04 INFO - 'NO_EM_RESTART': '1', 09:02:04 INFO - 'PAGER': '/bin/cat', 09:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:04 INFO - 'PWD': '/builds/slave/test', 09:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:04 INFO - 'SHELL': '/bin/bash', 09:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:02:04 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:04 INFO - 'USER': 'cltbld', 09:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:04 INFO - 'XPC_FLAGS': '0x0', 09:02:04 INFO - 'XPC_SERVICE_NAME': '0', 09:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 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)) 09:02:05 INFO - Cleaning up... 09:02:05 INFO - Return code: 0 09:02:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:05 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')]} 09:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:05 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')]} 09:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:05 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 0x100b41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ab7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc698572f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, '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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 09:02:05 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 09:02:05 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 09:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:05 INFO - 'HOME': '/Users/cltbld', 09:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:05 INFO - 'LOGNAME': 'cltbld', 09:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:05 INFO - 'MOZ_NO_REMOTE': '1', 09:02:05 INFO - 'NO_EM_RESTART': '1', 09:02:05 INFO - 'PAGER': '/bin/cat', 09:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:05 INFO - 'PWD': '/builds/slave/test', 09:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:05 INFO - 'SHELL': '/bin/bash', 09:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:02:05 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:05 INFO - 'USER': 'cltbld', 09:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:05 INFO - 'XPC_FLAGS': '0x0', 09:02:05 INFO - 'XPC_SERVICE_NAME': '0', 09:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:05 INFO - Cleaning up... 09:02:05 INFO - Return code: 0 09:02:05 INFO - Running post-action listener: _resource_record_post_action 09:02:05 INFO - Running post-action listener: _start_resource_monitoring 09:02:05 INFO - Starting resource monitoring. 09:02:05 INFO - [mozharness: 2017-01-04 17:02:05.406482Z] Finished create-virtualenv step (success) 09:02:05 INFO - [mozharness: 2017-01-04 17:02:05.407522Z] Running install step. 09:02:05 INFO - Running pre-action listener: _resource_record_pre_action 09:02:05 INFO - Running main action method: install 09:02:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:05 INFO - Reading from file tmpfile_stdout 09:02:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:05 INFO - Reading from file tmpfile_stdout 09:02:05 INFO - Output received: 09:02:05 INFO - Usage: mozinstall [options] installer 09:02:05 INFO - Options: 09:02:05 INFO - -h, --help show this help message and exit 09:02:05 INFO - -d DEST, --destination=DEST 09:02:05 INFO - Directory to install application into. [default: 09:02:05 INFO - "/builds/slave/test"] 09:02:05 INFO - --app=APP Application being installed. [default: firefox] 09:02:05 INFO - mkdir: /builds/slave/test/build/application 09:02:05 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'] 09:02:05 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 09:02:37 INFO - Reading from file tmpfile_stdout 09:02:37 INFO - Output received: 09:02:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:02:37 INFO - Running post-action listener: _resource_record_post_action 09:02:37 INFO - [mozharness: 2017-01-04 17:02:37.258133Z] Finished install step (success) 09:02:37 INFO - [mozharness: 2017-01-04 17:02:37.258272Z] Running run-tests step. 09:02:37 INFO - Running pre-action listener: _resource_record_pre_action 09:02:37 INFO - Running main action method: run_tests 09:02:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:37 INFO - 2017-01-04 09:02:37.272 screenresolution[1896:14138] starting screenresolution argv=screenresolution get 09:02:37 INFO - 2017-01-04 09:02:37.275 screenresolution[1896:14138] Display 0: 1600x1200x32@60 09:02:37 INFO - 2017-01-04 09:02:37.281 screenresolution[1897:14140] starting screenresolution argv=screenresolution list 09:02:37 INFO - Available Modes on Display 0 09:02:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:37 INFO - Intel Iris: 09:02:37 INFO - Chipset Model: Intel Iris 09:02:37 INFO - Type: GPU 09:02:37 INFO - Bus: Built-In 09:02:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:37 INFO - Vendor: Intel (0x8086) 09:02:37 INFO - Device ID: 0x0a2e 09:02:37 INFO - Revision ID: 0x0009 09:02:37 INFO - Displays: 09:02:37 INFO - Display: 09:02:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:37 INFO - Display Serial Number: 1600x1200 60 09:02:37 INFO - Main Display: Yes 09:02:37 INFO - Mirror: Off 09:02:37 INFO - Online: Yes 09:02:37 INFO - Rotation: Supported 09:02:37 INFO - Return code: 0 09:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:37 INFO - Python 2.7.3 09:02:37 INFO - Return code: 0 09:02:37 INFO - grabbing minidump binary from tooltool 09:02:37 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')]} 09:02:37 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 0x100955d30>, '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 0x1007bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10094db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:37 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 09:02:37 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 09:02:37 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 09:02:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:37 INFO - Return code: 0 09:02:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:02:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:02:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0361 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rfBWrA3QIn/Render', 09:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:37 INFO - 'HOME': '/Users/cltbld', 09:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:37 INFO - 'LOGNAME': 'cltbld', 09:02:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:37 INFO - 'MOZ_NO_REMOTE': '1', 09:02:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:37 INFO - 'NO_EM_RESTART': '1', 09:02:37 INFO - 'PAGER': '/bin/cat', 09:02:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:37 INFO - 'PWD': '/builds/slave/test', 09:02:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:37 INFO - 'SHELL': '/bin/bash', 09:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nx9Cnr4ymH/Listeners', 09:02:37 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 09:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:37 INFO - 'USER': 'cltbld', 09:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:37 INFO - 'XPC_FLAGS': '0x0', 09:02:37 INFO - 'XPC_SERVICE_NAME': '0', 09:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:02:37 INFO - mozversion application_buildid: 20170104063707 09:02:37 INFO - mozversion application_changeset: 6fc25451e804c71e053d15091d8ba1113395d4cf 09:02:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:02:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:37 INFO - mozversion application_name: Firefox 09:02:37 INFO - mozversion application_remotingname: firefox-dev 09:02:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:02:37 INFO - mozversion application_vendor: Mozilla 09:02:37 INFO - mozversion application_version: 52.0a2 09:02:37 INFO - mozversion platform_buildid: 20170104063707 09:02:37 INFO - mozversion platform_changeset: 6fc25451e804c71e053d15091d8ba1113395d4cf 09:02:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:02:37 INFO - mozversion platform_version: 52.0a2 09:02:37 INFO - using testdate: 1483549357 09:02:37 INFO - actual date: 1483549357 09:02:37 INFO - starting webserver on 'localhost:51729' 09:02:37 INFO - SUITE-START | Running 2 tests 09:02:37 INFO - TEST-START | dromaeo_css 09:02:37 INFO - operating with platform_type : mac_ 09:02:37 INFO - Initialising browser for dromaeo_css test... 09:02:37 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpzMH5A5/profile http://localhost:51729/getInfo.html) 09:02:40 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 09:02:40 INFO - PROCESS | 1907 | colorDepth:24 09:02:40 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 09:02:40 INFO - PROCESS | 1907 | __metrics 09:02:40 INFO - PROCESS | 1907 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:02:40 INFO - PROCESS | 1907 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:02:41 INFO - TEST-INFO | 1907: exit 0 09:02:41 INFO - Browser initialized. 09:02:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:41 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpzMH5A5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:53 INFO - PROCESS | 1909 | RSS: Main: 223105024 09:02:53 INFO - PROCESS | 1909 | 09:05:58 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:51729/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51729/tests/dromaeo/cssquery-ext.html) 09:06:00 INFO - PROCESS | 1909 | RSS: Main: 360161280 09:06:00 INFO - PROCESS | 1909 | 09:09:02 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:51729/tests/dromaeo/cssquery-ext.html (next: http://localhost:51729/tests/dromaeo/cssquery-jquery.html) 09:09:03 INFO - PROCESS | 1909 | RSS: Main: 346992640 09:09:03 INFO - PROCESS | 1909 | 09:12:06 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:51729/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51729/tests/dromaeo/cssquery-mootools.html) 09:12:06 INFO - PROCESS | 1909 | RSS: Main: 333127680 09:12:06 INFO - PROCESS | 1909 | 09:15:09 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:51729/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51729/tests/dromaeo/cssquery-prototype.html) 09:15:09 INFO - PROCESS | 1909 | RSS: Main: 312135680 09:15:09 INFO - PROCESS | 1909 | 09:18:13 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:51729/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51729/tests/dromaeo/cssquery-yui.html) 09:18:13 INFO - PROCESS | 1909 | RSS: Main: 305074176 09:18:13 INFO - PROCESS | 1909 | 09:21:16 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:51729/tests/dromaeo/cssquery-yui.html (next: http://localhost:51729/tests/dromaeo/cssquery-dojo.html) 09:21:17 INFO - PROCESS | 1909 | RSS: Main: 384552960 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | __start_tp_report 09:21:17 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 09:21:17 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 09:21:17 INFO - PROCESS | 1909 | |i|pagename|runs| 09:21:17 INFO - PROCESS | 1909 | |0;dojo.html;3264.053424506893;3318.2865683877963;3321.8671253149873;3340.2651416688336;3384.8476818543163;5543.029969755292;5625;5632.26780518532;5633.0987042073275;5644.407337229593;4328.84849030284;5059.949400505994;5558.027345214581;5626.324841019084;5646.604737668355;5121.308623335848;5156.3296771419855;5164.147915593922;5228.294180285669;5242.3184985951775;5110.182370820669;5116.872078198037;5138.768755406016;5165.845024649267;5262.81580144695;5120.692758434506;5175.8188463403785;5197.4022987356375;5210.713410762407;5214.087534681435;4272.9359059614135;5180.041692286939;5197.922031169535;5214.895702085956;5243.187305967562;5097.541221290065;5198.5061419165495;5204.29741984831;5217.713025783581;5254.264402983586;5466.9179962300595;5533.170024496318;5641.633293835928;5652.180433837112;5733.913991290134;6020.157177995085;6027.60820546663;6035.456808887177;6054;6095.207623009025;6187.783427580013;6207.161648786679;6229.968850155766;6232.906506402408;6240.594361366497;5514.696691681916;5682.431756824326;5693.28833895763;5714.142878568248;5739.167820666022;6151;6228.688565571703;6233.407826256498;6279.152314437584;6301.243850737941;4980.9252861207115;6013.917494850969;6036.547258955596;6036.63780173191;6071.666058366833;5396;6176.413240742212;6181.629102253879;6198.318184999646;6203.875922481525;5878.911816322759;5883.970580147072;5945.494632956247;6026.367231440723;6064.151018857364;6136.439666665066;6139.3388173831;6140.201773769381;6140.478059365004;6157.84605384869;4845.63657725672;4845.830395936126;4848.975755121272;4853.708777473363;4870.512948705172;4210.410542524049;4231.259529582311;4267.8079486423185;4322.459692538433;4337.414449049338;4783.402074740658;5695.117256825199;5751.338596061423;5777.306723193244;5802.158686990363;6203.844903877439;6211.782587609502;6221.5333849961435;6227.097070924781;6227.719752611053;5576.693281869536;5752.884942301214;5770;5781.826545203567;5798.275215598051;5167.689938603696;5256.448072952361;5265.842024739264;5308.708021058879;5316.601254905898;6314.4632706218645;6331.493480521639;6359.618422894641;6359.8092057238355;6371.840703982438;6189.535784816247;6206.379362063757;6213.844653883689;6217.409346112112;6234.127222188807;6183.196184495987;6219.937800621936;6250.874982500233;6252.531060170414;6347.174867267226;6108.402780883668;6118.223266370627;6129.603311142764;6130.153553065376;6133.579501509479;6176.227971503562;6184.412480814315;6187.876242475215;6188;6189.412005859616;5820.70896455184;5877.823665290048;5894.911576326444;5970.880582388241;5981.970090149522;5841.591088623755;5894.38108998549;5962.880742385215;5987.670678112746;6001.189839371804;5815.563832712648;5823.912641310469;5824.883502330014;5830.4461076197695;5834.299884013945;5734.971325143348;5863.560232982629;5950.434708702666;5964.701764911824;5975.342712301556;5737.741801618854;5745.712714364348;5794.420557944172;5820.621659592282;5846.678432686073 09:21:17 INFO - PROCESS | 1909 | |1;ext.html;448.34317724533554;479.79848463645266;498.54632284621005;506.291192330737;520.7994921955046;29456.55815162774;37946.8102659487;38865.61134388655;39175.60824391755;39182;39573.40639890404;39839.8048058558;39903.800480997554;39947.60052399482;39992;9079.682211122628;9253.167214950654;9267.165955064043;9284.907150928506;9304.627814887397;9138.406003609743;9218.400803947756;9249.95375023124;9258.305627077962;9260.583273752698;9219.492927888963;9266.397684150543;9278.860817087716;9298.070192980715;9303.534823258844;9163.450192988408;9220.907790922072;9227.15456908621;9228.723138305862;9228.769280768001;8959.462432254086;9038.096190380975;9038.186563209343;9056.230220431271;9063.13900179482;38147.809260953516;38473.61526384756;38550.80724596359;38594.42108368377;38651.22697546033;2576.471823276224;3369.393509168349;3461.9826900865332;3803.7717736935597;3821.312163810513;27743.30641733973;27884.817277408347;27924.022659207032;28970.710292897224;29212.97754578601;3046.5430185472137;3151.369726054775;3159.210197450637;3258.8370581471067;3312.569365982431;442843;447530.76234618947;450301;450515;450647;32425.351492970007;33091;33198.3360332792;55992;56785.889269965075;34517.82741086279;34531.654683453344;34630.653693462744;34668.47997280043;34742.30515389678;13923.094998825043;14061.367238474293;14062.367193476319;14064.859351406354;14071.437142514389;11786.764264714659;11881.643550693492;11890.464929078213;11904.523819047314;11930.22453540517;7609.6575654095695;7610.8097297568;7678.8080297993;7730.806729831799;7744.690212391554;3141.9842900785807;3188.920276993094;3191.5372271020574;3194.0577529628818;3212.855421506038;912.5118061836886;933.3979583168818;1000.364768372089;1042.6038105519854;1048.5648455890873;29140.125796226148;29659.96190133335;30889.382212355627;31085;31774.841125794224;789.5815217934504;973.0950216298769;979.5298256836759;997.1723469520281;1033.9224558158019;14415.639609009859;14503.85496145046;14710.175354407931;14747.410103595736;14792.926035369754;664964;668075;669742;671035;671226;53244.73377633087;54074.72962635162;54075.188872166946;54202.18696719552;54275.72862135664;1200.3818033712646;1245.3337464456472;1248.3820508848178;1346.158650843223;1382.3502953611787;24441.38896527601;24451.877740611184;30286.54570181405;30453;31135.221619459695;52189.95620087653;52805.207921880436;52911.73544132255;52985.47014529805;53172;568334;573681.1315943394;573827;574371;575268;25397.11110111137;25816.967321306936;26023.73976260289;26778.732212677623;26837.731622683525;43852.780736096116;44277.77861110674;44413.33379999367;44932;45603.77198113988;28878.278043049093;30651.77392904259;35996.28007439889;37662.435063473524;38737.6126238734;36815.14653139688;36906.8154659225;37325;37519.06202344963;38018.239635206584;31772.523412148374;32339;36648.26703465969;37354.813225934784;37588;26279.73720262773;29533.26166845846;33040.83479582587;33630.663693363735;33995.320093598486;23823.880880595487;24791.752082478943;25250.74749252484;25358.873205633852;25432.491350173263 09:21:17 INFO - PROCESS | 1909 | |2;jquery.html;371.1482148469263;375.58122693197095;381.6851097844278;390.0541187620021;399.15976868691405;14813.333399996998;15197.772033419507;17977.820221797778;20007.1997120115;20210.6968395474;14953.775693364581;16228.269727862245;19352;19435.805641943578;19872.304469343577;5734.139879018149;5957.404259574029;5993.071073983518;6002.279726432834;6032.698365081728;5596.2165296858475;5855;5896.174535565025;5912.556558258127;5924.081767326072;4052.412400201969;4303.892402689927;5866.73599688015;5872;6164;5278.392984806739;6487.772927947546;6506.837329066764;6511.4465270452065;6521;4999.925001124986;5305.522502974751;5341.092014357569;5377.085895397752;5378.8924221515745;12778.680532986657;12985.675358116028;15527.534173974798;24218.878905605536;24384.878075609686;2074.2532688232222;2175.510510135207;2328.7904088631935;2367.3134790910617;2788.5956536302324;9931.851022234672;9948.204143668489;10138.9493052535;13064.477420903153;13764.587062388144;1715.794104707443;1756.956076098095;1817.854571634266;1819.9454016379398;1908.9045547722696;109498.45250773695;121605.5986003508;126597.73402265951;127635;128560;11159.776804463865;11309.151813614011;11507.539698412138;11521.193516453768;11670.299782013106;14569.490067847575;14574.052686575338;14580.781288280685;14594.270286485633;14603.488877889435;8220.58897055145;8296.087430382653;8319.916800832036;8428.609279468941;8433.439813634497;8190.8771368429525;8191.426600137933;8229.423940324239;8384.287335576544;8392.622331995075;4436.75770784181;4492.9550704493195;4548.56788605082;4556.225441674923;4605.424321959755;1877.4649224970817;1886.5290657094536;1901.115981068782;1908.332083770697;1948.9610207795765;761.6420282467232;767.3745897093851;769.4075561817369;880.9735707928644;921.709661456639;15123.848761512465;19507.609847803247;20091.497712557302;20346;20520.87135207548;922.7563625539461;925.5187302602743;927.2674587076134;954.7660823098386;976.770458942148;7961.283484486424;10374.792504149875;10556.577736890438;10597.311174773615;10620.628278010232;486450.567747159;571548;582713;588427;589756.1024389787;115393.84606154;125977;128911;148869;150290;959.12240300126;997.3766396002499;1007.7833265847731;1009.2985375164191;1013.0578561937469;16321.26554305083;16925.238364273882;17736.467905962843;17891.46325610234;18076.276948922423;43503.56496434995;44792;57799.711001444724;59038;59531.70234148802;84769;86137.8317300964;86354;87620.68568971343;88463;7858.174891636524;7864.84270314579;8042.758717238492;8095.83808323842;8155.877661835196;10657.893421066;12252.938735306623;12312.876871231172;12609.180403273758;12653;8742.694005709769;10680.8931910683;10705.625303114353;11101.50043248072;11117.44412779374;10717.732056698645;10762.838557421801;10777.676669699922;10791.352518848895;10837.241393102408;10853;10940.890591094305;11090.611828586283;11148.665540033811;11370.260933039326;7794.181610930771;7886.684532618631;7937.444378893653;8045.83908321842;8091.1099779025435;8238.958805205935;8339.499630022217;9777.608895644378;10939.234253602172;11043.061339786209 09:21:17 INFO - PROCESS | 1909 | |3;mootools.html;1316.0918965913525;1471.8528147185282;1497.8651921327082;1664.9750253746197;1844.4835446075094;7416.295451932065;7787.987561616989;8009.4793838400465;8327.125651806564;8334.708285210003;8174.141715119916;8267.007959044908;8272.503649781002;8278.62746176422;8298.045724741656;2247.505548779266;2271.5684020036224;2279.943001424961;2282.4978504728933;2282.9771702283015;2273.7953584177503;2314.5718042162252;2329.9767002330013;2332.8017118544944;2334.031376978552;2259.8644081355174;2266.875321857297;2288.1305104060434;2289.5077558324956;2290.828187885907;2221.533477969628;2246.1352379334076;2283.862968221912;2285.702858628384;2288.942776430586;2282.885855707208;2307.3308740465245;2307.6307790753567;2308.0767692922796;2309.6304591265316;8184.345252379795;8247.546384948826;8262.17378262175;8262.669493220264;8273.503589784632;4680.4149481314835;4852.393450818648;4891.144049791273;4894.94758626897;4903.338049363326;2906.4187162567573;3260.041389548399;3388.4070287699556;3450.723942084652;3456.5160877477174;1038.4911393417246;1056.2447849787438;1098.758273179899;1106.6845948904524;1108.2353176308322;10153.949230253875;10817;10874.238803283773;10875.184361172944;10899.509522071527;4140.0891803803115;4187.811548480326;4271.615554600101;4275.551067137968;4422.380866678667;8552.61513231906;8564.871526927102;8566.27186689126;8576.44253123545;8592;5023.497650234947;5037.345145131109;5038.773255203525;5043.747812609355;5071.163258062452;3982.3628219484744;4219.346001369792;4349.543297953669;4364.519902810688;4615.653825963066;3597.0467826026124;3632.2735452908996;3710.387786015331;3714.851405943732;3715;1128.554221082684;1136.2103338179886;1137.3232926408773;1147.0995268714018;1147.65570328901;814.6171299489231;888.9821154777743;899.2491269789668;902.8555431130987;931.3201363005044;5492.258545096441;5600.663960162403;5678.977783998837;5751.252337196138;5811.099279611667;793.8531371696139;897.6095398501673;911.7492689510437;930.3766476460787;970.9417434953925;3643.708503319728;3706.314331848617;3740.4576336431064;3850.268448994682;3944.8422063117155;9670.032996700274;10182.18542516597;10183.032611901857;10203.591856325813;10206.897931020743;10092.293539452168;10106.848397274047;10118.08937195656;10119.898801012043;10136.037076477724;6966.373026427545;7749.108852482037;7766.8058298541855;7769.417293702882;7770.067591889009;5228.790848366023;5357.19642053695;5504.091824848855;5608.551315894801;5629.915551266652;9244.399114057565;9354.29842761782;9380.624775009197;9382.390144640576;9488.430694158373;8628.22345988864;9378.718638440858;9465.337426380087;9521.476318802255;9563.04369563066;6252.499800015897;6469.45009674183;6661.500387470862;6667.366600172976;6752.729890804313;7795.727149549739;7869.606519674108;7960.323372513401;7971;7985.480943738638;7108.680109395193;7113.217546070032;7139.750108746169;7141.428685705235;7198.49610527258;6757.898631520433;6874.931250687429;6979.546329488567;7003.229644738973;7028.156621205488;6411.102445657519;6605.240397354366;6609.438197753245;6621.404073633396;6630.403263706289;4673.158831410379;4693.483716791082;4837.879053023703;4911.386076740408;4993.975030124826;4809.134355815847;4873.829415970424;4890.7554622268035;4944.01119776039;4984.451710311935 09:21:17 INFO - PROCESS | 1909 | |4;prototype.html;249.38526532098385;296.3155111691991;304.4231181910281;311.05439464029354;312.4235784976717;4528.637708983278;4764.404449443819;5021.949780502194;5051.696898186111;5140.794368225276;4605.562471565205;4608.870826647468;4807.8798030049265;4953.876153096175;4957.256411538271;3094.4584697677874;3369.073504786179;3369.9384693821366;3822.73240873139;3827.5981021992707;3946.3291240489043;3951.9209615807667;3956.3472027115636;3965.5241371035518;3990.580988996157;3810.5046343975246;3954.6836253099827;3963.3460479021073;3972.324704800191;4000.9399859002137;3476.4090104682264;3919.568847426781;4003.6187515307174;4022.8994275143064;4034.536028356747;2795.203367040384;3160.8893688720786;3237.264511487813;3550.8579656813954;3553.6979356754705;4620.897491641295;4661.137689527448;5050.39395272566;5081.958198569285;5132.332796736438;3743.3636281832155;4225.3873188387515;4524.411826462571;4549.499555048942;4563.0150884365075;4492.326151077365;4557.908841823178;5362.8123015694655;5395.622306438551;5395.866867957734;3547.6984456321243;3842.731008829383;3844.1542860570908;3856.5950575189763;3870.3807390817333;5029.67307125043;5321.122014867542;5578.497935185812;5609.719514024323;5649.887002259932;3680.8895733128047;3879.0884142226555;3886.8639597613947;3900.8829735107993;3928.489296391451;3404.8978530643626;3743.12036671382;3988.581198974124;3996.3406038003404;3997.720159588801;3989.8204580793936;4373.278409062469;4504.932426013613;4531.84138555149;4562.908741825145;5416.64375996435;5418.512333889968;5432.103702889058;5441.401445840955;5453.727313634303;1146.2893006335958;1214.9854871182486;1221.9389030548436;1279.8801049082053;1289.265118882228;827.7475370012204;832.3382910585977;844.9058469282294;883.8055627761883;903.0653273861521;4137.586241375839;4262.742490965173;4614.423821538335;4823.03539292143;4994.250862370601;4378.088571100247;5220.7128607926925;5228;5339.679619222859;5340.0508814283285;4077.1641813428178;4275.679324050647;4522.186006518858;4906.779194936236;4944.233643785219;2280.9393631960884;2606.4656745367156;2622.561018253043;2763.226296636945;2774.375765452757;5580.748866301026;5588.9600295891705;5591.27313449249;5614.214010038598;5618.634788738801;4366.410534577792;4779.569838714532;5156.432792392834;5386.663735692805;5435.483629055233;4977.751112444435;5530.087535556669;5579.261894530674;5591.496765290981;5619.4099619540175;4355.735017790667;5138.640295179302;5178.456262092426;5197.856471576247;5234.895302093861;4694.74178920149;4827.40695570468;5117.748190796038;5122.334096567422;5243.606729495379;4988.600911927109;5327.856564626889;5384.7576859040655;5480.684635687486;5643.7742490299925;3179.523071539195;3276.574045374086;3647.854085836537;3690.1954514568583;3705.9444108338935;4191.8532851350055;4718.811247550059;5158.845234642967;5567.719424532299;5657.9151312731165;4515.39042229308;4865.464798872192;5031.6729412588065;5424.403315635356;5486.917696234639;5044.470330615232;5376.876133927155;5417.835165439449;5432.456754324536;5509.256250406143;4494.7752612368595;5087.3386459759995;5110.233464980282;5280.3663560373;5305.771575938193;4617.861464156081;5365.6244062916785;5373.865653358697;5523.751431185687;5554.944450555605;5373.038840822993;5397.487238712316;5407.621466497308;5409.945900541102;5417.566594672496 09:21:17 INFO - PROCESS | 1909 | |5;yui.html;698.3819319901887;751.4138971602149;770.9730159444421;783.8046978358;798.2257210505808;15976.760348594766;16558.50324490264;16596.170191490437;16639;16652;14976.700465990674;15111.77332340013;15113.471028514005;15114.47099351523;15123.470678526253;641.2337256977913;661.0513912535503;814.7107776739247;836.5691668790578;840.4033136473112;660.3165723476203;803.6463955859432;827.7475370012144;831.4678605692362;843.8565443874556;833.945793523422;856.2037305306063;870.29071306885;870.5342641686699;870.5516658920661;862.0603542139098;862.2153840005597;864.4640322999718;864.7751584588021;865.9783505412352;444.19378827428113;492.5616201580596;508.8651507350555;511.2637801565733;615.1203778596605;14268.143911365247;14697.55907322782;14739.778903316459;14749.705005899928;14758;12820.102592818506;13104.148230365092;13259.933700331436;13266.204027758366;13267.336633168303;11968;12146.45340959659;12209.816852747215;12387.690307742288;12668.809967850397;4382.298832186835;4407.955920440787;4473.753943533105;4486.461624605068;4489.506154323022;93787.06212937851;97350.51324743402;97977;99193.5040324801;101550.47674284891;10066.748331291776;10074.496275186211;10331.535080921227;10399.636012739518;10425.426601536836;19461.12424940881;19481.90259048724;19509;19523;19535.609287814164;7344.926550734424;7351.375133113638;7357.59533225667;7410.407167426593;7647.235276472419;4167.562405947392;4209.768462734518;4212.220739163204;4343.261645520269;4395.472543294825;3244.367348367046;3352.580927384077;3353.9496907546404;3364.882229121969;3677.852885884588;687.4466054825813;690.1304356510732;696.1889398850384;705.0376236437329;709.1667290933133;588.2529187931389;622.7228883147046;638.3999040901538;646.8415238266655;672.586359388982;8219.219174178443;8414;8522.914770852336;8687.435316704394;8695.73911782788;577.3331801768963;633.9746410143634;636.8407898025494;648.1023782061784;670.8758879607288;4059.4316795648633;4232;4256.361545768098;4266.381374700651;4304.913901721948;90255.54872225598;108742;109465.45267273772;116672.24991625133;121189.39405303093;73570;74127.25872741203;97168;108668;109047;76613.85079223815;82189.76715349275;83250.58374708088;85305.57347213349;85532.14467855367;8803.603837827184;9263.687602653781;9574.617015319309;9837.950810245902;9914.058164474363;51581.226281606556;51905.7404712974;52177;53966.65083372947;57280.42719572751;72328;72566;75761.62119189369;75955.10112247238;76188;12131.575394861138;12186.634400967985;12299.200551964093;12328.137030408145;12628.116031878048;13534;13668.111572747739;13929.373178206803;14126.576202713935;14158.805385165317;12588.811167832308;12754.42605082755;12885.48458061667;13012.089153759525;13470.865291346961;12116.15186936906;12607.684807879876;12721.363931803187;12857.228566286052;13133.60599182026;12761.61715148547;12771.48914043465;12826.486940522274;12853.742925141258;13206.867931320563;10700.41147736882;10835.783284334111;11115;11142.220044596801;11277.887221127683;10398.68803935883;10869.51087201062;11252.21234513609;11288.661340159808;11383.317000979967 09:21:17 INFO - PROCESS | 1909 | __end_tp_report 09:21:17 INFO - PROCESS | 1909 | __start_cc_report 09:21:17 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,1827 09:21:17 INFO - PROCESS | 1909 | __end_cc_report 09:21:17 INFO - PROCESS | 1909 | __startTimestamp1483550477042__endTimestamp 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | ------- Summary: start ------- 09:21:17 INFO - PROCESS | 1909 | Number of tests: 6 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5637.97 Median:5820.62 stddev:654.28 (11.2%) stddev-sans-first:629.22 09:21:17 INFO - PROCESS | 1909 | Values: 3264.1 3318.3 3321.9 3340.3 3384.8 5543.0 5625.0 5632.3 5633.1 5644.4 4328.8 5059.9 5558.0 5626.3 5646.6 5121.3 5156.3 5164.1 5228.3 5242.3 5110.2 5116.9 5138.8 5165.8 5262.8 5120.7 5175.8 5197.4 5210.7 5214.1 4272.9 5180.0 5197.9 5214.9 5243.2 5097.5 5198.5 5204.3 5217.7 5254.3 5466.9 5533.2 5641.6 5652.2 5733.9 6020.2 6027.6 6035.5 6054.0 6095.2 6187.8 6207.2 6230.0 6232.9 6240.6 5514.7 5682.4 5693.3 5714.1 5739.2 6151.0 6228.7 6233.4 6279.2 6301.2 4980.9 6013.9 6036.5 6036.6 6071.7 5396.0 6176.4 6181.6 6198.3 6203.9 5878.9 5884.0 5945.5 6026.4 6064.2 6136.4 6139.3 6140.2 6140.5 6157.8 4845.6 4845.8 4849.0 4853.7 4870.5 4210.4 4231.3 4267.8 4322.5 4337.4 4783.4 5695.1 5751.3 5777.3 5802.2 6203.8 6211.8 6221.5 6227.1 6227.7 5576.7 5752.9 5770.0 5781.8 5798.3 5167.7 5256.4 5265.8 5308.7 5316.6 6314.5 6331.5 6359.6 6359.8 6371.8 6189.5 6206.4 6213.8 6217.4 6234.1 6183.2 6219.9 6250.9 6252.5 6347.2 6108.4 6118.2 6129.6 6130.2 6133.6 6176.2 6184.4 6187.9 6188.0 6189.4 5820.7 5877.8 5894.9 5970.9 5982.0 5841.6 5894.4 5962.9 5987.7 6001.2 5815.6 5823.9 5824.9 5830.4 5834.3 5735.0 5863.6 5950.4 5964.7 5975.3 5737.7 5745.7 5794.4 5820.6 5846.7 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:67166.80 Median:20911.01 stddev:153146.76 (732.4%) stddev-sans-first:153494.45 09:21:17 INFO - PROCESS | 1909 | Values: 448.3 479.8 498.5 506.3 520.8 29456.6 37946.8 38865.6 39175.6 39182.0 39573.4 39839.8 39903.8 39947.6 39992.0 9079.7 9253.2 9267.2 9284.9 9304.6 9138.4 9218.4 9250.0 9258.3 9260.6 9219.5 9266.4 9278.9 9298.1 9303.5 9163.5 9220.9 9227.2 9228.7 9228.8 8959.5 9038.1 9038.2 9056.2 9063.1 38147.8 38473.6 38550.8 38594.4 38651.2 2576.5 3369.4 3462.0 3803.8 3821.3 27743.3 27884.8 27924.0 28970.7 29213.0 3046.5 3151.4 3159.2 3258.8 3312.6 442843.0 447530.8 450301.0 450515.0 450647.0 32425.4 33091.0 33198.3 55992.0 56785.9 34517.8 34531.7 34630.7 34668.5 34742.3 13923.1 14061.4 14062.4 14064.9 14071.4 11786.8 11881.6 11890.5 11904.5 11930.2 7609.7 7610.8 7678.8 7730.8 7744.7 3142.0 3188.9 3191.5 3194.1 3212.9 912.5 933.4 1000.4 1042.6 1048.6 29140.1 29660.0 30889.4 31085.0 31774.8 789.6 973.1 979.5 997.2 1033.9 14415.6 14503.9 14710.2 14747.4 14792.9 664964.0 668075.0 669742.0 671035.0 671226.0 53244.7 54074.7 54075.2 54202.2 54275.7 1200.4 1245.3 1248.4 1346.2 1382.4 24441.4 24451.9 30286.5 30453.0 31135.2 52190.0 52805.2 52911.7 52985.5 53172.0 568334.0 573681.1 573827.0 574371.0 575268.0 25397.1 25817.0 26023.7 26778.7 26837.7 43852.8 44277.8 44413.3 44932.0 45603.8 28878.3 30651.8 35996.3 37662.4 38737.6 36815.1 36906.8 37325.0 37519.1 38018.2 31772.5 32339.0 36648.3 37354.8 37588.0 26279.7 29533.3 33040.8 33630.7 33995.3 23823.9 24791.8 25250.7 25358.9 25432.5 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:34069.99 Median:1579.87 stddev:95206.13 (6026.2%) stddev-sans-first:95439.58 09:21:17 INFO - PROCESS | 1909 | Values: 371.1 375.6 381.7 390.1 399.2 14813.3 15197.8 17977.8 20007.2 20210.7 14953.8 16228.3 19352.0 19435.8 19872.3 5734.1 5957.4 5993.1 6002.3 6032.7 5596.2 5855.0 5896.2 5912.6 5924.1 4052.4 4303.9 5866.7 5872.0 6164.0 5278.4 6487.8 6506.8 6511.4 6521.0 4999.9 5305.5 5341.1 5377.1 5378.9 12778.7 12985.7 15527.5 24218.9 24384.9 2074.3 2175.5 2328.8 2367.3 2788.6 9931.9 9948.2 10138.9 13064.5 13764.6 1715.8 1757.0 1817.9 1819.9 1908.9 109498.5 121605.6 126597.7 127635.0 128560.0 11159.8 11309.2 11507.5 11521.2 11670.3 14569.5 14574.1 14580.8 14594.3 14603.5 8220.6 8296.1 8319.9 8428.6 8433.4 8190.9 8191.4 8229.4 8384.3 8392.6 4436.8 4493.0 4548.6 4556.2 4605.4 1877.5 1886.5 1901.1 1908.3 1949.0 761.6 767.4 769.4 881.0 921.7 15123.8 19507.6 20091.5 20346.0 20520.9 922.8 925.5 927.3 954.8 976.8 7961.3 10374.8 10556.6 10597.3 10620.6 486450.6 571548.0 582713.0 588427.0 589756.1 115393.8 125977.0 128911.0 148869.0 150290.0 959.1 997.4 1007.8 1009.3 1013.1 16321.3 16925.2 17736.5 17891.5 18076.3 43503.6 44792.0 57799.7 59038.0 59531.7 84769.0 86137.8 86354.0 87620.7 88463.0 7858.2 7864.8 8042.8 8095.8 8155.9 10657.9 12252.9 12312.9 12609.2 12653.0 8742.7 10680.9 10705.6 11101.5 11117.4 10717.7 10762.8 10777.7 10791.4 10837.2 10853.0 10940.9 11090.6 11148.7 11370.3 7794.2 7886.7 7937.4 8045.8 8091.1 8239.0 8339.5 9777.6 10939.2 11043.1 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:5345.62 Median:4927.70 stddev:2946.18 (59.8%) stddev-sans-first:2938.88 09:21:17 INFO - PROCESS | 1909 | Values: 1316.1 1471.9 1497.9 1665.0 1844.5 7416.3 7788.0 8009.5 8327.1 8334.7 8174.1 8267.0 8272.5 8278.6 8298.0 2247.5 2271.6 2279.9 2282.5 2283.0 2273.8 2314.6 2330.0 2332.8 2334.0 2259.9 2266.9 2288.1 2289.5 2290.8 2221.5 2246.1 2283.9 2285.7 2288.9 2282.9 2307.3 2307.6 2308.1 2309.6 8184.3 8247.5 8262.2 8262.7 8273.5 4680.4 4852.4 4891.1 4894.9 4903.3 2906.4 3260.0 3388.4 3450.7 3456.5 1038.5 1056.2 1098.8 1106.7 1108.2 10153.9 10817.0 10874.2 10875.2 10899.5 4140.1 4187.8 4271.6 4275.6 4422.4 8552.6 8564.9 8566.3 8576.4 8592.0 5023.5 5037.3 5038.8 5043.7 5071.2 3982.4 4219.3 4349.5 4364.5 4615.7 3597.0 3632.3 3710.4 3714.9 3715.0 1128.6 1136.2 1137.3 1147.1 1147.7 814.6 889.0 899.2 902.9 931.3 5492.3 5600.7 5679.0 5751.3 5811.1 793.9 897.6 911.7 930.4 970.9 3643.7 3706.3 3740.5 3850.3 3944.8 9670.0 10182.2 10183.0 10203.6 10206.9 10092.3 10106.8 10118.1 10119.9 10136.0 6966.4 7749.1 7766.8 7769.4 7770.1 5228.8 5357.2 5504.1 5608.6 5629.9 9244.4 9354.3 9380.6 9382.4 9488.4 8628.2 9378.7 9465.3 9521.5 9563.0 6252.5 6469.5 6661.5 6667.4 6752.7 7795.7 7869.6 7960.3 7971.0 7985.5 7108.7 7113.2 7139.8 7141.4 7198.5 6757.9 6874.9 6979.5 7003.2 7028.2 6411.1 6605.2 6609.4 6621.4 6630.4 4673.2 4693.5 4837.9 4911.4 4994.0 4809.1 4873.8 4890.8 4944.0 4984.5 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4295.75 Median:4706.78 stddev:1311.23 (27.9%) stddev-sans-first:1279.25 09:21:17 INFO - PROCESS | 1909 | Values: 249.4 296.3 304.4 311.1 312.4 4528.6 4764.4 5021.9 5051.7 5140.8 4605.6 4608.9 4807.9 4953.9 4957.3 3094.5 3369.1 3369.9 3822.7 3827.6 3946.3 3951.9 3956.3 3965.5 3990.6 3810.5 3954.7 3963.3 3972.3 4000.9 3476.4 3919.6 4003.6 4022.9 4034.5 2795.2 3160.9 3237.3 3550.9 3553.7 4620.9 4661.1 5050.4 5082.0 5132.3 3743.4 4225.4 4524.4 4549.5 4563.0 4492.3 4557.9 5362.8 5395.6 5395.9 3547.7 3842.7 3844.2 3856.6 3870.4 5029.7 5321.1 5578.5 5609.7 5649.9 3680.9 3879.1 3886.9 3900.9 3928.5 3404.9 3743.1 3988.6 3996.3 3997.7 3989.8 4373.3 4504.9 4531.8 4562.9 5416.6 5418.5 5432.1 5441.4 5453.7 1146.3 1215.0 1221.9 1279.9 1289.3 827.7 832.3 844.9 883.8 903.1 4137.6 4262.7 4614.4 4823.0 4994.3 4378.1 5220.7 5228.0 5339.7 5340.1 4077.2 4275.7 4522.2 4906.8 4944.2 2280.9 2606.5 2622.6 2763.2 2774.4 5580.7 5589.0 5591.3 5614.2 5618.6 4366.4 4779.6 5156.4 5386.7 5435.5 4977.8 5530.1 5579.3 5591.5 5619.4 4355.7 5138.6 5178.5 5197.9 5234.9 4694.7 4827.4 5117.7 5122.3 5243.6 4988.6 5327.9 5384.8 5480.7 5643.8 3179.5 3276.6 3647.9 3690.2 3705.9 4191.9 4718.8 5158.8 5567.7 5657.9 4515.4 4865.5 5031.7 5424.4 5486.9 5044.5 5376.9 5417.8 5432.5 5509.3 4494.8 5087.3 5110.2 5280.4 5305.8 4617.9 5365.6 5373.9 5523.8 5554.9 5373.0 5397.5 5407.6 5409.9 5417.6 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:20741.53 Median:4362.78 stddev:30415.64 (697.2%) stddev-sans-first:30463.74 09:21:17 INFO - PROCESS | 1909 | Values: 698.4 751.4 771.0 783.8 798.2 15976.8 16558.5 16596.2 16639.0 16652.0 14976.7 15111.8 15113.5 15114.5 15123.5 641.2 661.1 814.7 836.6 840.4 660.3 803.6 827.7 831.5 843.9 833.9 856.2 870.3 870.5 870.6 862.1 862.2 864.5 864.8 866.0 444.2 492.6 508.9 511.3 615.1 14268.1 14697.6 14739.8 14749.7 14758.0 12820.1 13104.1 13259.9 13266.2 13267.3 11968.0 12146.5 12209.8 12387.7 12668.8 4382.3 4408.0 4473.8 4486.5 4489.5 93787.1 97350.5 97977.0 99193.5 101550.5 10066.7 10074.5 10331.5 10399.6 10425.4 19461.1 19481.9 19509.0 19523.0 19535.6 7344.9 7351.4 7357.6 7410.4 7647.2 4167.6 4209.8 4212.2 4343.3 4395.5 3244.4 3352.6 3353.9 3364.9 3677.9 687.4 690.1 696.2 705.0 709.2 588.3 622.7 638.4 646.8 672.6 8219.2 8414.0 8522.9 8687.4 8695.7 577.3 634.0 636.8 648.1 670.9 4059.4 4232.0 4256.4 4266.4 4304.9 90255.5 108742.0 109465.5 116672.2 121189.4 73570.0 74127.3 97168.0 108668.0 109047.0 76613.9 82189.8 83250.6 85305.6 85532.1 8803.6 9263.7 9574.6 9838.0 9914.1 51581.2 51905.7 52177.0 53966.7 57280.4 72328.0 72566.0 75761.6 75955.1 76188.0 12131.6 12186.6 12299.2 12328.1 12628.1 13534.0 13668.1 13929.4 14126.6 14158.8 12588.8 12754.4 12885.5 13012.1 13470.9 12116.2 12607.7 12721.4 12857.2 13133.6 12761.6 12771.5 12826.5 12853.7 13206.9 10700.4 10835.8 11115.0 11142.2 11277.9 10398.7 10869.5 11252.2 11288.7 11383.3 09:21:17 INFO - PROCESS | 1909 | -------- Summary: end -------- 09:21:17 INFO - PROCESS | 1909 | 09:21:17 INFO - TEST-INFO | 1909: exit 0 09:21:17 INFO - TEST-OK | dromaeo_css | took 1119820ms 09:21:17 INFO - TEST-START | kraken 09:21:17 INFO - operating with platform_type : mac_ 09:21:17 INFO - Initialising browser for kraken test... 09:21:17 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpDe5agq/profile http://localhost:51729/getInfo.html) 09:21:20 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 09:21:20 INFO - PROCESS | 1913 | colorDepth:24 09:21:20 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 09:21:20 INFO - PROCESS | 1913 | __metrics 09:21:20 INFO - PROCESS | 1913 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:21:20 INFO - PROCESS | 1913 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:21:21 INFO - TEST-INFO | 1913: exit 0 09:21:21 INFO - Browser initialized. 09:21:21 INFO - Running cycle 1/1 for kraken test... 09:21:21 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpDe5agq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:33 INFO - PROCESS | 1914 | RSS: Main: 222699520 09:21:33 INFO - PROCESS | 1914 | 09:21:36 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51729/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:37 INFO - PROCESS | 1914 | RSS: Main: 272482304 09:21:37 INFO - PROCESS | 1914 | 09:21:39 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51729/tests/kraken/driver.html?testName=audio-dft) 09:21:40 INFO - PROCESS | 1914 | RSS: Main: 282873856 09:21:40 INFO - PROCESS | 1914 | 09:21:43 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51729/tests/kraken/driver.html?testName=audio-fft) 09:21:44 INFO - PROCESS | 1914 | RSS: Main: 381612032 09:21:44 INFO - PROCESS | 1914 | 09:21:46 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51729/tests/kraken/driver.html?testName=audio-oscillator) 09:21:46 INFO - PROCESS | 1914 | RSS: Main: 281305088 09:21:46 INFO - PROCESS | 1914 | 09:21:48 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51729/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:49 INFO - PROCESS | 1914 | RSS: Main: 282509312 09:21:49 INFO - PROCESS | 1914 | 09:21:52 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51729/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:53 INFO - PROCESS | 1914 | RSS: Main: 322822144 09:21:53 INFO - PROCESS | 1914 | 09:21:55 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51729/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:56 INFO - PROCESS | 1914 | RSS: Main: 324472832 09:21:56 INFO - PROCESS | 1914 | 09:21:59 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51729/tests/kraken/driver.html?testName=json-parse-financial) 09:21:59 INFO - PROCESS | 1914 | RSS: Main: 322879488 09:21:59 INFO - PROCESS | 1914 | 09:22:01 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51729/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:02 INFO - PROCESS | 1914 | RSS: Main: 296693760 09:22:02 INFO - PROCESS | 1914 | 09:22:04 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:04 INFO - PROCESS | 1914 | RSS: Main: 301101056 09:22:04 INFO - PROCESS | 1914 | 09:22:06 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:07 INFO - PROCESS | 1914 | RSS: Main: 303083520 09:22:07 INFO - PROCESS | 1914 | 09:22:10 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:11 INFO - PROCESS | 1914 | RSS: Main: 323211264 09:22:11 INFO - PROCESS | 1914 | 09:22:14 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:14 INFO - PROCESS | 1914 | RSS: Main: 316477440 09:22:14 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51729/tests/kraken/driver.html?testName=ai-astar) 09:22:16 INFO - PROCESS | 1914 | RSS: Main: 360726528 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | __start_tp_report 09:22:16 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 09:22:16 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 09:22:16 INFO - PROCESS | 1914 | |i|pagename|runs| 09:22:16 INFO - PROCESS | 1914 | |0;ai-astar;117;117;115;116;116;116;116;116;116;123 09:22:16 INFO - PROCESS | 1914 | |1;audio-beat-detection;146;115;131;178;110;108;107;111;124;131 09:22:16 INFO - PROCESS | 1914 | |2;audio-dft;185;186;184;184;185;185;184;184;185;186 09:22:16 INFO - PROCESS | 1914 | |3;audio-fft;81;62;70;116;64;59;57;57;84;59 09:22:16 INFO - PROCESS | 1914 | |4;audio-oscillator;87;71;96;82;97;101;120;68;68;95 09:22:16 INFO - PROCESS | 1914 | |5;imaging-gaussian-blur;90;86;90;89;90;86;91;132;90;86 09:22:16 INFO - PROCESS | 1914 | |6;imaging-darkroom;97;98;99;101;98;99;97;98;99;97 09:22:16 INFO - PROCESS | 1914 | |7;imaging-desaturate;85;83;81;82;81;84;85;85;83;84 09:22:16 INFO - PROCESS | 1914 | |8;json-parse-financial;55;54;55;54;77;92;93;92;92;82 09:22:16 INFO - PROCESS | 1914 | |9;json-stringify-tinderbox;66;72;69;67;73;78;75;74;73;73 09:22:16 INFO - PROCESS | 1914 | |10;stanford-crypto-aes;84;86;84;83;84;81;82;81;84;81 09:22:16 INFO - PROCESS | 1914 | |11;stanford-crypto-ccm;148;160;164;148;128;149;144;141;209;156 09:22:16 INFO - PROCESS | 1914 | |12;stanford-crypto-pbkdf2;164;164;163;172;165;168;222;182;166;223 09:22:16 INFO - PROCESS | 1914 | |13;stanford-crypto-sha256-iterative;68;68;67;67;67;67;67;66;67;67 09:22:16 INFO - PROCESS | 1914 | __end_tp_report 09:22:16 INFO - PROCESS | 1914 | __start_cc_report 09:22:16 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,2068 09:22:16 INFO - PROCESS | 1914 | __end_cc_report 09:22:16 INFO - PROCESS | 1914 | __startTimestamp1483550536943__endTimestamp 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | ------- Summary: start ------- 09:22:16 INFO - PROCESS | 1914 | Number of tests: 14 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#0] ai-astar Cycles:10 Average:116.80 Median:116.00 stddev:2.25 (1.9%) stddev-sans-first:2.39 09:22:16 INFO - PROCESS | 1914 | Values: 117.0 117.0 115.0 116.0 116.0 116.0 116.0 116.0 116.0 123.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:127.50 stddev:22.22 (17.4%) stddev-sans-first:22.37 09:22:16 INFO - PROCESS | 1914 | Values: 146.0 115.0 131.0 178.0 110.0 108.0 107.0 111.0 124.0 131.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:0.79 (0.4%) stddev-sans-first:0.83 09:22:16 INFO - PROCESS | 1914 | Values: 185.0 186.0 184.0 184.0 185.0 185.0 184.0 184.0 185.0 186.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#3] audio-fft Cycles:10 Average:70.90 Median:63.00 stddev:18.57 (29.5%) stddev-sans-first:19.34 09:22:16 INFO - PROCESS | 1914 | Values: 81.0 62.0 70.0 116.0 64.0 59.0 57.0 57.0 84.0 59.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:84.50 stddev:16.70 (19.8%) stddev-sans-first:17.71 09:22:16 INFO - PROCESS | 1914 | Values: 87.0 71.0 96.0 82.0 97.0 101.0 120.0 68.0 68.0 95.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:90.00 stddev:13.84 (15.4%) stddev-sans-first:14.64 09:22:16 INFO - PROCESS | 1914 | Values: 90.0 86.0 90.0 89.0 90.0 86.0 91.0 132.0 90.0 86.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:98.00 stddev:1.25 (1.3%) stddev-sans-first:1.24 09:22:16 INFO - PROCESS | 1914 | Values: 97.0 98.0 99.0 101.0 98.0 99.0 97.0 98.0 99.0 97.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#7] imaging-desaturate Cycles:10 Average:83.30 Median:84.00 stddev:1.57 (1.9%) stddev-sans-first:1.54 09:22:16 INFO - PROCESS | 1914 | Values: 85.0 83.0 81.0 82.0 81.0 84.0 85.0 85.0 83.0 84.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#8] json-parse-financial Cycles:10 Average:74.60 Median:87.00 stddev:18.03 (20.7%) stddev-sans-first:17.67 09:22:16 INFO - PROCESS | 1914 | Values: 55.0 54.0 55.0 54.0 77.0 92.0 93.0 92.0 92.0 82.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:73.00 stddev:3.68 (5.0%) stddev-sans-first:3.20 09:22:16 INFO - PROCESS | 1914 | Values: 66.0 72.0 69.0 67.0 73.0 78.0 75.0 74.0 73.0 73.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:84.00 stddev:1.70 (2.0%) stddev-sans-first:1.76 09:22:16 INFO - PROCESS | 1914 | Values: 84.0 86.0 84.0 83.0 84.0 81.0 82.0 81.0 84.0 81.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#11] stanford-crypto-ccm Cycles:10 Average:154.70 Median:152.50 stddev:21.61 (14.2%) stddev-sans-first:22.78 09:22:16 INFO - PROCESS | 1914 | Values: 148.0 160.0 164.0 148.0 128.0 149.0 144.0 141.0 209.0 156.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:170.00 stddev:23.65 (13.9%) stddev-sans-first:24.46 09:22:16 INFO - PROCESS | 1914 | Values: 164.0 164.0 163.0 172.0 165.0 168.0 222.0 182.0 166.0 223.0 09:22:16 INFO - PROCESS | 1914 | 09:22:16 INFO - PROCESS | 1914 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.50 09:22:16 INFO - PROCESS | 1914 | Values: 68.0 68.0 67.0 67.0 67.0 67.0 67.0 66.0 67.0 67.0 09:22:16 INFO - PROCESS | 1914 | -------- Summary: end -------- 09:22:16 INFO - PROCESS | 1914 | 09:22:17 INFO - TEST-INFO | 1914: exit 0 09:22:18 INFO - TEST-OK | kraken | took 60322ms 09:22:18 INFO - SUITE-END | took 1180s 09:22:18 INFO - Completed test suite (00:19:40) 09:22:18 INFO - javascript benchmark 09:22:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.053424506893, 3318.2865683877963, 3321.8671253149873, 3340.2651416688336, 3384.8476818543163, 5543.029969755292, 5625.0, 5632.26780518532, 5633.0987042073275, 5644.407337229593, 4328.84849030284, 5059.949400505994, 5558.027345214581, 5626.324841019084, 5646.604737668355, 5121.308623335848, 5156.3296771419855, 5164.147915593922, 5228.294180285669, 5242.3184985951775, 5110.182370820669, 5116.872078198037, 5138.768755406016, 5165.845024649267, 5262.81580144695, 5120.692758434506, 5175.8188463403785, 5197.4022987356375, 5210.713410762407, 5214.087534681435, 4272.9359059614135, 5180.041692286939, 5197.922031169535, 5214.895702085956, 5243.187305967562, 5097.541221290065, 5198.5061419165495, 5204.29741984831, 5217.713025783581, 5254.264402983586, 5466.9179962300595, 5533.170024496318, 5641.633293835928, 5652.180433837112, 5733.913991290134, 6020.157177995085, 6027.60820546663, 6035.456808887177, 6054.0, 6095.207623009025, 6187.783427580013, 6207.161648786679, 6229.968850155766, 6232.906506402408, 6240.594361366497, 5514.696691681916, 5682.431756824326, 5693.28833895763, 5714.142878568248, 5739.167820666022, 6151.0, 6228.688565571703, 6233.407826256498, 6279.152314437584, 6301.243850737941, 4980.9252861207115, 6013.917494850969, 6036.547258955596, 6036.63780173191, 6071.666058366833, 5396.0, 6176.413240742212, 6181.629102253879, 6198.318184999646, 6203.875922481525, 5878.911816322759, 5883.970580147072, 5945.494632956247, 6026.367231440723, 6064.151018857364, 6136.439666665066, 6139.3388173831, 6140.201773769381, 6140.478059365004, 6157.84605384869, 4845.63657725672, 4845.830395936126, 4848.975755121272, 4853.708777473363, 4870.512948705172, 4210.410542524049, 4231.259529582311, 4267.8079486423185, 4322.459692538433, 4337.414449049338, 4783.402074740658, 5695.117256825199, 5751.338596061423, 5777.306723193244, 5802.158686990363, 6203.844903877439, 6211.782587609502, 6221.5333849961435, 6227.097070924781, 6227.719752611053, 5576.693281869536, 5752.884942301214, 5770.0, 5781.826545203567, 5798.275215598051, 5167.689938603696, 5256.448072952361, 5265.842024739264, 5308.708021058879, 5316.601254905898, 6314.4632706218645, 6331.493480521639, 6359.618422894641, 6359.8092057238355, 6371.840703982438, 6189.535784816247, 6206.379362063757, 6213.844653883689, 6217.409346112112, 6234.127222188807, 6183.196184495987, 6219.937800621936, 6250.874982500233, 6252.531060170414, 6347.174867267226, 6108.402780883668, 6118.223266370627, 6129.603311142764, 6130.153553065376, 6133.579501509479, 6176.227971503562, 6184.412480814315, 6187.876242475215, 6188.0, 6189.412005859616, 5820.70896455184, 5877.823665290048, 5894.911576326444, 5970.880582388241, 5981.970090149522, 5841.591088623755, 5894.38108998549, 5962.880742385215, 5987.670678112746, 6001.189839371804, 5815.563832712648, 5823.912641310469, 5824.883502330014, 5830.4461076197695, 5834.299884013945, 5734.971325143348, 5863.560232982629, 5950.434708702666, 5964.701764911824, 5975.342712301556, 5737.741801618854, 5745.712714364348, 5794.420557944172, 5820.621659592282, 5846.678432686073], "value": 5596.263582721941, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.34317724533554, 479.79848463645266, 498.54632284621005, 506.291192330737, 520.7994921955046, 29456.55815162774, 37946.8102659487, 38865.61134388655, 39175.60824391755, 39182.0, 39573.40639890404, 39839.8048058558, 39903.800480997554, 39947.60052399482, 39992.0, 9079.682211122628, 9253.167214950654, 9267.165955064043, 9284.907150928506, 9304.627814887397, 9138.406003609743, 9218.400803947756, 9249.95375023124, 9258.305627077962, 9260.583273752698, 9219.492927888963, 9266.397684150543, 9278.860817087716, 9298.070192980715, 9303.534823258844, 9163.450192988408, 9220.907790922072, 9227.15456908621, 9228.723138305862, 9228.769280768001, 8959.462432254086, 9038.096190380975, 9038.186563209343, 9056.230220431271, 9063.13900179482, 38147.809260953516, 38473.61526384756, 38550.80724596359, 38594.42108368377, 38651.22697546033, 2576.471823276224, 3369.393509168349, 3461.9826900865332, 3803.7717736935597, 3821.312163810513, 27743.30641733973, 27884.817277408347, 27924.022659207032, 28970.710292897224, 29212.97754578601, 3046.5430185472137, 3151.369726054775, 3159.210197450637, 3258.8370581471067, 3312.569365982431, 442843.0, 447530.76234618947, 450301.0, 450515.0, 450647.0, 32425.351492970007, 33091.0, 33198.3360332792, 55992.0, 56785.889269965075, 34517.82741086279, 34531.654683453344, 34630.653693462744, 34668.47997280043, 34742.30515389678, 13923.094998825043, 14061.367238474293, 14062.367193476319, 14064.859351406354, 14071.437142514389, 11786.764264714659, 11881.643550693492, 11890.464929078213, 11904.523819047314, 11930.22453540517, 7609.6575654095695, 7610.8097297568, 7678.8080297993, 7730.806729831799, 7744.690212391554, 3141.9842900785807, 3188.920276993094, 3191.5372271020574, 3194.0577529628818, 3212.855421506038, 912.5118061836886, 933.3979583168818, 1000.364768372089, 1042.6038105519854, 1048.5648455890873, 29140.125796226148, 29659.96190133335, 30889.382212355627, 31085.0, 31774.841125794224, 789.5815217934504, 973.0950216298769, 979.5298256836759, 997.1723469520281, 1033.9224558158019, 14415.639609009859, 14503.85496145046, 14710.175354407931, 14747.410103595736, 14792.926035369754, 664964.0, 668075.0, 669742.0, 671035.0, 671226.0, 53244.73377633087, 54074.72962635162, 54075.188872166946, 54202.18696719552, 54275.72862135664, 1200.3818033712646, 1245.3337464456472, 1248.3820508848178, 1346.158650843223, 1382.3502953611787, 24441.38896527601, 24451.877740611184, 30286.54570181405, 30453.0, 31135.221619459695, 52189.95620087653, 52805.207921880436, 52911.73544132255, 52985.47014529805, 53172.0, 568334.0, 573681.1315943394, 573827.0, 574371.0, 575268.0, 25397.11110111137, 25816.967321306936, 26023.73976260289, 26778.732212677623, 26837.731622683525, 43852.780736096116, 44277.77861110674, 44413.33379999367, 44932.0, 45603.77198113988, 28878.278043049093, 30651.77392904259, 35996.28007439889, 37662.435063473524, 38737.6126238734, 36815.14653139688, 36906.8154659225, 37325.0, 37519.06202344963, 38018.239635206584, 31772.523412148374, 32339.0, 36648.26703465969, 37354.813225934784, 37588.0, 26279.73720262773, 29533.26166845846, 33040.83479582587, 33630.663693363735, 33995.320093598486, 23823.880880595487, 24791.752082478943, 25250.74749252484, 25358.873205633852, 25432.491350173263], "value": 17799.201119842146, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.1482148469263, 375.58122693197095, 381.6851097844278, 390.0541187620021, 399.15976868691405, 14813.333399996998, 15197.772033419507, 17977.820221797778, 20007.1997120115, 20210.6968395474, 14953.775693364581, 16228.269727862245, 19352.0, 19435.805641943578, 19872.304469343577, 5734.139879018149, 5957.404259574029, 5993.071073983518, 6002.279726432834, 6032.698365081728, 5596.2165296858475, 5855.0, 5896.174535565025, 5912.556558258127, 5924.081767326072, 4052.412400201969, 4303.892402689927, 5866.73599688015, 5872.0, 6164.0, 5278.392984806739, 6487.772927947546, 6506.837329066764, 6511.4465270452065, 6521.0, 4999.925001124986, 5305.522502974751, 5341.092014357569, 5377.085895397752, 5378.8924221515745, 12778.680532986657, 12985.675358116028, 15527.534173974798, 24218.878905605536, 24384.878075609686, 2074.2532688232222, 2175.510510135207, 2328.7904088631935, 2367.3134790910617, 2788.5956536302324, 9931.851022234672, 9948.204143668489, 10138.9493052535, 13064.477420903153, 13764.587062388144, 1715.794104707443, 1756.956076098095, 1817.854571634266, 1819.9454016379398, 1908.9045547722696, 109498.45250773695, 121605.5986003508, 126597.73402265951, 127635.0, 128560.0, 11159.776804463865, 11309.151813614011, 11507.539698412138, 11521.193516453768, 11670.299782013106, 14569.490067847575, 14574.052686575338, 14580.781288280685, 14594.270286485633, 14603.488877889435, 8220.58897055145, 8296.087430382653, 8319.916800832036, 8428.609279468941, 8433.439813634497, 8190.8771368429525, 8191.426600137933, 8229.423940324239, 8384.287335576544, 8392.622331995075, 4436.75770784181, 4492.9550704493195, 4548.56788605082, 4556.225441674923, 4605.424321959755, 1877.4649224970817, 1886.5290657094536, 1901.115981068782, 1908.332083770697, 1948.9610207795765, 761.6420282467232, 767.3745897093851, 769.4075561817369, 880.9735707928644, 921.709661456639, 15123.848761512465, 19507.609847803247, 20091.497712557302, 20346.0, 20520.87135207548, 922.7563625539461, 925.5187302602743, 927.2674587076134, 954.7660823098386, 976.770458942148, 7961.283484486424, 10374.792504149875, 10556.577736890438, 10597.311174773615, 10620.628278010232, 486450.567747159, 571548.0, 582713.0, 588427.0, 589756.1024389787, 115393.84606154, 125977.0, 128911.0, 148869.0, 150290.0, 959.12240300126, 997.3766396002499, 1007.7833265847731, 1009.2985375164191, 1013.0578561937469, 16321.26554305083, 16925.238364273882, 17736.467905962843, 17891.46325610234, 18076.276948922423, 43503.56496434995, 44792.0, 57799.711001444724, 59038.0, 59531.70234148802, 84769.0, 86137.8317300964, 86354.0, 87620.68568971343, 88463.0, 7858.174891636524, 7864.84270314579, 8042.758717238492, 8095.83808323842, 8155.877661835196, 10657.893421066, 12252.938735306623, 12312.876871231172, 12609.180403273758, 12653.0, 8742.694005709769, 10680.8931910683, 10705.625303114353, 11101.50043248072, 11117.44412779374, 10717.732056698645, 10762.838557421801, 10777.676669699922, 10791.352518848895, 10837.241393102408, 10853.0, 10940.890591094305, 11090.611828586283, 11148.665540033811, 11370.260933039326, 7794.181610930771, 7886.684532618631, 7937.444378893653, 8045.83908321842, 8091.1099779025435, 8238.958805205935, 8339.499630022217, 9777.608895644378, 10939.234253602172, 11043.061339786209], "value": 9265.928476660914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.0918965913525, 1471.8528147185282, 1497.8651921327082, 1664.9750253746197, 1844.4835446075094, 7416.295451932065, 7787.987561616989, 8009.4793838400465, 8327.125651806564, 8334.708285210003, 8174.141715119916, 8267.007959044908, 8272.503649781002, 8278.62746176422, 8298.045724741656, 2247.505548779266, 2271.5684020036224, 2279.943001424961, 2282.4978504728933, 2282.9771702283015, 2273.7953584177503, 2314.5718042162252, 2329.9767002330013, 2332.8017118544944, 2334.031376978552, 2259.8644081355174, 2266.875321857297, 2288.1305104060434, 2289.5077558324956, 2290.828187885907, 2221.533477969628, 2246.1352379334076, 2283.862968221912, 2285.702858628384, 2288.942776430586, 2282.885855707208, 2307.3308740465245, 2307.6307790753567, 2308.0767692922796, 2309.6304591265316, 8184.345252379795, 8247.546384948826, 8262.17378262175, 8262.669493220264, 8273.503589784632, 4680.4149481314835, 4852.393450818648, 4891.144049791273, 4894.94758626897, 4903.338049363326, 2906.4187162567573, 3260.041389548399, 3388.4070287699556, 3450.723942084652, 3456.5160877477174, 1038.4911393417246, 1056.2447849787438, 1098.758273179899, 1106.6845948904524, 1108.2353176308322, 10153.949230253875, 10817.0, 10874.238803283773, 10875.184361172944, 10899.509522071527, 4140.0891803803115, 4187.811548480326, 4271.615554600101, 4275.551067137968, 4422.380866678667, 8552.61513231906, 8564.871526927102, 8566.27186689126, 8576.44253123545, 8592.0, 5023.497650234947, 5037.345145131109, 5038.773255203525, 5043.747812609355, 5071.163258062452, 3982.3628219484744, 4219.346001369792, 4349.543297953669, 4364.519902810688, 4615.653825963066, 3597.0467826026124, 3632.2735452908996, 3710.387786015331, 3714.851405943732, 3715.0, 1128.554221082684, 1136.2103338179886, 1137.3232926408773, 1147.0995268714018, 1147.65570328901, 814.6171299489231, 888.9821154777743, 899.2491269789668, 902.8555431130987, 931.3201363005044, 5492.258545096441, 5600.663960162403, 5678.977783998837, 5751.252337196138, 5811.099279611667, 793.8531371696139, 897.6095398501673, 911.7492689510437, 930.3766476460787, 970.9417434953925, 3643.708503319728, 3706.314331848617, 3740.4576336431064, 3850.268448994682, 3944.8422063117155, 9670.032996700274, 10182.18542516597, 10183.032611901857, 10203.591856325813, 10206.897931020743, 10092.293539452168, 10106.848397274047, 10118.08937195656, 10119.898801012043, 10136.037076477724, 6966.373026427545, 7749.108852482037, 7766.8058298541855, 7769.417293702882, 7770.067591889009, 5228.790848366023, 5357.19642053695, 5504.091824848855, 5608.551315894801, 5629.915551266652, 9244.399114057565, 9354.29842761782, 9380.624775009197, 9382.390144640576, 9488.430694158373, 8628.22345988864, 9378.718638440858, 9465.337426380087, 9521.476318802255, 9563.04369563066, 6252.499800015897, 6469.45009674183, 6661.500387470862, 6667.366600172976, 6752.729890804313, 7795.727149549739, 7869.606519674108, 7960.323372513401, 7971.0, 7985.480943738638, 7108.680109395193, 7113.217546070032, 7139.750108746169, 7141.428685705235, 7198.49610527258, 6757.898631520433, 6874.931250687429, 6979.546329488567, 7003.229644738973, 7028.156621205488, 6411.102445657519, 6605.240397354366, 6609.438197753245, 6621.404073633396, 6630.403263706289, 4673.158831410379, 4693.483716791082, 4837.879053023703, 4911.386076740408, 4993.975030124826, 4809.134355815847, 4873.829415970424, 4890.7554622268035, 4944.01119776039, 4984.451710311935], "value": 4330.848792463237, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.38526532098385, 296.3155111691991, 304.4231181910281, 311.05439464029354, 312.4235784976717, 4528.637708983278, 4764.404449443819, 5021.949780502194, 5051.696898186111, 5140.794368225276, 4605.562471565205, 4608.870826647468, 4807.8798030049265, 4953.876153096175, 4957.256411538271, 3094.4584697677874, 3369.073504786179, 3369.9384693821366, 3822.73240873139, 3827.5981021992707, 3946.3291240489043, 3951.9209615807667, 3956.3472027115636, 3965.5241371035518, 3990.580988996157, 3810.5046343975246, 3954.6836253099827, 3963.3460479021073, 3972.324704800191, 4000.9399859002137, 3476.4090104682264, 3919.568847426781, 4003.6187515307174, 4022.8994275143064, 4034.536028356747, 2795.203367040384, 3160.8893688720786, 3237.264511487813, 3550.8579656813954, 3553.6979356754705, 4620.897491641295, 4661.137689527448, 5050.39395272566, 5081.958198569285, 5132.332796736438, 3743.3636281832155, 4225.3873188387515, 4524.411826462571, 4549.499555048942, 4563.0150884365075, 4492.326151077365, 4557.908841823178, 5362.8123015694655, 5395.622306438551, 5395.866867957734, 3547.6984456321243, 3842.731008829383, 3844.1542860570908, 3856.5950575189763, 3870.3807390817333, 5029.67307125043, 5321.122014867542, 5578.497935185812, 5609.719514024323, 5649.887002259932, 3680.8895733128047, 3879.0884142226555, 3886.8639597613947, 3900.8829735107993, 3928.489296391451, 3404.8978530643626, 3743.12036671382, 3988.581198974124, 3996.3406038003404, 3997.720159588801, 3989.8204580793936, 4373.278409062469, 4504.932426013613, 4531.84138555149, 4562.908741825145, 5416.64375996435, 5418.512333889968, 5432.103702889058, 5441.401445840955, 5453.727313634303, 1146.2893006335958, 1214.9854871182486, 1221.9389030548436, 1279.8801049082053, 1289.265118882228, 827.7475370012204, 832.3382910585977, 844.9058469282294, 883.8055627761883, 903.0653273861521, 4137.586241375839, 4262.742490965173, 4614.423821538335, 4823.03539292143, 4994.250862370601, 4378.088571100247, 5220.7128607926925, 5228.0, 5339.679619222859, 5340.0508814283285, 4077.1641813428178, 4275.679324050647, 4522.186006518858, 4906.779194936236, 4944.233643785219, 2280.9393631960884, 2606.4656745367156, 2622.561018253043, 2763.226296636945, 2774.375765452757, 5580.748866301026, 5588.9600295891705, 5591.27313449249, 5614.214010038598, 5618.634788738801, 4366.410534577792, 4779.569838714532, 5156.432792392834, 5386.663735692805, 5435.483629055233, 4977.751112444435, 5530.087535556669, 5579.261894530674, 5591.496765290981, 5619.4099619540175, 4355.735017790667, 5138.640295179302, 5178.456262092426, 5197.856471576247, 5234.895302093861, 4694.74178920149, 4827.40695570468, 5117.748190796038, 5122.334096567422, 5243.606729495379, 4988.600911927109, 5327.856564626889, 5384.7576859040655, 5480.684635687486, 5643.7742490299925, 3179.523071539195, 3276.574045374086, 3647.854085836537, 3690.1954514568583, 3705.9444108338935, 4191.8532851350055, 4718.811247550059, 5158.845234642967, 5567.719424532299, 5657.9151312731165, 4515.39042229308, 4865.464798872192, 5031.6729412588065, 5424.403315635356, 5486.917696234639, 5044.470330615232, 5376.876133927155, 5417.835165439449, 5432.456754324536, 5509.256250406143, 4494.7752612368595, 5087.3386459759995, 5110.233464980282, 5280.3663560373, 5305.771575938193, 4617.861464156081, 5365.6244062916785, 5373.865653358697, 5523.751431185687, 5554.944450555605, 5373.038840822993, 5397.487238712316, 5407.621466497308, 5409.945900541102, 5417.566594672496], "value": 3878.9373672738575, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3819319901887, 751.4138971602149, 770.9730159444421, 783.8046978358, 798.2257210505808, 15976.760348594766, 16558.50324490264, 16596.170191490437, 16639.0, 16652.0, 14976.700465990674, 15111.77332340013, 15113.471028514005, 15114.47099351523, 15123.470678526253, 641.2337256977913, 661.0513912535503, 814.7107776739247, 836.5691668790578, 840.4033136473112, 660.3165723476203, 803.6463955859432, 827.7475370012144, 831.4678605692362, 843.8565443874556, 833.945793523422, 856.2037305306063, 870.29071306885, 870.5342641686699, 870.5516658920661, 862.0603542139098, 862.2153840005597, 864.4640322999718, 864.7751584588021, 865.9783505412352, 444.19378827428113, 492.5616201580596, 508.8651507350555, 511.2637801565733, 615.1203778596605, 14268.143911365247, 14697.55907322782, 14739.778903316459, 14749.705005899928, 14758.0, 12820.102592818506, 13104.148230365092, 13259.933700331436, 13266.204027758366, 13267.336633168303, 11968.0, 12146.45340959659, 12209.816852747215, 12387.690307742288, 12668.809967850397, 4382.298832186835, 4407.955920440787, 4473.753943533105, 4486.461624605068, 4489.506154323022, 93787.06212937851, 97350.51324743402, 97977.0, 99193.5040324801, 101550.47674284891, 10066.748331291776, 10074.496275186211, 10331.535080921227, 10399.636012739518, 10425.426601536836, 19461.12424940881, 19481.90259048724, 19509.0, 19523.0, 19535.609287814164, 7344.926550734424, 7351.375133113638, 7357.59533225667, 7410.407167426593, 7647.235276472419, 4167.562405947392, 4209.768462734518, 4212.220739163204, 4343.261645520269, 4395.472543294825, 3244.367348367046, 3352.580927384077, 3353.9496907546404, 3364.882229121969, 3677.852885884588, 687.4466054825813, 690.1304356510732, 696.1889398850384, 705.0376236437329, 709.1667290933133, 588.2529187931389, 622.7228883147046, 638.3999040901538, 646.8415238266655, 672.586359388982, 8219.219174178443, 8414.0, 8522.914770852336, 8687.435316704394, 8695.73911782788, 577.3331801768963, 633.9746410143634, 636.8407898025494, 648.1023782061784, 670.8758879607288, 4059.4316795648633, 4232.0, 4256.361545768098, 4266.381374700651, 4304.913901721948, 90255.54872225598, 108742.0, 109465.45267273772, 116672.24991625133, 121189.39405303093, 73570.0, 74127.25872741203, 97168.0, 108668.0, 109047.0, 76613.85079223815, 82189.76715349275, 83250.58374708088, 85305.57347213349, 85532.14467855367, 8803.603837827184, 9263.687602653781, 9574.617015319309, 9837.950810245902, 9914.058164474363, 51581.226281606556, 51905.7404712974, 52177.0, 53966.65083372947, 57280.42719572751, 72328.0, 72566.0, 75761.62119189369, 75955.10112247238, 76188.0, 12131.575394861138, 12186.634400967985, 12299.200551964093, 12328.137030408145, 12628.116031878048, 13534.0, 13668.111572747739, 13929.373178206803, 14126.576202713935, 14158.805385165317, 12588.811167832308, 12754.42605082755, 12885.48458061667, 13012.089153759525, 13470.865291346961, 12116.15186936906, 12607.684807879876, 12721.363931803187, 12857.228566286052, 13133.60599182026, 12761.61715148547, 12771.48914043465, 12826.486940522274, 12853.742925141258, 13206.867931320563, 10700.41147736882, 10835.783284334111, 11115.0, 11142.220044596801, 11277.887221127683, 10398.68803935883, 10869.51087201062, 11252.21234513609, 11288.661340159808, 11383.317000979967], "value": 7325.0823180329935, "unit": "score"}], "name": "dromaeo_css", "value": 6959.135793721555, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 115.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 123.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 131.0, 178.0, 110.0, 108.0, 107.0, 111.0, 124.0, 131.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 184.0, 184.0, 185.0, 185.0, 184.0, 184.0, 185.0, 186.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 70.0, 116.0, 64.0, 59.0, 57.0, 57.0, 84.0, 59.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 96.0, 82.0, 97.0, 101.0, 120.0, 68.0, 68.0, 95.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 89.0, 90.0, 86.0, 91.0, 132.0, 90.0, 86.0], "value": 93.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 99.0, 101.0, 98.0, 99.0, 97.0, 98.0, 99.0, 97.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 81.0, 82.0, 81.0, 84.0, 85.0, 85.0, 83.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 77.0, 92.0, 93.0, 92.0, 92.0, 82.0], "value": 74.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 69.0, 67.0, 73.0, 78.0, 75.0, 74.0, 73.0, 73.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 83.0, 84.0, 81.0, 82.0, 81.0, 84.0, 81.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 160.0, 164.0, 148.0, 128.0, 149.0, 144.0, 141.0, 209.0, 156.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 172.0, 165.0, 168.0, 222.0, 182.0, 166.0, 223.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1492.0, "alertThreshold": 2.0}]} 09:22:18 INFO - Return code: 0 09:22:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:22:18 INFO - # TBPL SUCCESS # 09:22:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:18 INFO - 2017-01-04 09:22:18.242 screenresolution[1918:16214] starting screenresolution argv=screenresolution get 09:22:18 INFO - 2017-01-04 09:22:18.245 screenresolution[1918:16214] Display 0: 1600x1200x32@60 09:22:18 INFO - 2017-01-04 09:22:18.251 screenresolution[1919:16217] starting screenresolution argv=screenresolution list 09:22:18 INFO - Available Modes on Display 0 09:22:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:18 INFO - Intel Iris: 09:22:18 INFO - Chipset Model: Intel Iris 09:22:18 INFO - Type: GPU 09:22:18 INFO - Bus: Built-In 09:22:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:18 INFO - Vendor: Intel (0x8086) 09:22:18 INFO - Device ID: 0x0a2e 09:22:18 INFO - Revision ID: 0x0009 09:22:18 INFO - Displays: 09:22:18 INFO - Display: 09:22:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:18 INFO - Display Serial Number: 1600x1200 60 09:22:18 INFO - Main Display: Yes 09:22:18 INFO - Mirror: Off 09:22:18 INFO - Online: Yes 09:22:18 INFO - Rotation: Supported 09:22:18 INFO - Return code: 0 09:22:18 INFO - Running post-action listener: _resource_record_post_action 09:22:18 INFO - [mozharness: 2017-01-04 17:22:18.499624Z] Finished run-tests step (success) 09:22:18 INFO - Running post-run listener: _resource_record_post_run 09:22:18 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 757760; Write bytes: 653514240; Read time: 10; Write time: 1893 09:22:18 INFO - TinderboxPrint: CPU usage
53.4% 09:22:18 INFO - TinderboxPrint: I/O read bytes / time
757,760 / 10 09:22:18 INFO - TinderboxPrint: I/O write bytes / time
653,514,240 / 1,893 09:22:18 INFO - TinderboxPrint: CPU idle
2,257.8 (46.6%) 09:22:18 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 09:22:18 INFO - TinderboxPrint: CPU user
1,617.8 (33.4%) 09:22:18 INFO - TinderboxPrint: Swap in / out
349,908,992 / 0 09:22:18 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 338520576; Write bytes: 334993408; Read time: 26661; Write time: 703 09:22:18 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 757760; Write bytes: 300826112; Read time: 10; Write time: 1134 09:22:18 INFO - Running post-run listener: _upload_blobber_files 09:22:18 INFO - Blob upload gear active. 09:22:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:22:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:22:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:22:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:20 INFO - (blobuploader) - INFO - Done attempting. 09:22:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:22:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:22:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:21 INFO - (blobuploader) - INFO - Done attempting. 09:22:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:22:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:23 INFO - (blobuploader) - INFO - Done attempting. 09:22:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:23 INFO - Return code: 0 09:22:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a194670d545094d8a2556f9da065fa1d903b5f4e19d06a1af075f297319e87a12925a832a7923846ddf9134c887795498cec5950341bb27a9f4ccad49539f9ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3c8bc34f06dfd710b362d62416b0f6613ccf92f303d8f0e0af58e8d07b73b421edcc08928ed01f062ae44f6d4f1c32828661ca7f6f0a8b46eadb9ca19aabd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c699a0621e31ad536a9f43bb8a943a3773f75085a3d2a0a7889f618f52be491d138fad021590ee58c47d3043c8eca934e67737f2498985f959ad75ac03c3cdf"} 09:22:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:23 INFO - Contents: 09:22:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a194670d545094d8a2556f9da065fa1d903b5f4e19d06a1af075f297319e87a12925a832a7923846ddf9134c887795498cec5950341bb27a9f4ccad49539f9ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3c8bc34f06dfd710b362d62416b0f6613ccf92f303d8f0e0af58e8d07b73b421edcc08928ed01f062ae44f6d4f1c32828661ca7f6f0a8b46eadb9ca19aabd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c699a0621e31ad536a9f43bb8a943a3773f75085a3d2a0a7889f618f52be491d138fad021590ee58c47d3043c8eca934e67737f2498985f959ad75ac03c3cdf"} 09:22:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:23 INFO - Copying logs to upload dir... 09:22:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.224441 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 09:22:23.974464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:22:23.978133) ========= 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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a194670d545094d8a2556f9da065fa1d903b5f4e19d06a1af075f297319e87a12925a832a7923846ddf9134c887795498cec5950341bb27a9f4ccad49539f9ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3c8bc34f06dfd710b362d62416b0f6613ccf92f303d8f0e0af58e8d07b73b421edcc08928ed01f062ae44f6d4f1c32828661ca7f6f0a8b46eadb9ca19aabd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c699a0621e31ad536a9f43bb8a943a3773f75085a3d2a0a7889f618f52be491d138fad021590ee58c47d3043c8eca934e67737f2498985f959ad75ac03c3cdf"} build_url:https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011168 build_url: 'https://queue.taskcluster.net/v1/task/WLpbhH3EQ9ifczfOuFmyDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a194670d545094d8a2556f9da065fa1d903b5f4e19d06a1af075f297319e87a12925a832a7923846ddf9134c887795498cec5950341bb27a9f4ccad49539f9ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3c8bc34f06dfd710b362d62416b0f6613ccf92f303d8f0e0af58e8d07b73b421edcc08928ed01f062ae44f6d4f1c32828661ca7f6f0a8b46eadb9ca19aabd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c699a0621e31ad536a9f43bb8a943a3773f75085a3d2a0a7889f618f52be491d138fad021590ee58c47d3043c8eca934e67737f2498985f959ad75ac03c3cdf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:22:24.013293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:22:24.013708) ========= 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.rfBWrA3QIn/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.nx9Cnr4ymH/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.005065 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:22:24.062810) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:22:24.063179) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:22:24.063584) ========= ========= Total master_lag: 0.18 =========