builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0383 starttime: 1479859769.03 results: success (0) buildid: 20161122145719 builduid: a5135d4e4298484e8ca5c03daec65fef revision: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.030104) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.031089) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.031681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.059251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.059726) ========= 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.FERxgMM9YC/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.RMSkRfohln/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-22 16:09:29-- 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% 18.9M=0.001s 2016-11-22 16:09:29 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.435004 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.518998) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.519359) ========= 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.FERxgMM9YC/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.RMSkRfohln/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.023921 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.567963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 16:09:29.571402) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee --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 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FERxgMM9YC/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.RMSkRfohln/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-22 16:09:29,666 truncating revision to first 12 chars 2016-11-22 16:09:29,666 Setting DEBUG logging. 2016-11-22 16:09:29,667 attempt 1/10 2016-11-22 16:09:29,667 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5468cd9ed8e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 16:09:30,761 unpacking tar archive at: mozilla-aurora-5468cd9ed8e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.368859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 16:09:30.964539) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:30.964909) ========= script_repo_revision: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:30.965393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:30.965697) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:30.985425) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-11-22 16:09:30.985737) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FERxgMM9YC/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.RMSkRfohln/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:09:31 INFO - MultiFileLogger online at 20161122 16:09:31 in /builds/slave/test 16:09:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:09:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:31 INFO - {'append_to_log': False, 16:09:31 INFO - 'base_work_dir': '/builds/slave/test', 16:09:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:09:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:31 INFO - 'branch': 'Mozilla-Aurora', 16:09:31 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:31 INFO - 'config_files': ('talos/mac_config.py',), 16:09:31 INFO - 'default_actions': ('clobber', 16:09:31 INFO - 'read-buildbot-config', 16:09:31 INFO - 'download-and-extract', 16:09:31 INFO - 'populate-webroot', 16:09:31 INFO - 'create-virtualenv', 16:09:31 INFO - 'install', 16:09:31 INFO - 'run-tests'), 16:09:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:31 INFO - 'download_minidump_stackwalk': True, 16:09:31 INFO - 'download_symbols': 'ondemand', 16:09:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:31 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:31 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:31 INFO - 'installer_path': 'installer.exe', 16:09:31 INFO - 'log_level': 'info', 16:09:31 INFO - 'log_name': 'talos', 16:09:31 INFO - 'log_to_console': True, 16:09:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:09:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:09:31 INFO - 'opt_config_files': (), 16:09:31 INFO - 'pip_index': False, 16:09:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:31 INFO - 'cmd': ('bash', 16:09:31 INFO - '-c', 16:09:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:31 INFO - 'enabled': True, 16:09:31 INFO - 'halt_on_failure': False, 16:09:31 INFO - 'name': 'check_screen_resolution'},), 16:09:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:31 INFO - 'cmd': ('bash', 16:09:31 INFO - '-c', 16:09:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:31 INFO - 'enabled': True, 16:09:31 INFO - 'halt_on_failure': False, 16:09:31 INFO - 'name': 'check_screen_resolution'},), 16:09:31 INFO - 'run_cmd_checks_enabled': True, 16:09:31 INFO - 'sps_profile': False, 16:09:31 INFO - 'sps_profile_interval': 0, 16:09:31 INFO - 'suite': 'dromaeojs-e10s', 16:09:31 INFO - 'system_bits': '32', 16:09:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:31 INFO - 'title': 't-yosemite-r7-0383', 16:09:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:31 INFO - 'use_talos_json': True, 16:09:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:31 INFO - 'work_dir': 'build'} 16:09:31 INFO - [mozharness: 2016-11-23 00:09:31.190179Z] Running clobber step. 16:09:31 INFO - Running pre-action listener: _resource_record_pre_action 16:09:31 INFO - Running main action method: clobber 16:09:31 INFO - rmtree: /builds/slave/test/build 16:09:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:33 INFO - Running post-action listener: _resource_record_post_action 16:09:33 INFO - [mozharness: 2016-11-23 00:09:33.679217Z] Finished clobber step (success) 16:09:33 INFO - [mozharness: 2016-11-23 00:09:33.679471Z] Running read-buildbot-config step. 16:09:33 INFO - Running pre-action listener: _resource_record_pre_action 16:09:33 INFO - Running main action method: read_buildbot_config 16:09:33 INFO - Using buildbot properties: 16:09:33 INFO - { 16:09:33 INFO - "project": "", 16:09:33 INFO - "product": "firefox", 16:09:33 INFO - "script_repo_revision": "production", 16:09:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 16:09:33 INFO - "repository": "", 16:09:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 16:09:33 INFO - "buildid": "20161122145719", 16:09:33 INFO - "pgo_build": "False", 16:09:33 INFO - "basedir": "/builds/slave/test", 16:09:33 INFO - "buildnumber": 2, 16:09:33 INFO - "slavename": "t-yosemite-r7-0383", 16:09:33 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:09:33 INFO - "platform": "yosemite_r7", 16:09:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:09:33 INFO - "branch": "mozilla-aurora", 16:09:33 INFO - "repo_path": "releases/mozilla-aurora", 16:09:33 INFO - "revision": "5468cd9ed8e4a4cc32d37135842c04a7df8a58ee", 16:09:33 INFO - "stage_platform": "macosx64", 16:09:33 INFO - "builduid": "a5135d4e4298484e8ca5c03daec65fef", 16:09:33 INFO - "slavebuilddir": "test" 16:09:33 INFO - } 16:09:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:09:33 INFO - Running post-action listener: _resource_record_post_action 16:09:33 INFO - [mozharness: 2016-11-23 00:09:33.685361Z] Finished read-buildbot-config step (success) 16:09:33 INFO - [mozharness: 2016-11-23 00:09:33.685616Z] Running download-and-extract step. 16:09:33 INFO - Running pre-action listener: _resource_record_pre_action 16:09:33 INFO - Running main action method: download_and_extract 16:09:33 INFO - mkdir: /builds/slave/test/build/tests 16:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:33 INFO - trying https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:09:33 INFO - Downloading https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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 16:09:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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 16:09:34 INFO - Downloaded 1199 bytes. 16:09:34 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:09:34 INFO - Using the following test package requirements: 16:09:34 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:09:34 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:09:34 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:09:34 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'jsshell-mac.zip'], 16:09:34 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:09:34 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:09:34 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:09:34 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:09:34 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:09:34 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:34 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:09:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:09:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:09:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:09:34 INFO - Fetch https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:09:35 INFO - Http code: 200 16:09:35 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 16:09:35 INFO - x-amz-cf-id: fcveGu1SEjmQleNm7MV1PMjj9HUbcrPL1h2zq_4QBcUH61zQ9SKGjg== 16:09:35 INFO - x-amz-version-id: fwVBu_3UojICNabUEKItiyKM9yT24zjr 16:09:35 INFO - Expected file size: 16277479 16:09:35 INFO - Obtained file size: 16277479 16:09:42 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 16:09:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:09:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:09:42 INFO - Fetch https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:09:42 INFO - Http code: 200 16:09:42 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 16:09:42 INFO - x-amz-cf-id: 3w__EcEevBQ0HZNPl_5WZYrGqzBlRAF6xJzpCoxo7nswsTFw4gfjAA== 16:09:42 INFO - x-amz-version-id: fwVBu_3UojICNabUEKItiyKM9yT24zjr 16:09:43 INFO - Expected file size: 16277479 16:09:43 INFO - Obtained file size: 16277479 16:09:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:09:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:09:49 INFO - Fetch https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:09:49 INFO - Http code: 200 16:09:49 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 16:09:49 INFO - x-amz-cf-id: IxF4tULijPvrTdKWtdJ80bR6F1W3ECt65VJgtI4kAQKIqh0I8sbndA== 16:09:49 INFO - x-amz-version-id: IW5Lw.QPK5oKmSEtXjpOZ9G_o4ymIIyN 16:09:50 INFO - Expected file size: 14409038 16:09:50 INFO - Obtained file size: 14409038 16:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:50 INFO - trying https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:09:50 INFO - Downloading https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:09:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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 16:10:21 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg: The read operation timed out 16:10:21 INFO - retry: attempt #1 caught exception: The read operation timed out 16:10:21 INFO - retry: Failed, sleeping 30 seconds before retrying 16:10:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #2 16:10:54 INFO - Downloaded 109673273 bytes. 16:10:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:10:54 INFO - mkdir: /builds/slave/test/properties 16:10:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:54 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:54 INFO - Contents: 16:10:54 INFO - build_url:https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:10:55 INFO - Running post-action listener: _resource_record_post_action 16:10:55 INFO - Running post-action listener: set_extra_try_arguments 16:10:55 INFO - [mozharness: 2016-11-23 00:10:55.070198Z] Finished download-and-extract step (success) 16:10:55 INFO - [mozharness: 2016-11-23 00:10:55.070352Z] Running populate-webroot step. 16:10:55 INFO - Running pre-action listener: _resource_record_pre_action 16:10:55 INFO - Running main action method: populate_webroot 16:10:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:55 INFO - '--tppagecycles', 16:10:55 INFO - '7'], 16:10:55 INFO - 'tests': ['tsvgm']}}, 16:10:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:10:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:10:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['damp', 'tps']}, 16:10:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'tests': ['damp', 'tps']}, 16:10:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['dromaeo_dom']}, 16:10:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['basic_compositor_video']}, 16:10:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:10:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['a11yr', 16:10:55 INFO - 'ts_paint', 16:10:55 INFO - 'tpaint', 16:10:55 INFO - 'sessionrestore', 16:10:55 INFO - 'sessionrestore_no_auto_restore', 16:10:55 INFO - 'tabpaint']}, 16:10:55 INFO - 'other-e10s': {'tests': ['a11yr', 16:10:55 INFO - 'ts_paint', 16:10:55 INFO - 'tpaint', 16:10:55 INFO - 'sessionrestore', 16:10:55 INFO - 'sessionrestore_no_auto_restore', 16:10:55 INFO - 'tabpaint']}, 16:10:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:10:55 INFO - 'ts_paint', 16:10:55 INFO - 'tpaint', 16:10:55 INFO - 'sessionrestore', 16:10:55 INFO - 'sessionrestore_no_auto_restore', 16:10:55 INFO - 'tabpaint']}, 16:10:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:10:55 INFO - 'ts_paint', 16:10:55 INFO - 'tpaint', 16:10:55 INFO - 'sessionrestore', 16:10:55 INFO - 'sessionrestore_no_auto_restore', 16:10:55 INFO - 'tabpaint']}, 16:10:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['a11yr', 16:10:55 INFO - 'ts_paint', 16:10:55 INFO - 'tpaint', 16:10:55 INFO - 'sessionrestore', 16:10:55 INFO - 'sessionrestore_no_auto_restore', 16:10:55 INFO - 'tabpaint']}, 16:10:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['a11yr', 16:10:55 INFO - 'ts_paint', 16:10:55 INFO - 'tpaint', 16:10:55 INFO - 'sessionrestore', 16:10:55 INFO - 'sessionrestore_no_auto_restore', 16:10:55 INFO - 'tabpaint']}, 16:10:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['tsvgx', 16:10:55 INFO - 'tsvgr_opacity', 16:10:55 INFO - 'tart', 16:10:55 INFO - 'tscrollx', 16:10:55 INFO - 'cart']}, 16:10:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:10:55 INFO - 'tsvgr_opacity', 16:10:55 INFO - 'tart', 16:10:55 INFO - 'tscrollx', 16:10:55 INFO - 'cart']}, 16:10:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'talos_options': ['--disable-e10s'], 16:10:55 INFO - 'tests': ['tp5o']}, 16:10:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'tests': ['tp5o']}, 16:10:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'talos_options': ['--disable-e10s', 16:10:55 INFO - '--xperf_path', 16:10:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:55 INFO - 'tests': ['tp5n']}, 16:10:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:55 INFO - 'talos_options': ['--xperf_path', 16:10:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:55 INFO - 'tests': ['tp5n']}}, 16:10:55 INFO - 'talos.zip': {'path': '', 16:10:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:55 INFO - Running post-action listener: _resource_record_post_action 16:10:55 INFO - [mozharness: 2016-11-23 00:10:55.089174Z] Finished populate-webroot step (success) 16:10:55 INFO - [mozharness: 2016-11-23 00:10:55.089306Z] Running create-virtualenv step. 16:10:55 INFO - Running pre-action listener: _resource_record_pre_action 16:10:55 INFO - Running main action method: create_virtualenv 16:10:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10:55 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 16:10:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:55 INFO - Using real prefix '/tools/python27' 16:10:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:55 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:58 INFO - Installing pip.................done. 16:10:58 INFO - Return code: 0 16:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:10:59 INFO - Reading from file tmpfile_stdout 16:10:59 INFO - Output received: 16:10:59 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) 16:10:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10:59 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')]} 16:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:59 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')]} 16:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:59 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:10:59 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 16:10:59 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 16:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:59 INFO - 'HOME': '/Users/cltbld', 16:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:59 INFO - 'LOGNAME': 'cltbld', 16:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:59 INFO - 'MOZ_NO_REMOTE': '1', 16:10:59 INFO - 'NO_EM_RESTART': '1', 16:10:59 INFO - 'PAGER': '/bin/cat', 16:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:59 INFO - 'PWD': '/builds/slave/test', 16:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:59 INFO - 'SHELL': '/bin/bash', 16:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:10:59 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:59 INFO - 'USER': 'cltbld', 16:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:59 INFO - 'XPC_FLAGS': '0x0', 16:10:59 INFO - 'XPC_SERVICE_NAME': '0', 16:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:59 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 16:10:59 INFO - Cleaning up... 16:10:59 INFO - Return code: 0 16:10:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:10:59 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')]} 16:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:59 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')]} 16:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:59 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:10:59 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 16:10:59 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 16:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:59 INFO - 'HOME': '/Users/cltbld', 16:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:59 INFO - 'LOGNAME': 'cltbld', 16:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:59 INFO - 'MOZ_NO_REMOTE': '1', 16:10:59 INFO - 'NO_EM_RESTART': '1', 16:10:59 INFO - 'PAGER': '/bin/cat', 16:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:59 INFO - 'PWD': '/builds/slave/test', 16:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:59 INFO - 'SHELL': '/bin/bash', 16:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:10:59 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:59 INFO - 'USER': 'cltbld', 16:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:59 INFO - 'XPC_FLAGS': '0x0', 16:10:59 INFO - 'XPC_SERVICE_NAME': '0', 16:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:59 INFO - Downloading/unpacking psutil>=3.1.1 16:10:59 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 16:10:59 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 16:10:59 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 16:10:59 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 16:10:59 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 16:10:59 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 16:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:11:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:03 INFO - Installing collected packages: psutil 16:11:03 INFO - Running setup.py install for psutil 16:11:03 INFO - building 'psutil._psutil_osx' extension 16:11:03 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 16:11:03 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 16:11:04 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 16:11:04 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 16:11:04 INFO - building 'psutil._psutil_posix' extension 16:11:04 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 16:11:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:11:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:11:04 INFO - ^ 16:11:04 INFO - 1 warning generated. 16:11:04 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 16:11:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:04 INFO - Successfully installed psutil 16:11:04 INFO - Cleaning up... 16:11:04 INFO - Return code: 0 16:11:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:04 INFO - 'HOME': '/Users/cltbld', 16:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:04 INFO - 'LOGNAME': 'cltbld', 16:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:04 INFO - 'MOZ_NO_REMOTE': '1', 16:11:04 INFO - 'NO_EM_RESTART': '1', 16:11:04 INFO - 'PAGER': '/bin/cat', 16:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:04 INFO - 'PWD': '/builds/slave/test', 16:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:04 INFO - 'SHELL': '/bin/bash', 16:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:04 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:04 INFO - 'USER': 'cltbld', 16:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:04 INFO - 'XPC_FLAGS': '0x0', 16:11:04 INFO - 'XPC_SERVICE_NAME': '0', 16:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:11:04 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 16:11:04 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 16:11:04 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 16:11:04 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 16:11:04 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 16:11:04 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 16:11:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:11:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:11:07 INFO - Installing collected packages: mozsystemmonitor 16:11:07 INFO - Running setup.py install for mozsystemmonitor 16:11:07 INFO - Successfully installed mozsystemmonitor 16:11:07 INFO - Cleaning up... 16:11:07 INFO - Return code: 0 16:11:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:07 INFO - 'HOME': '/Users/cltbld', 16:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:07 INFO - 'LOGNAME': 'cltbld', 16:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:07 INFO - 'MOZ_NO_REMOTE': '1', 16:11:07 INFO - 'NO_EM_RESTART': '1', 16:11:07 INFO - 'PAGER': '/bin/cat', 16:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:07 INFO - 'PWD': '/builds/slave/test', 16:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:07 INFO - 'SHELL': '/bin/bash', 16:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:07 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:07 INFO - 'USER': 'cltbld', 16:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:07 INFO - 'XPC_FLAGS': '0x0', 16:11:07 INFO - 'XPC_SERVICE_NAME': '0', 16:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:07 INFO - Downloading/unpacking jsonschema==2.5.1 16:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:10 INFO - warning: no files found matching '*.txt' 16:11:10 INFO - no previously-included directories found matching 'build' 16:11:10 INFO - no previously-included directories found matching 'dist' 16:11:10 INFO - no previously-included directories found matching '.git*' 16:11:10 INFO - Installing collected packages: jsonschema, functools32 16:11:11 INFO - Running setup.py install for functools32 16:11:11 INFO - warning: no files found matching '*.txt' 16:11:11 INFO - no previously-included directories found matching 'build' 16:11:11 INFO - no previously-included directories found matching 'dist' 16:11:11 INFO - no previously-included directories found matching '.git*' 16:11:11 INFO - Successfully installed jsonschema functools32 16:11:11 INFO - Cleaning up... 16:11:11 INFO - Return code: 0 16:11:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:11 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:11 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 16:11:11 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 16:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:11 INFO - 'HOME': '/Users/cltbld', 16:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:11 INFO - 'LOGNAME': 'cltbld', 16:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:11 INFO - 'MOZ_NO_REMOTE': '1', 16:11:11 INFO - 'NO_EM_RESTART': '1', 16:11:11 INFO - 'PAGER': '/bin/cat', 16:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:11 INFO - 'PWD': '/builds/slave/test', 16:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:11 INFO - 'SHELL': '/bin/bash', 16:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:11 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:11 INFO - 'USER': 'cltbld', 16:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:11 INFO - 'XPC_FLAGS': '0x0', 16:11:11 INFO - 'XPC_SERVICE_NAME': '0', 16:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:11 INFO - Cleaning up... 16:11:11 INFO - Return code: 0 16:11:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:11 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:11 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 16:11:11 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 16:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:11 INFO - 'HOME': '/Users/cltbld', 16:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:11 INFO - 'LOGNAME': 'cltbld', 16:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:11 INFO - 'MOZ_NO_REMOTE': '1', 16:11:11 INFO - 'NO_EM_RESTART': '1', 16:11:11 INFO - 'PAGER': '/bin/cat', 16:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:11 INFO - 'PWD': '/builds/slave/test', 16:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:11 INFO - 'SHELL': '/bin/bash', 16:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:11 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:11 INFO - 'USER': 'cltbld', 16:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:11 INFO - 'XPC_FLAGS': '0x0', 16:11:11 INFO - 'XPC_SERVICE_NAME': '0', 16:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:14 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11:14 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 16:11:14 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 16:11:14 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 16:11:14 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 16:11:14 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 16:11:14 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 16:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:15 INFO - Downloading docopt-0.6.1.tar.gz 16:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:15 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:15 INFO - Running setup.py install for blobuploader 16:11:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:15 INFO - Running setup.py install for requests 16:11:16 INFO - Running setup.py install for docopt 16:11:16 INFO - Successfully installed blobuploader requests docopt 16:11:16 INFO - Cleaning up... 16:11:16 INFO - Return code: 0 16:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:16 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:16 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 16:11:16 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 16:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:16 INFO - 'HOME': '/Users/cltbld', 16:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:16 INFO - 'LOGNAME': 'cltbld', 16:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:16 INFO - 'MOZ_NO_REMOTE': '1', 16:11:16 INFO - 'NO_EM_RESTART': '1', 16:11:16 INFO - 'PAGER': '/bin/cat', 16:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:16 INFO - 'PWD': '/builds/slave/test', 16:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:16 INFO - 'SHELL': '/bin/bash', 16:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:16 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:16 INFO - 'USER': 'cltbld', 16:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:16 INFO - 'XPC_FLAGS': '0x0', 16:11:16 INFO - 'XPC_SERVICE_NAME': '0', 16:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:16 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-j3RRXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:16 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-WrJr6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:16 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-xF4Igd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zR71Mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-WudIqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-lY7Nbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-pYyKmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-8zsWOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-8J6ldZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-KDYWgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-PI5HYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tZKuPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-xE4Tsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JS8ZxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Bd2Ggl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-nJ8gd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-9bGsC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:11:18 INFO - Running setup.py install for manifestparser 16:11:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:11:18 INFO - Running setup.py install for mozcrash 16:11:18 INFO - Running setup.py install for mozdebug 16:11:18 INFO - Running setup.py install for mozdevice 16:11:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Running setup.py install for mozfile 16:11:19 INFO - Running setup.py install for mozhttpd 16:11:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Running setup.py install for mozinfo 16:11:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Running setup.py install for mozInstall 16:11:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:11:19 INFO - Running setup.py install for mozleak 16:11:19 INFO - Running setup.py install for mozlog 16:11:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:11:20 INFO - Running setup.py install for moznetwork 16:11:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:11:20 INFO - Running setup.py install for mozprocess 16:11:20 INFO - Running setup.py install for mozprofile 16:11:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:11:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:11:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:11:20 INFO - Running setup.py install for mozrunner 16:11:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:11:20 INFO - Running setup.py install for mozscreenshot 16:11:20 INFO - Running setup.py install for moztest 16:11:20 INFO - Running setup.py install for mozversion 16:11:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:11:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:11:21 INFO - Cleaning up... 16:11:21 INFO - Return code: 0 16:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:21 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:21 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 16:11:21 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 16:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:21 INFO - 'HOME': '/Users/cltbld', 16:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:21 INFO - 'LOGNAME': 'cltbld', 16:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:21 INFO - 'MOZ_NO_REMOTE': '1', 16:11:21 INFO - 'NO_EM_RESTART': '1', 16:11:21 INFO - 'PAGER': '/bin/cat', 16:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:21 INFO - 'PWD': '/builds/slave/test', 16:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:21 INFO - 'SHELL': '/bin/bash', 16:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:21 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:21 INFO - 'USER': 'cltbld', 16:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:21 INFO - 'XPC_FLAGS': '0x0', 16:11:21 INFO - 'XPC_SERVICE_NAME': '0', 16:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:21 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Ce9Hee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:21 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)) 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:21 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-i2xyzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:21 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)) 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:21 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JftLBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:21 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)) 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:21 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-0N1its-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:21 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)) 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:21 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Kmgnsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:21 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)) 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:21 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-LfUPd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:21 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)) 16:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-3jnshI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-yc6uw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JzuoxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-XOGTjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zXMBy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-kV6Yx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ec4Swr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-XTJyJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Svaz1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:22 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)) 16:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:22 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ocfdWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:23 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-J5ajTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:23 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)) 16:11:23 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)) 16:11:23 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)) 16:11:23 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)) 16:11:23 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)) 16:11:23 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)) 16:11:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:11:23 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 16:11:23 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 16:11:23 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 16:11:23 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 16:11:23 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 16:11:23 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 16:11:25 INFO - Downloading blessings-1.6.tar.gz 16:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:11:25 INFO - Installing collected packages: blessings 16:11:25 INFO - Running setup.py install for blessings 16:11:26 INFO - Successfully installed blessings 16:11:26 INFO - Cleaning up... 16:11:26 INFO - Return code: 0 16:11:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:11:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:26 INFO - Reading from file tmpfile_stdout 16:11:26 INFO - Current package versions: 16:11:26 INFO - blessings == 1.6 16:11:26 INFO - blobuploader == 1.2.4 16:11:26 INFO - docopt == 0.6.1 16:11:26 INFO - functools32 == 3.2.3-2 16:11:26 INFO - jsonschema == 2.5.1 16:11:26 INFO - manifestparser == 1.1 16:11:26 INFO - mozInstall == 1.12 16:11:26 INFO - mozcrash == 1.0 16:11:26 INFO - mozdebug == 0.1 16:11:26 INFO - mozdevice == 0.48 16:11:26 INFO - mozfile == 1.2 16:11:26 INFO - mozhttpd == 0.7 16:11:26 INFO - mozinfo == 0.9 16:11:26 INFO - mozleak == 0.1 16:11:26 INFO - mozlog == 3.4 16:11:26 INFO - moznetwork == 0.27 16:11:26 INFO - mozprocess == 0.23 16:11:26 INFO - mozprofile == 0.28 16:11:26 INFO - mozrunner == 6.13 16:11:26 INFO - mozscreenshot == 0.1 16:11:26 INFO - mozsystemmonitor == 0.3 16:11:26 INFO - moztest == 0.7 16:11:26 INFO - mozversion == 1.4 16:11:26 INFO - psutil == 3.1.1 16:11:26 INFO - requests == 1.2.3 16:11:26 INFO - wsgiref == 0.1.2 16:11:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:26 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:26 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 16:11:26 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 16:11:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:26 INFO - 'HOME': '/Users/cltbld', 16:11:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:26 INFO - 'LOGNAME': 'cltbld', 16:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:26 INFO - 'MOZ_NO_REMOTE': '1', 16:11:26 INFO - 'NO_EM_RESTART': '1', 16:11:26 INFO - 'PAGER': '/bin/cat', 16:11:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:26 INFO - 'PWD': '/builds/slave/test', 16:11:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:26 INFO - 'SHELL': '/bin/bash', 16:11:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:26 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:26 INFO - 'USER': 'cltbld', 16:11:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:26 INFO - 'XPC_FLAGS': '0x0', 16:11:26 INFO - 'XPC_SERVICE_NAME': '0', 16:11:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 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)) 16:11:26 INFO - Cleaning up... 16:11:26 INFO - Return code: 0 16:11:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:26 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 0x109091490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109007570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb42c7a370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, '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.FERxgMM9YC/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.RMSkRfohln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 16:11:26 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 16:11:26 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 16:11:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:26 INFO - 'HOME': '/Users/cltbld', 16:11:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:26 INFO - 'LOGNAME': 'cltbld', 16:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:26 INFO - 'MOZ_NO_REMOTE': '1', 16:11:26 INFO - 'NO_EM_RESTART': '1', 16:11:26 INFO - 'PAGER': '/bin/cat', 16:11:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:26 INFO - 'PWD': '/builds/slave/test', 16:11:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:26 INFO - 'SHELL': '/bin/bash', 16:11:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:26 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:26 INFO - 'USER': 'cltbld', 16:11:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:26 INFO - 'XPC_FLAGS': '0x0', 16:11:26 INFO - 'XPC_SERVICE_NAME': '0', 16:11:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:11:26 INFO - Cleaning up... 16:11:26 INFO - Return code: 0 16:11:26 INFO - Running post-action listener: _resource_record_post_action 16:11:26 INFO - Running post-action listener: _start_resource_monitoring 16:11:27 INFO - Starting resource monitoring. 16:11:27 INFO - [mozharness: 2016-11-23 00:11:27.025663Z] Finished create-virtualenv step (success) 16:11:27 INFO - [mozharness: 2016-11-23 00:11:27.026671Z] Running install step. 16:11:27 INFO - Running pre-action listener: _resource_record_pre_action 16:11:27 INFO - Running main action method: install 16:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:27 INFO - Reading from file tmpfile_stdout 16:11:27 INFO - Detecting whether we're running mozinstall >=1.0... 16:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:11:27 INFO - Reading from file tmpfile_stdout 16:11:27 INFO - Output received: 16:11:27 INFO - Usage: mozinstall [options] installer 16:11:27 INFO - Options: 16:11:27 INFO - -h, --help show this help message and exit 16:11:27 INFO - -d DEST, --destination=DEST 16:11:27 INFO - Directory to install application into. [default: 16:11:27 INFO - "/builds/slave/test"] 16:11:27 INFO - --app=APP Application being installed. [default: firefox] 16:11:27 INFO - mkdir: /builds/slave/test/build/application 16:11:27 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'] 16:11:27 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 16:11:58 INFO - Reading from file tmpfile_stdout 16:11:58 INFO - Output received: 16:11:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:11:58 INFO - Running post-action listener: _resource_record_post_action 16:11:58 INFO - [mozharness: 2016-11-23 00:11:58.290414Z] Finished install step (success) 16:11:58 INFO - [mozharness: 2016-11-23 00:11:58.290558Z] Running run-tests step. 16:11:58 INFO - Running pre-action listener: _resource_record_pre_action 16:11:58 INFO - Running main action method: run_tests 16:11:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:58 INFO - 2016-11-22 16:11:58.305 screenresolution[1626:7440] starting screenresolution argv=screenresolution get 16:11:58 INFO - 2016-11-22 16:11:58.307 screenresolution[1626:7440] Display 0: 1600x1200x32@60 16:11:58 INFO - 2016-11-22 16:11:58.313 screenresolution[1627:7442] starting screenresolution argv=screenresolution list 16:11:58 INFO - Available Modes on Display 0 16:11:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:58 INFO - Intel Iris: 16:11:58 INFO - Chipset Model: Intel Iris 16:11:58 INFO - Type: GPU 16:11:58 INFO - Bus: Built-In 16:11:58 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:58 INFO - Vendor: Intel (0x8086) 16:11:58 INFO - Device ID: 0x0a2e 16:11:58 INFO - Revision ID: 0x0009 16:11:58 INFO - Displays: 16:11:58 INFO - Display: 16:11:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:58 INFO - Display Serial Number: 1600x1200 60 16:11:58 INFO - Main Display: Yes 16:11:58 INFO - Mirror: Off 16:11:58 INFO - Online: Yes 16:11:58 INFO - Rotation: Supported 16:11:58 INFO - Return code: 0 16:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:58 INFO - Python 2.7.3 16:11:58 INFO - Return code: 0 16:11:58 INFO - grabbing minidump binary from tooltool 16:11: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')]} 16:11:58 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 0x108ea5d30>, '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 0x108d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e9db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11:58 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 16:11:58 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 16:11:58 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 16:11:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:58 INFO - Return code: 0 16:11:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:11:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:11:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FERxgMM9YC/Render', 16:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:58 INFO - 'HOME': '/Users/cltbld', 16:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:58 INFO - 'LOGNAME': 'cltbld', 16:11:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:58 INFO - 'MOZ_NO_REMOTE': '1', 16:11:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:58 INFO - 'NO_EM_RESTART': '1', 16:11:58 INFO - 'PAGER': '/bin/cat', 16:11:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:58 INFO - 'PWD': '/builds/slave/test', 16:11:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:58 INFO - 'SHELL': '/bin/bash', 16:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RMSkRfohln/Listeners', 16:11:58 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 16:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:58 INFO - 'USER': 'cltbld', 16:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:58 INFO - 'XPC_FLAGS': '0x0', 16:11:58 INFO - 'XPC_SERVICE_NAME': '0', 16:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:11:58 INFO - mozversion application_buildid: 20161122145719 16:11:58 INFO - mozversion application_changeset: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee 16:11:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:11:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:58 INFO - mozversion application_name: Firefox 16:11:58 INFO - mozversion application_remotingname: firefox-dev 16:11:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:11:58 INFO - mozversion application_vendor: Mozilla 16:11:58 INFO - mozversion application_version: 52.0a2 16:11:58 INFO - mozversion platform_buildid: 20161122145719 16:11:58 INFO - mozversion platform_changeset: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee 16:11:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:11:58 INFO - mozversion platform_version: 52.0a2 16:11:58 INFO - using testdate: 1479859918 16:11:58 INFO - actual date: 1479859918 16:11:58 INFO - starting webserver on 'localhost:49238' 16:11:58 INFO - SUITE-START | Running 2 tests 16:11:58 INFO - TEST-START | dromaeo_css 16:11:58 INFO - operating with platform_type : mac_ 16:11:58 INFO - Initialising browser for dromaeo_css test... 16:11:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpp1cApO/profile http://localhost:49238/getInfo.html) 16:12:00 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 16:12:02 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 16:12:02 INFO - PROCESS | 1637 | colorDepth:24 16:12:02 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 16:12:02 INFO - PROCESS | 1637 | __metrics 16:12:02 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 16:12:03 INFO - TEST-INFO | 1637: exit 0 16:12:03 INFO - Browser initialized. 16:12:03 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:03 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpp1cApO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:15 INFO - PROCESS | 1640 | RSS: Main: 230019072 16:12:15 INFO - PROCESS | 1640 | 16:13:05 INFO - PROCESS | 1640 | 1479859985010 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:15:19 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 16:15:21 INFO - PROCESS | 1640 | RSS: Main: 232722432 16:15:21 INFO - PROCESS | 1640 | 16:18:23 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 16:18:24 INFO - PROCESS | 1640 | RSS: Main: 243384320 16:18:24 INFO - PROCESS | 1640 | 16:21:26 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 16:21:26 INFO - PROCESS | 1640 | RSS: Main: 246628352 16:21:26 INFO - PROCESS | 1640 | 16:24:28 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 16:24:29 INFO - PROCESS | 1640 | RSS: Main: 245776384 16:24:29 INFO - PROCESS | 1640 | 16:27:32 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 16:27:32 INFO - PROCESS | 1640 | RSS: Main: 249262080 16:27:32 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 16:30:35 INFO - PROCESS | 1640 | RSS: Main: 251465728 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | __start_tp_report 16:30:35 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 16:30:35 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 16:30:35 INFO - PROCESS | 1640 | |i|pagename|runs| 16:30:35 INFO - PROCESS | 1640 | |0;dojo.html;3156.084735426726;3363.108776174313;3411.761176717629;3413.2832105257926;3442.879499217528;5788.710564471776;5842.883142337156;5880.647161170333;5882.941170588293;5888.617239879405;5809.854753631162;5817.127430885369;5845.298564172294;5850.35646078932;5862.384449632791;5224.268602395678;5284.762185701653;5302.125149350352;5320.015797077553;5327.014502317064;5029.798808047673;5339.893202135974;5347.652402593839;5365.597580181482;5382.273393091941;5205.973970130144;5211.557017653504;5278.577713782888;5333.386660534049;5339.519443250107;5289.809792796609;5328.147496400596;5329.973350133263;5330.893382132355;5348.51863332298;5143.871403214949;5278.683279003272;5286.709230992294;5314.601404894648;5344.064788662007;5853.970730146365;5895.263092113486;5899.439553242478;5940.732667029994;5952.494038006731;6067.666278354689;6076.936536106208;6198.163247961557;6209.06719339988;6276.121343011982;6274.717637706269;6288.622682639057;6300.684965751694;6306.306306306303;6307.684615769193;5877.735501902424;5955.40445955401;5985.221921150266;6058.909116363258;6063.969680151571;6331.810045698544;6357.936420635827;6359.650219237987;6368.649724265118;6390.840228994312;6256.0303153011355;6260.655663938436;6290.182276304051;6306.779262725782;6309.116723658692;5617.747201375948;6008.24896887889;6219.129321894939;6233.252009758858;6315.684215789192;6218.875622487525;6261.78083767066;6282.4974002079725;6303.023031430135;6327.96836015817;6255.562110652301;6259.1550140730415;6281.309056003836;6285.371462853678;6302.684865756693;4928.67963582366;4954.77703503336;4982.676126051868;5082.818935170906;5121.07820592297;4433.733975961452;4449.866504004885;4463.464384273908;4518.661100417482;4564.817407303737;5107.744612769421;5492.011437941209;5952.761889524457;6059.303180134341;6061.363556826558;6205.6586887721605;6308.3060863305;6308.432241098231;6312.621242725451;6327.145835312266;5815.244018277682;5978.252718410199;5983.850403739942;5995.100734889801;5997.2803263607775;5498.752556134984;5509.779608815603;5509.889802203934;5516.834494965157;5524.226608274845;6267.310595834455;6342.5560210785725;6361.522885783677;6390.840228994126;6488.837779055561;6296.748130074746;6296.905546416716;6308.053791931247;6310.274318453253;6324.525660575383;6473.320301368289;6508.9349106508325;6513.706883190173;6517.087607735016;6524.641144737085;6149.139120523041;6177.382261773787;6220.440160385587;6249.156363890998;6332.525060620564;6143.907841382294;6314.084457753692;6322.810315690536;6325.019621958604;6331.113644089739;6252.749890004349;6265.4361107500545;6306.148669929501;6319.304876463493;6328.841778955379;5610.653443173527;5764.596478246483;6094.085887116968;6287.308396076336;6334.651594162366;5144.202648589475;5917.970410148094;6144.433169541739;6199.194104766351;6203.348648391854;6169.167162433014;6255.437010669061;6279.686015699105;6304.684765761602;6304.779332723333;5330.653507521999;5500.28496295495;6097.17688112117;6138.202033735586;6153.323134455296 16:30:35 INFO - PROCESS | 1640 | |1;ext.html;457.56759861930476;464.57723471640816;464.85357112509547;479.5851588376056;549.0145189385054;39035.02412439691;40730.796346018265;40955;40980.59019409813;41287.79356103219;41459;41832.74501764952;42043.159136817245;42244;43137.568624313746;9337.953310233457;9457.054294570522;9498.81002379952;9502.239820814353;9529;9374.296927730413;9412.482313472756;9455.149036586707;9493.667721629745;9628.41131213349;9421.010793866648;9477.47873866937;9477.715668529921;9501.572429240701;9528.571214295338;9401.10689484498;9477.620895164186;9489.667861624845;9504;9522.761930951712;8058.395620328499;9115.86051743532;9291.117343852391;9321;9406.247500199968;34073.65926340754;39329.803350983064;39383.60616393828;39394.80302598498;39657.60342396568;3203.9839800800846;3224.580804495424;3231.6929891660257;3378.2567835076243;3651.653092956165;28962.27594310138;29233.269168270752;29998.550021749692;30004.249893752614;30505.847470762503;2939.3533422646983;3024.138120635608;3079.538069289602;3083.1058992892035;3084.1364417963005;505827;508135;509382;511968;512106;57371.71314143486;57495.98764043238;57610;57900.710496447246;58014.129788053215;56621.71689141528;58273.417265827644;58981.41018589759;59247.40752592419;59252.11121833176;15465.304061317267;15624.375024998873;15656.530304090895;15712.528624141294;15779;12281.263124212575;12326;12347.320897350732;12355.93822030884;12378.257304561756;14582.61465160808;14927.029743066665;14982.775258371134;15022.549323520312;15069.698606027818;3155.7790954632956;5263.6578622389425;6214.2542894852;6234.407731265523;6296.464800491918;852.424613385962;885.3846576629181;943.2783920300975;953.6566835939057;1073.1468482556386;28352.574711379344;28749.562521873824;29302.26744331409;29572.852135739184;29751.553726694117;1023.8361862102176;1023.8941942701864;1045.7960697664034;1093.748437859297;1105.5301496863801;13590.728185436235;13902.23537705436;14572.41710331596;14854.777178342334;15084.019538729948;708795;709240;709659;713622;715520;56322.7183864078;56445.71777141088;57080.714596426755;57081.714591426775;57083.85832283331;1260.4517035089582;1313.5205649937895;1358.110437663332;1360.3538319298452;1364.4814970311418;28264;28428.85785571059;29139.980100696375;29395.265118372212;29591.556126657688;47078.52921470878;47700.80747981328;47830.28254576115;47944.04111917812;48020;635689;642132;642244;642321;643152;21346;23008.19471318496;25190.370240744127;25562.616560751976;25984.480310394065;43980.78009609931;43988.34017489676;44521.77739111284;44837.32744008777;44988.77505612451;36595.45106823453;37162.814185928895;38134;38508.614913850506;39832.80083599564;28400.43199136047;31126.844365778026;36165.27669446649;37893.81053094717;38622.6137738619;34326.28368581611;35054.64945350616;37269.627303726615;37638.059048524;37658.05854853541;32459.837700811346;33801.49297760585;33899.66100339064;34390.65609343975;34550.96347109591;20736.48158796042;21530.92345382756;24121.638175427033;25688.357791055372;25799.96800128049 16:30:35 INFO - PROCESS | 1640 | |2;jquery.html;337.76525314906144;362.9927401451973;377.68085967357575;377.9527559055118;394.1840390391888;18192.454226373215;19295.228190872367;19640.803591964075;20195.495112622193;20425;19385.4184374469;19712;19768.703469447933;20115;20293.797062029375;5487.341519017724;5530.253415788878;5553.500184983351;5851.444112809277;5852.385499522553;5603.971980140093;5809.3900140485275;5811.970940145315;5813.244278243822;5813.273340832396;5512.33851937768;5623.80316688916;5677.630953987978;5774.682392468412;5780.0751879699255;5619.915701264485;5630.746616402252;5700.686462244575;5715.514181294574;5719.227904232917;4805.486271824364;4934.851954441372;4968.105740966625;4972.502749725035;5005.524475174852;22497.437564060867;23607.05571777127;23785.762142378528;24530.386740331454;24631.87684061586;2061.309461330456;2200.19692812124;2302.5394921015695;2553.6680231569962;2584.2118153963033;9459.479728614924;10003.34978226413;10040.548175332127;13206;13602;1828.963420731578;1908.4847091285415;1908.9331873384504;1915.79884112169;1973.4277059652684;122110;122646;129333;129943;131526;9600.039996000483;10773.407462589475;11517.48171332292;11571.710707232218;12008.579699710468;14136.293185340692;14222.359993800304;14424.783628245585;14426.278686065654;14438.422463101568;8111.75664730059;8370.702541106139;8413.663453461793;8480.703175388955;8524.74425767228;8218.260356567938;8235.95882020586;8267.462614930011;8330.416870818985;8413.537255450887;4366.12677464502;4522.25382811839;4542.136993971135;4605.700629459075;4607.35497030416;1656.8011838579448;1911.1590900004232;1953.3456292142007;1968.4586738646988;2001.1095062697252;827.8468483330482;918.1598837064012;950.296780382512;961.956711947964;986.3342243985281;19642.017899104987;20749.89625051865;20771.68842467364;20811.375658730263;20830.895845520674;916.4776077635684;934.5841100710119;948.4830767231875;973.8539219117189;981.4601968917067;10550.15598752098;10911.890881091247;10968.725781855517;11006.779864402668;11020.338779673246;515878;571169;605217.9739101277;605293;625247;80730.59634701788;108458.91541084646;109301;123141;125295;936.2322895225985;974.8196583632051;981.0875885426622;985.531872360623;989.4656885281867;17842;18674.90662546733;18893.622127557097;18977.90511047436;19050;51460;51464.22803657873;52771.9445611078;52894.41316760503;53503.73248133734;51814.481855180966;72648;72936.27063729295;75300.24699752932;75497.6225118871;7765.495242809199;7784.88322675149;7793.532388056735;7957.721479748181;8219.506829590244;8915.50964696922;11997.82003269969;12100;12259.203151795105;12565.308908009782;9285.907140928504;9559.474228917197;10516.526756296138;10634.52144653508;10773.299735517166;10193.388396696222;10201.642942496977;10264.538095785558;10456.68629941103;10587.258891877802;10452;10650.520726567169;10823.025927666547;10966.342019478856;10970.835437468604;7773.144954055162;7832.020997375328;7977.242161994601;7988.920110798817;8129.268365847102;7964.960175199087;9931.553080111556;10525.36847789135;10591.682249532527;10746 16:30:35 INFO - PROCESS | 1640 | |3;mootools.html;1485.9554213373597;1522.9771553426697;1706.2663054886402;1749.7987731410876;1821.3261093395445;7736.110347310061;7973.840523189533;8354.289885359743;8756.649734010647;8822.206001459866;8253;8534.48793072416;8563.443376180543;8678.132186781319;8699.52152631605;2343.4492894169857;2357.610994185957;2360.33910505058;2362.4684445999683;2365.29041287613;2367.7277113132036;2391.473875747333;2400.9039638414442;2419.383057320381;2419.9516009679796;2338.8830558472096;2358.705161854768;2359.28041947206;2382.9761702383016;2384.582698027847;2285;2294.6558016297604;2315.120254303371;2316.108298305148;2338.485533182714;2355.211004313557;2376.2039716694926;2383.2373640435067;2385.5109702510945;2390.521895620883;8669.479831210148;8703.869441958375;8705.47767133974;8706.869396959051;8727.956360218222;5169.379674439054;5306.548943339821;5332.893342133174;5335.893282134336;5364.678119312854;3372.629010808809;3372.865085396581;3394;3458.688718015391;3495.143689796016;982.4007355513131;1004.5881188712593;1031.1080915008538;1036.2228328753436;1038.5638032026568;11193.216474846764;11237.269577477438;11311.886881131166;11320.207585468937;11341.036011938995;4573;4690.6951048182445;4765.309030190603;4795.592374648124;4833.758312084382;9707.514624268899;9781.315307928518;9786;9802.411855288705;9803.068708472683;5209.479052094836;5216.37403511581;5294.97352513235;5309.41596424393;5317.1226747586215;4321.611055005002;4335.653147748172;4815.253635686474;4844.467108618049;4865.464798872121;3397.286569820316;3652.945205821915;3901.9804900975882;3902.2195560887367;4000.8599699010397;1142.691618097274;1265.089135822225;1284.653143651209;1301.1412467771415;1312.8818406343476;796.80079980005;904.8687940248759;905.4114825363434;917.4220241247971;928.6192661009089;5728.942710572925;5790.449907258803;5856.063029915278;5945.464908158287;5964.194833697482;808.5189312359255;816.6937398475571;926.36081104038;937.8593211018402;976.2336565795816;3560.4481305397185;3767.698584113264;3833.769973801581;3909.5308562972627;3955.3869150281753;10085.344452610558;10392.012758787903;10466.790664186674;10493.527791249413;10528.578856845794;10176.084152426318;10309.948450257702;10321;10396.948015259875;10581.84127238092;7609.429292803062;7855.253750893656;7983.68065277394;8021.117677055519;8030.5181689098845;5753.108268218432;5833.7666493341485;5887.52899768009;5924.826169796779;5944.702764861826;8286.337093032664;9179.403338782959;9587.760305992406;9736.464494452874;9752.902470975483;9657.46883921391;9695.563699633392;9762.658306959222;9793.216542676426;9834.60661573529;6616.272210056986;6679.499037572299;7205.567665940061;7234.095738032746;7298.635068246673;8054.83890322202;8088;8100.35197184212;8104.98687664042;8139.715109971359;7060.258672839278;7092.6099064551945;7276.308750668678;7322.1579518356075;7325.963370183115;6533.967330163318;6559.934400655932;6990.965045174741;7151.2491188424465;7315.63421828917;6849.349311815369;6875.1749790025515;6939.375456208965;6942.583444993316;6955.513114081966;3919.529656441245;4348.63036641889;4452.554744525651;4756.524347565216;4947.282644016669;4022.3363145080734;4809.158397280532;4844.370231869834;4962.727050012284;5058.1401161803315 16:30:35 INFO - PROCESS | 1640 | |4;prototype.html;287.7324088597604;305.4684848363847;312.47036273516386;315.23869854301864;319.40112289457267;4296.0763435861245;4340.609345158935;4465.553444655533;4842.312516553311;5605.5515558755305;4559.042601053783;4970.602351811855;5078.5937125030005;5452.454754524546;5485.341758988927;3314.751393645486;3473.70473509752;3948.7235893487396;4166.250074986501;4222.43769805171;3680.650338217865;4176.60322269384;4183.435236243114;4219.514755803076;4221.535631080594;3723.6462536059166;4170.998960249549;4189.622933935945;4221.978890105545;4244.045089854789;3464.6613535863194;3989.501312335958;4138.668906487504;4197.979010104945;4234.597713217239;3274.2960263543214;3295.489199174132;3540.097275194809;3559.359315323241;3561.198730285665;5328.973355133238;5339.50262578012;5352.678839269656;5353.652012619206;5358.4641535846495;3874.9031274218087;4728.432588089417;4729.574338309534;4765.737884416356;4774.236122220428;5714.371344225934;5772.826815195551;5787.681677507735;5814.3313518945015;5819.301683797971;3352.8491217895253;3624.1912517798655;3680.8527658893613;3934.7639141651307;3954.8220330085214;4890.413150421937;5198.532132108091;5523.812380338247;5941.435563621449;6108.847278818065;3362.9495557566165;3910.7458015228917;3970.861019864291;3972.56301806801;3980.5024371953505;3753.211825516672;4036.858709945138;4038.404830092097;4049.5904936562697;4057.2494088593703;4202.915941681149;4362.476502819682;4736.668433209711;4743.312219728189;4751.144793937125;4908.460069392364;5580.302462192226;5849.61977471463;5883.94116058834;5896.616719913192;1119.8208286674094;1148.6439203846667;1161.4192903548226;1303.6963036963036;1330.813686083949;720.7045111504258;745.0314591031637;757.935101182839;759.0473955186206;846.2933450568721;4357.318066234027;4763.309025296039;5309.628326017219;5521.1442226454965;5557.859394750936;5557;5987.221661184019;6011.398860114041;6027.378090530546;6058.939960413481;5159.380874295109;5422.40353561108;5456.127019676912;5478.726063696799;5527.53015993645;2861.3848022675224;2973.9702602974126;3015.020118461509;3031.3937212557576;3056.203573730012;5368.852459016424;5872.706364681749;6024.493876530867;6027.60820546663;6030.7889223876955;5392.40683524812;5651.745671444794;5894.941050589439;5957.761689532457;5974.968510706381;4964.3298154748645;6025.969870150621;6027.909581356371;6042.431966414257;6048.40321355764;5108.412532558803;5389.299391079291;5543.972280138735;5701.629394089537;5766.913496297643;5105.617078719037;5502.504774570308;5676.886462270814;5689.374168841341;5740;5035.7985680572365;5261.684298941923;5373.462653734595;5991.610545314715;6046.54650901193;3257.3322468894294;3264.8204348761046;3812.5996770338716;3825.2731980923536;3840.1551658635058;5930.406959304035;5991.460768530853;6004.459598636144;6010.669413182317;6033.42682445167;5841.299044114733;5859.296884373903;5860.238169037998;5879.94120058811;5889.263842019747;5070.949290507047;5104.906988134847;5442.428545002717;5560.193771903132;5677.318721753416;4821.566059054702;5703.030484817674;5735.942640573541;5744.827655170351;5757.481826635623;5382.654336415896;5518.475744804237;5681.613722236753;5761.740721667451;5762.222100016612;5186.559142472932;5464.480874316933;5664.694831927683;5684.981831449776;5741.827745167651 16:30:35 INFO - PROCESS | 1640 | |5;yui.html;683.5932620091044;714.7819914925948;763.9006929099216;777.5218240781913;785.7917651822268;14519.927400362996;15169.165695886722;15171.013884097525;15176.31706573204;15208.01147925384;13294.468221271138;13453;13505.864941350583;13617.863821361783;13668.316584170801;819.8647223208163;836.8744688296744;840.9831803363929;852.7569642651846;856.7815207122175;831.264331067007;834.6077343648476;834.7120243515977;835.9331253499705;838.4927119092953;875.6497401039603;877.2411863737884;881.3610132653806;882.3073886998708;884.0496466298722;850.5747126436781;855.4567849415604;861.280830506531;861.2980420956962;864.6368525219423;560.814931072745;650.1125963060435;651;654.8002859127964;655.5083687234574;12375.504979800797;12405.069619778462;12538.561150359785;12713.93643031788;12722.300273484954;10959.671209863716;11378.715532111764;11384.772304553946;11586.362750048744;11606.4196790161;10041.397516148982;10173.59305627774;10386.844197337046;10441.947790261076;10500.78998420035;3418.9729199099465;3445.5176275321473;3448.137965508623;3578.3916734155255;3580.4629305604108;82516;85275.57362213149;87572;92168.07831921663;95234.52382738111;7208.459365547605;8686.826263474695;8759.781005474786;8844;9125.680601178927;15410.075395476084;17886.982301680106;18276.45170644883;18502.167402466923;18503.35238266654;7381.630918454056;7481.326680598663;7594.924050759532;7623.809404764815;7721.845563088819;4077.347624380085;4154.979225103855;4158.147579746144;4165.166966606691;4172.248995180897;3225.548423220751;3293.407186706416;3311;3322.4850148227065;3358.4962255661844;615.2371059885799;619.7582942652369;632.9936700632935;633.8161933039366;637.5409705012383;457.0584595732801;489.3491656097453;512.2009664922732;514.6423235851048;558.7234319012115;8977.236934860479;8995.55022248885;9103.726888193365;9159.893224558256;9422.104900034486;593.7328202308978;666.3835951744713;678.7963610916705;681.325487767107;686.361683634225;4334.241507736134;4544.954550454519;4549.431321084864;4557.908841823145;4599.724016559017;99020.5048974765;117247;118126.8187318133;123416.76583234232;124775.37612312062;58172.70913645489;79637;83822;84806;95233;58959.115613265836;70678.64660676663;72844.63577682078;72897.90653140207;77065.61467192628;8304.460210086325;8444.155584441507;9070.64831749271;9760.219182465244;9767.120959113714;40614.79692601518;53422;55273.44726552683;56558.58603534944;57224.71387643035;59946.70026649839;73184.63407683141;76809.84785228338;77050.6147469259;80624;11881.762364752829;12115;12227.205231659913;12268.141230114163;12274.815877761663;12724.427400766803;12906.935465322613;13157.342132893125;13331.533396331082;13374.13068150567;12124.939375303067;12461.626151215478;12527.248365098123;12693.174943628635;12882.162659427107;12364.81452778227;12399.380030998595;12422.06834487399;12538.185017973803;12966.675833104247;10931.234813562975;10983.670489885317;11042.944785276022;11047.834282485608;11140.442977851237;9464.384814987005;11036.169250421275;11128.625158374703;11233.157513186381;11435.771284574095;10808.243422960317;11047.834282485608;11228.943855280671;11254.7749045017;11323.886761132284 16:30:35 INFO - PROCESS | 1640 | __end_tp_report 16:30:35 INFO - PROCESS | 1640 | __start_cc_report 16:30:35 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,369 16:30:35 INFO - PROCESS | 1640 | __end_cc_report 16:30:35 INFO - PROCESS | 1640 | __startTimestamp1479861035437__endTimestamp 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | ------- Summary: start ------- 16:30:35 INFO - PROCESS | 1640 | Number of tests: 6 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5823.63 Median:6063.97 stddev:656.68 (10.8%) stddev-sans-first:624.46 16:30:35 INFO - PROCESS | 1640 | Values: 3156.1 3363.1 3411.8 3413.3 3442.9 5788.7 5842.9 5880.6 5882.9 5888.6 5809.9 5817.1 5845.3 5850.4 5862.4 5224.3 5284.8 5302.1 5320.0 5327.0 5029.8 5339.9 5347.7 5365.6 5382.3 5206.0 5211.6 5278.6 5333.4 5339.5 5289.8 5328.1 5330.0 5330.9 5348.5 5143.9 5278.7 5286.7 5314.6 5344.1 5854.0 5895.3 5899.4 5940.7 5952.5 6067.7 6076.9 6198.2 6209.1 6276.1 6274.7 6288.6 6300.7 6306.3 6307.7 5877.7 5955.4 5985.2 6058.9 6064.0 6331.8 6357.9 6359.7 6368.6 6390.8 6256.0 6260.7 6290.2 6306.8 6309.1 5617.7 6008.2 6219.1 6233.3 6315.7 6218.9 6261.8 6282.5 6303.0 6328.0 6255.6 6259.2 6281.3 6285.4 6302.7 4928.7 4954.8 4982.7 5082.8 5121.1 4433.7 4449.9 4463.5 4518.7 4564.8 5107.7 5492.0 5952.8 6059.3 6061.4 6205.7 6308.3 6308.4 6312.6 6327.1 5815.2 5978.3 5983.9 5995.1 5997.3 5498.8 5509.8 5509.9 5516.8 5524.2 6267.3 6342.6 6361.5 6390.8 6488.8 6296.7 6296.9 6308.1 6310.3 6324.5 6473.3 6508.9 6513.7 6517.1 6524.6 6149.1 6177.4 6220.4 6249.2 6332.5 6143.9 6314.1 6322.8 6325.0 6331.1 6252.7 6265.4 6306.1 6319.3 6328.8 5610.7 5764.6 6094.1 6287.3 6334.7 5144.2 5918.0 6144.4 6199.2 6203.3 6169.2 6255.4 6279.7 6304.7 6304.8 5330.7 5500.3 6097.2 6138.2 6153.3 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:73511.45 Median:39526.20 stddev:168082.62 (425.2%) stddev-sans-first:168464.64 16:30:35 INFO - PROCESS | 1640 | Values: 457.6 464.6 464.9 479.6 549.0 39035.0 40730.8 40955.0 40980.6 41287.8 41459.0 41832.7 42043.2 42244.0 43137.6 9338.0 9457.1 9498.8 9502.2 9529.0 9374.3 9412.5 9455.1 9493.7 9628.4 9421.0 9477.5 9477.7 9501.6 9528.6 9401.1 9477.6 9489.7 9504.0 9522.8 8058.4 9115.9 9291.1 9321.0 9406.2 34073.7 39329.8 39383.6 39394.8 39657.6 3204.0 3224.6 3231.7 3378.3 3651.7 28962.3 29233.3 29998.6 30004.2 30505.8 2939.4 3024.1 3079.5 3083.1 3084.1 505827.0 508135.0 509382.0 511968.0 512106.0 57371.7 57496.0 57610.0 57900.7 58014.1 56621.7 58273.4 58981.4 59247.4 59252.1 15465.3 15624.4 15656.5 15712.5 15779.0 12281.3 12326.0 12347.3 12355.9 12378.3 14582.6 14927.0 14982.8 15022.5 15069.7 3155.8 5263.7 6214.3 6234.4 6296.5 852.4 885.4 943.3 953.7 1073.1 28352.6 28749.6 29302.3 29572.9 29751.6 1023.8 1023.9 1045.8 1093.7 1105.5 13590.7 13902.2 14572.4 14854.8 15084.0 708795.0 709240.0 709659.0 713622.0 715520.0 56322.7 56445.7 57080.7 57081.7 57083.9 1260.5 1313.5 1358.1 1360.4 1364.5 28264.0 28428.9 29140.0 29395.3 29591.6 47078.5 47700.8 47830.3 47944.0 48020.0 635689.0 642132.0 642244.0 642321.0 643152.0 21346.0 23008.2 25190.4 25562.6 25984.5 43980.8 43988.3 44521.8 44837.3 44988.8 36595.5 37162.8 38134.0 38508.6 39832.8 28400.4 31126.8 36165.3 37893.8 38622.6 34326.3 35054.6 37269.6 37638.1 37658.1 32459.8 33801.5 33899.7 34390.7 34551.0 20736.5 21530.9 24121.6 25688.4 25800.0 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:33890.94 Median:386.07 stddev:97686.28 (25302.8%) stddev-sans-first:97927.83 16:30:35 INFO - PROCESS | 1640 | Values: 337.8 363.0 377.7 378.0 394.2 18192.5 19295.2 19640.8 20195.5 20425.0 19385.4 19712.0 19768.7 20115.0 20293.8 5487.3 5530.3 5553.5 5851.4 5852.4 5604.0 5809.4 5812.0 5813.2 5813.3 5512.3 5623.8 5677.6 5774.7 5780.1 5619.9 5630.7 5700.7 5715.5 5719.2 4805.5 4934.9 4968.1 4972.5 5005.5 22497.4 23607.1 23785.8 24530.4 24631.9 2061.3 2200.2 2302.5 2553.7 2584.2 9459.5 10003.3 10040.5 13206.0 13602.0 1829.0 1908.5 1908.9 1915.8 1973.4 122110.0 122646.0 129333.0 129943.0 131526.0 9600.0 10773.4 11517.5 11571.7 12008.6 14136.3 14222.4 14424.8 14426.3 14438.4 8111.8 8370.7 8413.7 8480.7 8524.7 8218.3 8236.0 8267.5 8330.4 8413.5 4366.1 4522.3 4542.1 4605.7 4607.4 1656.8 1911.2 1953.3 1968.5 2001.1 827.8 918.2 950.3 962.0 986.3 19642.0 20749.9 20771.7 20811.4 20830.9 916.5 934.6 948.5 973.9 981.5 10550.2 10911.9 10968.7 11006.8 11020.3 515878.0 571169.0 605218.0 605293.0 625247.0 80730.6 108458.9 109301.0 123141.0 125295.0 936.2 974.8 981.1 985.5 989.5 17842.0 18674.9 18893.6 18977.9 19050.0 51460.0 51464.2 52771.9 52894.4 53503.7 51814.5 72648.0 72936.3 75300.2 75497.6 7765.5 7784.9 7793.5 7957.7 8219.5 8915.5 11997.8 12100.0 12259.2 12565.3 9285.9 9559.5 10516.5 10634.5 10773.3 10193.4 10201.6 10264.5 10456.7 10587.3 10452.0 10650.5 10823.0 10966.3 10970.8 7773.1 7832.0 7977.2 7988.9 8129.3 7965.0 9931.6 10525.4 10591.7 10746.0 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5552.79 Median:5212.93 stddev:3082.62 (59.1%) stddev-sans-first:3076.12 16:30:35 INFO - PROCESS | 1640 | Values: 1486.0 1523.0 1706.3 1749.8 1821.3 7736.1 7973.8 8354.3 8756.6 8822.2 8253.0 8534.5 8563.4 8678.1 8699.5 2343.4 2357.6 2360.3 2362.5 2365.3 2367.7 2391.5 2400.9 2419.4 2420.0 2338.9 2358.7 2359.3 2383.0 2384.6 2285.0 2294.7 2315.1 2316.1 2338.5 2355.2 2376.2 2383.2 2385.5 2390.5 8669.5 8703.9 8705.5 8706.9 8728.0 5169.4 5306.5 5332.9 5335.9 5364.7 3372.6 3372.9 3394.0 3458.7 3495.1 982.4 1004.6 1031.1 1036.2 1038.6 11193.2 11237.3 11311.9 11320.2 11341.0 4573.0 4690.7 4765.3 4795.6 4833.8 9707.5 9781.3 9786.0 9802.4 9803.1 5209.5 5216.4 5295.0 5309.4 5317.1 4321.6 4335.7 4815.3 4844.5 4865.5 3397.3 3652.9 3902.0 3902.2 4000.9 1142.7 1265.1 1284.7 1301.1 1312.9 796.8 904.9 905.4 917.4 928.6 5728.9 5790.4 5856.1 5945.5 5964.2 808.5 816.7 926.4 937.9 976.2 3560.4 3767.7 3833.8 3909.5 3955.4 10085.3 10392.0 10466.8 10493.5 10528.6 10176.1 10309.9 10321.0 10396.9 10581.8 7609.4 7855.3 7983.7 8021.1 8030.5 5753.1 5833.8 5887.5 5924.8 5944.7 8286.3 9179.4 9587.8 9736.5 9752.9 9657.5 9695.6 9762.7 9793.2 9834.6 6616.3 6679.5 7205.6 7234.1 7298.6 8054.8 8088.0 8100.4 8105.0 8139.7 7060.3 7092.6 7276.3 7322.2 7326.0 6534.0 6559.9 6991.0 7151.2 7315.6 6849.3 6875.2 6939.4 6942.6 6955.5 3919.5 4348.6 4452.6 4756.5 4947.3 4022.3 4809.2 4844.4 4962.7 5058.1 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4599.21 Median:5230.11 stddev:1457.92 (27.9%) stddev-sans-first:1425.64 16:30:35 INFO - PROCESS | 1640 | Values: 287.7 305.5 312.5 315.2 319.4 4296.1 4340.6 4465.6 4842.3 5605.6 4559.0 4970.6 5078.6 5452.5 5485.3 3314.8 3473.7 3948.7 4166.3 4222.4 3680.7 4176.6 4183.4 4219.5 4221.5 3723.6 4171.0 4189.6 4222.0 4244.0 3464.7 3989.5 4138.7 4198.0 4234.6 3274.3 3295.5 3540.1 3559.4 3561.2 5329.0 5339.5 5352.7 5353.7 5358.5 3874.9 4728.4 4729.6 4765.7 4774.2 5714.4 5772.8 5787.7 5814.3 5819.3 3352.8 3624.2 3680.9 3934.8 3954.8 4890.4 5198.5 5523.8 5941.4 6108.8 3362.9 3910.7 3970.9 3972.6 3980.5 3753.2 4036.9 4038.4 4049.6 4057.2 4202.9 4362.5 4736.7 4743.3 4751.1 4908.5 5580.3 5849.6 5883.9 5896.6 1119.8 1148.6 1161.4 1303.7 1330.8 720.7 745.0 757.9 759.0 846.3 4357.3 4763.3 5309.6 5521.1 5557.9 5557.0 5987.2 6011.4 6027.4 6058.9 5159.4 5422.4 5456.1 5478.7 5527.5 2861.4 2974.0 3015.0 3031.4 3056.2 5368.9 5872.7 6024.5 6027.6 6030.8 5392.4 5651.7 5894.9 5957.8 5975.0 4964.3 6026.0 6027.9 6042.4 6048.4 5108.4 5389.3 5544.0 5701.6 5766.9 5105.6 5502.5 5676.9 5689.4 5740.0 5035.8 5261.7 5373.5 5991.6 6046.5 3257.3 3264.8 3812.6 3825.3 3840.2 5930.4 5991.5 6004.5 6010.7 6033.4 5841.3 5859.3 5860.2 5879.9 5889.3 5070.9 5104.9 5442.4 5560.2 5677.3 4821.6 5703.0 5735.9 5744.8 5757.5 5382.7 5518.5 5681.6 5761.7 5762.2 5186.6 5464.5 5664.7 5685.0 5741.8 16:30:35 INFO - PROCESS | 1640 | 16:30:35 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:19506.60 Median:500.78 stddev:29032.96 (5797.6%) stddev-sans-first:29080.00 16:30:35 INFO - PROCESS | 1640 | Values: 683.6 714.8 763.9 777.5 785.8 14519.9 15169.2 15171.0 15176.3 15208.0 13294.5 13453.0 13505.9 13617.9 13668.3 819.9 836.9 841.0 852.8 856.8 831.3 834.6 834.7 835.9 838.5 875.6 877.2 881.4 882.3 884.0 850.6 855.5 861.3 861.3 864.6 560.8 650.1 651.0 654.8 655.5 12375.5 12405.1 12538.6 12713.9 12722.3 10959.7 11378.7 11384.8 11586.4 11606.4 10041.4 10173.6 10386.8 10441.9 10500.8 3419.0 3445.5 3448.1 3578.4 3580.5 82516.0 85275.6 87572.0 92168.1 95234.5 7208.5 8686.8 8759.8 8844.0 9125.7 15410.1 17887.0 18276.5 18502.2 18503.4 7381.6 7481.3 7594.9 7623.8 7721.8 4077.3 4155.0 4158.1 4165.2 4172.2 3225.5 3293.4 3311.0 3322.5 3358.5 615.2 619.8 633.0 633.8 637.5 457.1 489.3 512.2 514.6 558.7 8977.2 8995.6 9103.7 9159.9 9422.1 593.7 666.4 678.8 681.3 686.4 4334.2 4545.0 4549.4 4557.9 4599.7 99020.5 117247.0 118126.8 123416.8 124775.4 58172.7 79637.0 83822.0 84806.0 95233.0 58959.1 70678.6 72844.6 72897.9 77065.6 8304.5 8444.2 9070.6 9760.2 9767.1 40614.8 53422.0 55273.4 56558.6 57224.7 59946.7 73184.6 76809.8 77050.6 80624.0 11881.8 12115.0 12227.2 12268.1 12274.8 12724.4 12906.9 13157.3 13331.5 13374.1 12124.9 12461.6 12527.2 12693.2 12882.2 12364.8 12399.4 12422.1 12538.2 12966.7 10931.2 10983.7 11042.9 11047.8 11140.4 9464.4 11036.2 11128.6 11233.2 11435.8 10808.2 11047.8 11228.9 11254.8 11323.9 16:30:35 INFO - PROCESS | 1640 | -------- Summary: end -------- 16:30:35 INFO - PROCESS | 1640 | 16:30:36 INFO - TEST-INFO | 1640: exit 0 16:30:36 INFO - TEST-OK | dromaeo_css | took 1117159ms 16:30:36 INFO - TEST-START | kraken 16:30:36 INFO - operating with platform_type : mac_ 16:30:36 INFO - Initialising browser for kraken test... 16:30:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpjZbT9t/profile http://localhost:49238/getInfo.html) 16:30:37 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 16:30:38 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 16:30:38 INFO - PROCESS | 1650 | colorDepth:24 16:30:38 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 16:30:38 INFO - PROCESS | 1650 | __metrics 16:30:39 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 16:30:39 INFO - TEST-INFO | 1650: exit 0 16:30:39 INFO - Browser initialized. 16:30:39 INFO - Running cycle 1/1 for kraken test... 16:30:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpjZbT9t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:52 INFO - PROCESS | 1652 | RSS: Main: 230051840 16:30:52 INFO - PROCESS | 1652 | 16:30:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:56 INFO - PROCESS | 1652 | RSS: Main: 241836032 16:30:56 INFO - PROCESS | 1652 | 16:30:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 16:30:59 INFO - PROCESS | 1652 | RSS: Main: 249438208 16:30:59 INFO - PROCESS | 1652 | 16:31:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 16:31:03 INFO - PROCESS | 1652 | RSS: Main: 251310080 16:31:03 INFO - PROCESS | 1652 | 16:31:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 16:31:06 INFO - PROCESS | 1652 | RSS: Main: 252944384 16:31:06 INFO - PROCESS | 1652 | 16:31:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:08 INFO - PROCESS | 1652 | RSS: Main: 253259776 16:31:08 INFO - PROCESS | 1652 | 16:31:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:12 INFO - PROCESS | 1652 | RSS: Main: 259170304 16:31:12 INFO - PROCESS | 1652 | 16:31:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:15 INFO - PROCESS | 1652 | RSS: Main: 258113536 16:31:15 INFO - PROCESS | 1652 | 16:31:18 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 16:31:19 INFO - PROCESS | 1652 | RSS: Main: 258306048 16:31:19 INFO - PROCESS | 1652 | 16:31:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:21 INFO - PROCESS | 1652 | RSS: Main: 257515520 16:31:21 INFO - PROCESS | 1652 | 16:31:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:24 INFO - PROCESS | 1652 | RSS: Main: 257548288 16:31:24 INFO - PROCESS | 1652 | 16:31:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:27 INFO - PROCESS | 1652 | RSS: Main: 257589248 16:31:27 INFO - PROCESS | 1652 | 16:31:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:30 INFO - PROCESS | 1652 | RSS: Main: 257892352 16:31:30 INFO - PROCESS | 1652 | 16:31:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:34 INFO - PROCESS | 1652 | RSS: Main: 257802240 16:31:34 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 16:31:36 INFO - PROCESS | 1652 | RSS: Main: 259407872 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | __start_tp_report 16:31:36 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 16:31:36 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 16:31:36 INFO - PROCESS | 1652 | |i|pagename|runs| 16:31:36 INFO - PROCESS | 1652 | |0;ai-astar;104;120;120;120;148;120;176;176;140;121 16:31:36 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;108;112;148;121;118;120;111;162;131 16:31:36 INFO - PROCESS | 1652 | |2;audio-dft;239;191;218;283;237;182;252;286;191;187 16:31:36 INFO - PROCESS | 1652 | |3;audio-fft;82;59;57;82;59;58;60;60;78;83 16:31:36 INFO - PROCESS | 1652 | |4;audio-oscillator;87;70;86;70;68;69;89;77;83;89 16:31:36 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;132;89;92;136;133;90;87;89;87 16:31:36 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;97;97;96;96;90;103;154;149;117 16:31:36 INFO - PROCESS | 1652 | |7;imaging-desaturate;98;122;88;87;92;77;140;144;90;90 16:31:36 INFO - PROCESS | 1652 | |8;json-parse-financial;57;53;56;56;59;58;59;57;70;89 16:31:36 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;67;75;76;85;75;72;70;70;71;73 16:31:36 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;85;85;85;85;87;74;84;99;112;82 16:31:36 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;164;152;161;158;158;148;148;152;199;165 16:31:36 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;168;175;164;169;226;219;223;181;170;172 16:31:36 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;68;67;81;73;68;67;67;69;68 16:31:36 INFO - PROCESS | 1652 | __end_tp_report 16:31:36 INFO - PROCESS | 1652 | __start_cc_report 16:31:36 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,977 16:31:36 INFO - PROCESS | 1652 | __end_cc_report 16:31:36 INFO - PROCESS | 1652 | __startTimestamp1479861096503__endTimestamp 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | ------- Summary: start ------- 16:31:36 INFO - PROCESS | 1652 | Number of tests: 14 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:134.50 Median:130.50 stddev:24.97 (19.1%) stddev-sans-first:23.92 16:31:36 INFO - PROCESS | 1652 | Values: 104.0 120.0 120.0 120.0 148.0 120.0 176.0 176.0 140.0 121.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:126.00 stddev:17.91 (14.2%) stddev-sans-first:18.28 16:31:36 INFO - PROCESS | 1652 | Values: 141.0 108.0 112.0 148.0 121.0 118.0 120.0 111.0 162.0 131.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:226.60 Median:238.00 stddev:39.13 (16.4%) stddev-sans-first:41.25 16:31:36 INFO - PROCESS | 1652 | Values: 239.0 191.0 218.0 283.0 237.0 182.0 252.0 286.0 191.0 187.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:67.80 Median:69.00 stddev:11.68 (16.9%) stddev-sans-first:11.20 16:31:36 INFO - PROCESS | 1652 | Values: 82.0 59.0 57.0 82.0 59.0 58.0 60.0 60.0 78.0 83.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:84.50 stddev:8.92 (10.6%) stddev-sans-first:8.95 16:31:36 INFO - PROCESS | 1652 | Values: 87.0 70.0 86.0 70.0 68.0 69.0 89.0 77.0 83.0 89.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:89.00 stddev:21.58 (24.2%) stddev-sans-first:22.41 16:31:36 INFO - PROCESS | 1652 | Values: 90.0 132.0 89.0 92.0 136.0 133.0 90.0 87.0 89.0 87.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:95.50 stddev:23.35 (24.4%) stddev-sans-first:24.18 16:31:36 INFO - PROCESS | 1652 | Values: 95.0 97.0 97.0 96.0 96.0 90.0 103.0 154.0 149.0 117.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:102.80 Median:89.00 stddev:23.69 (26.6%) stddev-sans-first:25.06 16:31:36 INFO - PROCESS | 1652 | Values: 98.0 122.0 88.0 87.0 92.0 77.0 140.0 144.0 90.0 90.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:58.50 stddev:10.68 (18.3%) stddev-sans-first:11.21 16:31:36 INFO - PROCESS | 1652 | Values: 57.0 53.0 56.0 56.0 59.0 58.0 59.0 57.0 70.0 89.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:74.00 stddev:4.93 (6.7%) stddev-sans-first:4.65 16:31:36 INFO - PROCESS | 1652 | Values: 67.0 75.0 76.0 85.0 75.0 72.0 70.0 70.0 71.0 73.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:85.00 stddev:10.44 (12.3%) stddev-sans-first:11.03 16:31:36 INFO - PROCESS | 1652 | Values: 85.0 85.0 85.0 85.0 87.0 74.0 84.0 99.0 112.0 82.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:160.50 Median:159.50 stddev:14.85 (9.3%) stddev-sans-first:15.70 16:31:36 INFO - PROCESS | 1652 | Values: 164.0 152.0 161.0 158.0 158.0 148.0 148.0 152.0 199.0 165.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.70 Median:178.00 stddev:25.27 (14.2%) stddev-sans-first:25.88 16:31:36 INFO - PROCESS | 1652 | Values: 168.0 175.0 164.0 169.0 226.0 219.0 223.0 181.0 170.0 172.0 16:31:36 INFO - PROCESS | 1652 | 16:31:36 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.00 stddev:4.43 (6.5%) stddev-sans-first:4.60 16:31:36 INFO - PROCESS | 1652 | Values: 67.0 68.0 67.0 81.0 73.0 68.0 67.0 67.0 69.0 68.0 16:31:36 INFO - PROCESS | 1652 | -------- Summary: end -------- 16:31:36 INFO - PROCESS | 1652 | 16:31:37 INFO - TEST-INFO | 1652: exit 0 16:31:37 INFO - TEST-OK | kraken | took 61484ms 16:31:37 INFO - SUITE-END | took 1178s 16:31:37 INFO - Completed test suite (00:19:38) 16:31:37 INFO - javascript benchmark 16:31:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7144.916307958281, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3156.084735426726, 3363.108776174313, 3411.761176717629, 3413.2832105257926, 3442.879499217528, 5788.710564471776, 5842.883142337156, 5880.647161170333, 5882.941170588293, 5888.617239879405, 5809.854753631162, 5817.127430885369, 5845.298564172294, 5850.35646078932, 5862.384449632791, 5224.268602395678, 5284.762185701653, 5302.125149350352, 5320.015797077553, 5327.014502317064, 5029.798808047673, 5339.893202135974, 5347.652402593839, 5365.597580181482, 5382.273393091941, 5205.973970130144, 5211.557017653504, 5278.577713782888, 5333.386660534049, 5339.519443250107, 5289.809792796609, 5328.147496400596, 5329.973350133263, 5330.893382132355, 5348.51863332298, 5143.871403214949, 5278.683279003272, 5286.709230992294, 5314.601404894648, 5344.064788662007, 5853.970730146365, 5895.263092113486, 5899.439553242478, 5940.732667029994, 5952.494038006731, 6067.666278354689, 6076.936536106208, 6198.163247961557, 6209.06719339988, 6276.121343011982, 6274.717637706269, 6288.622682639057, 6300.684965751694, 6306.306306306303, 6307.684615769193, 5877.735501902424, 5955.40445955401, 5985.221921150266, 6058.909116363258, 6063.969680151571, 6331.810045698544, 6357.936420635827, 6359.650219237987, 6368.649724265118, 6390.840228994312, 6256.0303153011355, 6260.655663938436, 6290.182276304051, 6306.779262725782, 6309.116723658692, 5617.747201375948, 6008.24896887889, 6219.129321894939, 6233.252009758858, 6315.684215789192, 6218.875622487525, 6261.78083767066, 6282.4974002079725, 6303.023031430135, 6327.96836015817, 6255.562110652301, 6259.1550140730415, 6281.309056003836, 6285.371462853678, 6302.684865756693, 4928.67963582366, 4954.77703503336, 4982.676126051868, 5082.818935170906, 5121.07820592297, 4433.733975961452, 4449.866504004885, 4463.464384273908, 4518.661100417482, 4564.817407303737, 5107.744612769421, 5492.011437941209, 5952.761889524457, 6059.303180134341, 6061.363556826558, 6205.6586887721605, 6308.3060863305, 6308.432241098231, 6312.621242725451, 6327.145835312266, 5815.244018277682, 5978.252718410199, 5983.850403739942, 5995.100734889801, 5997.2803263607775, 5498.752556134984, 5509.779608815603, 5509.889802203934, 5516.834494965157, 5524.226608274845, 6267.310595834455, 6342.5560210785725, 6361.522885783677, 6390.840228994126, 6488.837779055561, 6296.748130074746, 6296.905546416716, 6308.053791931247, 6310.274318453253, 6324.525660575383, 6473.320301368289, 6508.9349106508325, 6513.706883190173, 6517.087607735016, 6524.641144737085, 6149.139120523041, 6177.382261773787, 6220.440160385587, 6249.156363890998, 6332.525060620564, 6143.907841382294, 6314.084457753692, 6322.810315690536, 6325.019621958604, 6331.113644089739, 6252.749890004349, 6265.4361107500545, 6306.148669929501, 6319.304876463493, 6328.841778955379, 5610.653443173527, 5764.596478246483, 6094.085887116968, 6287.308396076336, 6334.651594162366, 5144.202648589475, 5917.970410148094, 6144.433169541739, 6199.194104766351, 6203.348648391854, 6169.167162433014, 6255.437010669061, 6279.686015699105, 6304.684765761602, 6304.779332723333, 5330.653507521999, 5500.28496295495, 6097.17688112117, 6138.202033735586, 6153.323134455296], "value": 5781.261749547685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.56759861930476, 464.57723471640816, 464.85357112509547, 479.5851588376056, 549.0145189385054, 39035.02412439691, 40730.796346018265, 40955.0, 40980.59019409813, 41287.79356103219, 41459.0, 41832.74501764952, 42043.159136817245, 42244.0, 43137.568624313746, 9337.953310233457, 9457.054294570522, 9498.81002379952, 9502.239820814353, 9529.0, 9374.296927730413, 9412.482313472756, 9455.149036586707, 9493.667721629745, 9628.41131213349, 9421.010793866648, 9477.47873866937, 9477.715668529921, 9501.572429240701, 9528.571214295338, 9401.10689484498, 9477.620895164186, 9489.667861624845, 9504.0, 9522.761930951712, 8058.395620328499, 9115.86051743532, 9291.117343852391, 9321.0, 9406.247500199968, 34073.65926340754, 39329.803350983064, 39383.60616393828, 39394.80302598498, 39657.60342396568, 3203.9839800800846, 3224.580804495424, 3231.6929891660257, 3378.2567835076243, 3651.653092956165, 28962.27594310138, 29233.269168270752, 29998.550021749692, 30004.249893752614, 30505.847470762503, 2939.3533422646983, 3024.138120635608, 3079.538069289602, 3083.1058992892035, 3084.1364417963005, 505827.0, 508135.0, 509382.0, 511968.0, 512106.0, 57371.71314143486, 57495.98764043238, 57610.0, 57900.710496447246, 58014.129788053215, 56621.71689141528, 58273.417265827644, 58981.41018589759, 59247.40752592419, 59252.11121833176, 15465.304061317267, 15624.375024998873, 15656.530304090895, 15712.528624141294, 15779.0, 12281.263124212575, 12326.0, 12347.320897350732, 12355.93822030884, 12378.257304561756, 14582.61465160808, 14927.029743066665, 14982.775258371134, 15022.549323520312, 15069.698606027818, 3155.7790954632956, 5263.6578622389425, 6214.2542894852, 6234.407731265523, 6296.464800491918, 852.424613385962, 885.3846576629181, 943.2783920300975, 953.6566835939057, 1073.1468482556386, 28352.574711379344, 28749.562521873824, 29302.26744331409, 29572.852135739184, 29751.553726694117, 1023.8361862102176, 1023.8941942701864, 1045.7960697664034, 1093.748437859297, 1105.5301496863801, 13590.728185436235, 13902.23537705436, 14572.41710331596, 14854.777178342334, 15084.019538729948, 708795.0, 709240.0, 709659.0, 713622.0, 715520.0, 56322.7183864078, 56445.71777141088, 57080.714596426755, 57081.714591426775, 57083.85832283331, 1260.4517035089582, 1313.5205649937895, 1358.110437663332, 1360.3538319298452, 1364.4814970311418, 28264.0, 28428.85785571059, 29139.980100696375, 29395.265118372212, 29591.556126657688, 47078.52921470878, 47700.80747981328, 47830.28254576115, 47944.04111917812, 48020.0, 635689.0, 642132.0, 642244.0, 642321.0, 643152.0, 21346.0, 23008.19471318496, 25190.370240744127, 25562.616560751976, 25984.480310394065, 43980.78009609931, 43988.34017489676, 44521.77739111284, 44837.32744008777, 44988.77505612451, 36595.45106823453, 37162.814185928895, 38134.0, 38508.614913850506, 39832.80083599564, 28400.43199136047, 31126.844365778026, 36165.27669446649, 37893.81053094717, 38622.6137738619, 34326.28368581611, 35054.64945350616, 37269.627303726615, 37638.059048524, 37658.05854853541, 32459.837700811346, 33801.49297760585, 33899.66100339064, 34390.65609343975, 34550.96347109591, 20736.48158796042, 21530.92345382756, 24121.638175427033, 25688.357791055372, 25799.96800128049], "value": 19185.711455922738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.76525314906144, 362.9927401451973, 377.68085967357575, 377.9527559055118, 394.1840390391888, 18192.454226373215, 19295.228190872367, 19640.803591964075, 20195.495112622193, 20425.0, 19385.4184374469, 19712.0, 19768.703469447933, 20115.0, 20293.797062029375, 5487.341519017724, 5530.253415788878, 5553.500184983351, 5851.444112809277, 5852.385499522553, 5603.971980140093, 5809.3900140485275, 5811.970940145315, 5813.244278243822, 5813.273340832396, 5512.33851937768, 5623.80316688916, 5677.630953987978, 5774.682392468412, 5780.0751879699255, 5619.915701264485, 5630.746616402252, 5700.686462244575, 5715.514181294574, 5719.227904232917, 4805.486271824364, 4934.851954441372, 4968.105740966625, 4972.502749725035, 5005.524475174852, 22497.437564060867, 23607.05571777127, 23785.762142378528, 24530.386740331454, 24631.87684061586, 2061.309461330456, 2200.19692812124, 2302.5394921015695, 2553.6680231569962, 2584.2118153963033, 9459.479728614924, 10003.34978226413, 10040.548175332127, 13206.0, 13602.0, 1828.963420731578, 1908.4847091285415, 1908.9331873384504, 1915.79884112169, 1973.4277059652684, 122110.0, 122646.0, 129333.0, 129943.0, 131526.0, 9600.039996000483, 10773.407462589475, 11517.48171332292, 11571.710707232218, 12008.579699710468, 14136.293185340692, 14222.359993800304, 14424.783628245585, 14426.278686065654, 14438.422463101568, 8111.75664730059, 8370.702541106139, 8413.663453461793, 8480.703175388955, 8524.74425767228, 8218.260356567938, 8235.95882020586, 8267.462614930011, 8330.416870818985, 8413.537255450887, 4366.12677464502, 4522.25382811839, 4542.136993971135, 4605.700629459075, 4607.35497030416, 1656.8011838579448, 1911.1590900004232, 1953.3456292142007, 1968.4586738646988, 2001.1095062697252, 827.8468483330482, 918.1598837064012, 950.296780382512, 961.956711947964, 986.3342243985281, 19642.017899104987, 20749.89625051865, 20771.68842467364, 20811.375658730263, 20830.895845520674, 916.4776077635684, 934.5841100710119, 948.4830767231875, 973.8539219117189, 981.4601968917067, 10550.15598752098, 10911.890881091247, 10968.725781855517, 11006.779864402668, 11020.338779673246, 515878.0, 571169.0, 605217.9739101277, 605293.0, 625247.0, 80730.59634701788, 108458.91541084646, 109301.0, 123141.0, 125295.0, 936.2322895225985, 974.8196583632051, 981.0875885426622, 985.531872360623, 989.4656885281867, 17842.0, 18674.90662546733, 18893.622127557097, 18977.90511047436, 19050.0, 51460.0, 51464.22803657873, 52771.9445611078, 52894.41316760503, 53503.73248133734, 51814.481855180966, 72648.0, 72936.27063729295, 75300.24699752932, 75497.6225118871, 7765.495242809199, 7784.88322675149, 7793.532388056735, 7957.721479748181, 8219.506829590244, 8915.50964696922, 11997.82003269969, 12100.0, 12259.203151795105, 12565.308908009782, 9285.907140928504, 9559.474228917197, 10516.526756296138, 10634.52144653508, 10773.299735517166, 10193.388396696222, 10201.642942496977, 10264.538095785558, 10456.68629941103, 10587.258891877802, 10452.0, 10650.520726567169, 10823.025927666547, 10966.342019478856, 10970.835437468604, 7773.144954055162, 7832.020997375328, 7977.242161994601, 7988.920110798817, 8129.268365847102, 7964.960175199087, 9931.553080111556, 10525.36847789135, 10591.682249532527, 10746.0], "value": 9303.723716604414, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1485.9554213373597, 1522.9771553426697, 1706.2663054886402, 1749.7987731410876, 1821.3261093395445, 7736.110347310061, 7973.840523189533, 8354.289885359743, 8756.649734010647, 8822.206001459866, 8253.0, 8534.48793072416, 8563.443376180543, 8678.132186781319, 8699.52152631605, 2343.4492894169857, 2357.610994185957, 2360.33910505058, 2362.4684445999683, 2365.29041287613, 2367.7277113132036, 2391.473875747333, 2400.9039638414442, 2419.383057320381, 2419.9516009679796, 2338.8830558472096, 2358.705161854768, 2359.28041947206, 2382.9761702383016, 2384.582698027847, 2285.0, 2294.6558016297604, 2315.120254303371, 2316.108298305148, 2338.485533182714, 2355.211004313557, 2376.2039716694926, 2383.2373640435067, 2385.5109702510945, 2390.521895620883, 8669.479831210148, 8703.869441958375, 8705.47767133974, 8706.869396959051, 8727.956360218222, 5169.379674439054, 5306.548943339821, 5332.893342133174, 5335.893282134336, 5364.678119312854, 3372.629010808809, 3372.865085396581, 3394.0, 3458.688718015391, 3495.143689796016, 982.4007355513131, 1004.5881188712593, 1031.1080915008538, 1036.2228328753436, 1038.5638032026568, 11193.216474846764, 11237.269577477438, 11311.886881131166, 11320.207585468937, 11341.036011938995, 4573.0, 4690.6951048182445, 4765.309030190603, 4795.592374648124, 4833.758312084382, 9707.514624268899, 9781.315307928518, 9786.0, 9802.411855288705, 9803.068708472683, 5209.479052094836, 5216.37403511581, 5294.97352513235, 5309.41596424393, 5317.1226747586215, 4321.611055005002, 4335.653147748172, 4815.253635686474, 4844.467108618049, 4865.464798872121, 3397.286569820316, 3652.945205821915, 3901.9804900975882, 3902.2195560887367, 4000.8599699010397, 1142.691618097274, 1265.089135822225, 1284.653143651209, 1301.1412467771415, 1312.8818406343476, 796.80079980005, 904.8687940248759, 905.4114825363434, 917.4220241247971, 928.6192661009089, 5728.942710572925, 5790.449907258803, 5856.063029915278, 5945.464908158287, 5964.194833697482, 808.5189312359255, 816.6937398475571, 926.36081104038, 937.8593211018402, 976.2336565795816, 3560.4481305397185, 3767.698584113264, 3833.769973801581, 3909.5308562972627, 3955.3869150281753, 10085.344452610558, 10392.012758787903, 10466.790664186674, 10493.527791249413, 10528.578856845794, 10176.084152426318, 10309.948450257702, 10321.0, 10396.948015259875, 10581.84127238092, 7609.429292803062, 7855.253750893656, 7983.68065277394, 8021.117677055519, 8030.5181689098845, 5753.108268218432, 5833.7666493341485, 5887.52899768009, 5924.826169796779, 5944.702764861826, 8286.337093032664, 9179.403338782959, 9587.760305992406, 9736.464494452874, 9752.902470975483, 9657.46883921391, 9695.563699633392, 9762.658306959222, 9793.216542676426, 9834.60661573529, 6616.272210056986, 6679.499037572299, 7205.567665940061, 7234.095738032746, 7298.635068246673, 8054.83890322202, 8088.0, 8100.35197184212, 8104.98687664042, 8139.715109971359, 7060.258672839278, 7092.6099064551945, 7276.308750668678, 7322.1579518356075, 7325.963370183115, 6533.967330163318, 6559.934400655932, 6990.965045174741, 7151.2491188424465, 7315.63421828917, 6849.349311815369, 6875.1749790025515, 6939.375456208965, 6942.583444993316, 6955.513114081966, 3919.529656441245, 4348.63036641889, 4452.554744525651, 4756.524347565216, 4947.282644016669, 4022.3363145080734, 4809.158397280532, 4844.370231869834, 4962.727050012284, 5058.1401161803315], "value": 4481.547292109216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.7324088597604, 305.4684848363847, 312.47036273516386, 315.23869854301864, 319.40112289457267, 4296.0763435861245, 4340.609345158935, 4465.553444655533, 4842.312516553311, 5605.5515558755305, 4559.042601053783, 4970.602351811855, 5078.5937125030005, 5452.454754524546, 5485.341758988927, 3314.751393645486, 3473.70473509752, 3948.7235893487396, 4166.250074986501, 4222.43769805171, 3680.650338217865, 4176.60322269384, 4183.435236243114, 4219.514755803076, 4221.535631080594, 3723.6462536059166, 4170.998960249549, 4189.622933935945, 4221.978890105545, 4244.045089854789, 3464.6613535863194, 3989.501312335958, 4138.668906487504, 4197.979010104945, 4234.597713217239, 3274.2960263543214, 3295.489199174132, 3540.097275194809, 3559.359315323241, 3561.198730285665, 5328.973355133238, 5339.50262578012, 5352.678839269656, 5353.652012619206, 5358.4641535846495, 3874.9031274218087, 4728.432588089417, 4729.574338309534, 4765.737884416356, 4774.236122220428, 5714.371344225934, 5772.826815195551, 5787.681677507735, 5814.3313518945015, 5819.301683797971, 3352.8491217895253, 3624.1912517798655, 3680.8527658893613, 3934.7639141651307, 3954.8220330085214, 4890.413150421937, 5198.532132108091, 5523.812380338247, 5941.435563621449, 6108.847278818065, 3362.9495557566165, 3910.7458015228917, 3970.861019864291, 3972.56301806801, 3980.5024371953505, 3753.211825516672, 4036.858709945138, 4038.404830092097, 4049.5904936562697, 4057.2494088593703, 4202.915941681149, 4362.476502819682, 4736.668433209711, 4743.312219728189, 4751.144793937125, 4908.460069392364, 5580.302462192226, 5849.61977471463, 5883.94116058834, 5896.616719913192, 1119.8208286674094, 1148.6439203846667, 1161.4192903548226, 1303.6963036963036, 1330.813686083949, 720.7045111504258, 745.0314591031637, 757.935101182839, 759.0473955186206, 846.2933450568721, 4357.318066234027, 4763.309025296039, 5309.628326017219, 5521.1442226454965, 5557.859394750936, 5557.0, 5987.221661184019, 6011.398860114041, 6027.378090530546, 6058.939960413481, 5159.380874295109, 5422.40353561108, 5456.127019676912, 5478.726063696799, 5527.53015993645, 2861.3848022675224, 2973.9702602974126, 3015.020118461509, 3031.3937212557576, 3056.203573730012, 5368.852459016424, 5872.706364681749, 6024.493876530867, 6027.60820546663, 6030.7889223876955, 5392.40683524812, 5651.745671444794, 5894.941050589439, 5957.761689532457, 5974.968510706381, 4964.3298154748645, 6025.969870150621, 6027.909581356371, 6042.431966414257, 6048.40321355764, 5108.412532558803, 5389.299391079291, 5543.972280138735, 5701.629394089537, 5766.913496297643, 5105.617078719037, 5502.504774570308, 5676.886462270814, 5689.374168841341, 5740.0, 5035.7985680572365, 5261.684298941923, 5373.462653734595, 5991.610545314715, 6046.54650901193, 3257.3322468894294, 3264.8204348761046, 3812.5996770338716, 3825.2731980923536, 3840.1551658635058, 5930.406959304035, 5991.460768530853, 6004.459598636144, 6010.669413182317, 6033.42682445167, 5841.299044114733, 5859.296884373903, 5860.238169037998, 5879.94120058811, 5889.263842019747, 5070.949290507047, 5104.906988134847, 5442.428545002717, 5560.193771903132, 5677.318721753416, 4821.566059054702, 5703.030484817674, 5735.942640573541, 5744.827655170351, 5757.481826635623, 5382.654336415896, 5518.475744804237, 5681.613722236753, 5761.740721667451, 5762.222100016612, 5186.559142472932, 5464.480874316933, 5664.694831927683, 5684.981831449776, 5741.827745167651], "value": 4117.731076291535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.5932620091044, 714.7819914925948, 763.9006929099216, 777.5218240781913, 785.7917651822268, 14519.927400362996, 15169.165695886722, 15171.013884097525, 15176.31706573204, 15208.01147925384, 13294.468221271138, 13453.0, 13505.864941350583, 13617.863821361783, 13668.316584170801, 819.8647223208163, 836.8744688296744, 840.9831803363929, 852.7569642651846, 856.7815207122175, 831.264331067007, 834.6077343648476, 834.7120243515977, 835.9331253499705, 838.4927119092953, 875.6497401039603, 877.2411863737884, 881.3610132653806, 882.3073886998708, 884.0496466298722, 850.5747126436781, 855.4567849415604, 861.280830506531, 861.2980420956962, 864.6368525219423, 560.814931072745, 650.1125963060435, 651.0, 654.8002859127964, 655.5083687234574, 12375.504979800797, 12405.069619778462, 12538.561150359785, 12713.93643031788, 12722.300273484954, 10959.671209863716, 11378.715532111764, 11384.772304553946, 11586.362750048744, 11606.4196790161, 10041.397516148982, 10173.59305627774, 10386.844197337046, 10441.947790261076, 10500.78998420035, 3418.9729199099465, 3445.5176275321473, 3448.137965508623, 3578.3916734155255, 3580.4629305604108, 82516.0, 85275.57362213149, 87572.0, 92168.07831921663, 95234.52382738111, 7208.459365547605, 8686.826263474695, 8759.781005474786, 8844.0, 9125.680601178927, 15410.075395476084, 17886.982301680106, 18276.45170644883, 18502.167402466923, 18503.35238266654, 7381.630918454056, 7481.326680598663, 7594.924050759532, 7623.809404764815, 7721.845563088819, 4077.347624380085, 4154.979225103855, 4158.147579746144, 4165.166966606691, 4172.248995180897, 3225.548423220751, 3293.407186706416, 3311.0, 3322.4850148227065, 3358.4962255661844, 615.2371059885799, 619.7582942652369, 632.9936700632935, 633.8161933039366, 637.5409705012383, 457.0584595732801, 489.3491656097453, 512.2009664922732, 514.6423235851048, 558.7234319012115, 8977.236934860479, 8995.55022248885, 9103.726888193365, 9159.893224558256, 9422.104900034486, 593.7328202308978, 666.3835951744713, 678.7963610916705, 681.325487767107, 686.361683634225, 4334.241507736134, 4544.954550454519, 4549.431321084864, 4557.908841823145, 4599.724016559017, 99020.5048974765, 117247.0, 118126.8187318133, 123416.76583234232, 124775.37612312062, 58172.70913645489, 79637.0, 83822.0, 84806.0, 95233.0, 58959.115613265836, 70678.64660676663, 72844.63577682078, 72897.90653140207, 77065.61467192628, 8304.460210086325, 8444.155584441507, 9070.64831749271, 9760.219182465244, 9767.120959113714, 40614.79692601518, 53422.0, 55273.44726552683, 56558.58603534944, 57224.71387643035, 59946.70026649839, 73184.63407683141, 76809.84785228338, 77050.6147469259, 80624.0, 11881.762364752829, 12115.0, 12227.205231659913, 12268.141230114163, 12274.815877761663, 12724.427400766803, 12906.935465322613, 13157.342132893125, 13331.533396331082, 13374.13068150567, 12124.939375303067, 12461.626151215478, 12527.248365098123, 12693.174943628635, 12882.162659427107, 12364.81452778227, 12399.380030998595, 12422.06834487399, 12538.185017973803, 12966.675833104247, 10931.234813562975, 10983.670489885317, 11042.944785276022, 11047.834282485608, 11140.442977851237, 9464.384814987005, 11036.169250421275, 11128.625158374703, 11233.157513186381, 11435.771284574095, 10808.243422960317, 11047.834282485608, 11228.943855280671, 11254.7749045017, 11323.886761132284], "value": 6985.4645920874245, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 120.0, 120.0, 120.0, 148.0, 120.0, 176.0, 176.0, 140.0, 121.0], "value": 134.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 112.0, 148.0, 121.0, 118.0, 120.0, 111.0, 162.0, 131.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [239.0, 191.0, 218.0, 283.0, 237.0, 182.0, 252.0, 286.0, 191.0, 187.0], "value": 226.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 57.0, 82.0, 59.0, 58.0, 60.0, 60.0, 78.0, 83.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 86.0, 70.0, 68.0, 69.0, 89.0, 77.0, 83.0, 89.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 132.0, 89.0, 92.0, 136.0, 133.0, 90.0, 87.0, 89.0, 87.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 97.0, 96.0, 96.0, 90.0, 103.0, 154.0, 149.0, 117.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 122.0, 88.0, 87.0, 92.0, 77.0, 140.0, 144.0, 90.0, 90.0], "value": 102.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 56.0, 56.0, 59.0, 58.0, 59.0, 57.0, 70.0, 89.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 76.0, 85.0, 75.0, 72.0, 70.0, 70.0, 71.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 87.0, 74.0, 84.0, 99.0, 112.0, 82.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 152.0, 161.0, 158.0, 158.0, 148.0, 148.0, 152.0, 199.0, 165.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 175.0, 164.0, 169.0, 226.0, 219.0, 223.0, 181.0, 170.0, 172.0], "value": 186.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 81.0, 73.0, 68.0, 67.0, 67.0, 69.0, 68.0], "value": 69.5, "unit": "score"}]}]} 16:31:37 INFO - Return code: 0 16:31:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:37 ERROR - # TBPL SUCCESS # 16:31:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:31:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:31:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:31:37 INFO - 2016-11-22 16:31:37.821 screenresolution[1657:10588] starting screenresolution argv=screenresolution get 16:31:37 INFO - 2016-11-22 16:31:37.824 screenresolution[1657:10588] Display 0: 1600x1200x32@60 16:31:37 INFO - 2016-11-22 16:31:37.830 screenresolution[1658:10591] starting screenresolution argv=screenresolution list 16:31:37 INFO - Available Modes on Display 0 16:31:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:31:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:31:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:31:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:31:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:31:38 INFO - Intel Iris: 16:31:38 INFO - Chipset Model: Intel Iris 16:31:38 INFO - Type: GPU 16:31:38 INFO - Bus: Built-In 16:31:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:31:38 INFO - Vendor: Intel (0x8086) 16:31:38 INFO - Device ID: 0x0a2e 16:31:38 INFO - Revision ID: 0x0009 16:31:38 INFO - Displays: 16:31:38 INFO - Display: 16:31:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:31:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:31:38 INFO - Display Serial Number: 1600x1200 60 16:31:38 INFO - Main Display: Yes 16:31:38 INFO - Mirror: Off 16:31:38 INFO - Online: Yes 16:31:38 INFO - Rotation: Supported 16:31:38 INFO - Return code: 0 16:31:38 INFO - Running post-action listener: _resource_record_post_action 16:31:38 INFO - [mozharness: 2016-11-23 00:31:38.073798Z] Finished run-tests step (success) 16:31:38 INFO - Running post-run listener: _resource_record_post_run 16:31:38 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 37684224; Write bytes: 661967872; Read time: 242; Write time: 1935 16:31:38 INFO - TinderboxPrint: CPU usage
54.6% 16:31:38 INFO - TinderboxPrint: I/O read bytes / time
37,684,224 / 242 16:31:38 INFO - TinderboxPrint: I/O write bytes / time
661,967,872 / 1,935 16:31:38 INFO - TinderboxPrint: CPU idle
2,196.7 (45.4%) 16:31:38 INFO - TinderboxPrint: CPU system
985.0 (20.3%) 16:31:38 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 16:31:38 INFO - TinderboxPrint: Swap in / out
386,129,920 / 0 16:31:38 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 352991744; Write bytes: 345090048; Read time: 26874; Write time: 712 16:31:38 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 29350912; Write bytes: 309566464; Read time: 174; Write time: 1193 16:31:38 INFO - Running post-run listener: _upload_blobber_files 16:31:38 INFO - Blob upload gear active. 16:31:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:31:38 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:31:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:40 INFO - (blobuploader) - INFO - Done attempting. 16:31:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:31:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:41 INFO - (blobuploader) - INFO - Done attempting. 16:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:31:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:43 INFO - (blobuploader) - INFO - Done attempting. 16:31:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:31:43 INFO - Return code: 0 16:31:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:31:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:31:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef784a3d0562d153f4cedfc80a9e6537aa635a0cdb59c6eab909daf204705a119b69da61e8714112ce12bc9e92071de36ccd201dd00be662713e41b64d8e8c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a14ac036be75cfa6a05efcec8ec875563f2129a61a632240235baf63222eddabe2a50f3070844275cd3344f54173a0ce7fdc14959b122544e780d2e7180fc89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecc484f9c1d9f5dd067932c79b796cbc382c0bf0554aa8bdfac58ecd877416e5204fcbe9123db3bd6ef15b742cf9fd62ce8a378a44e6dabd3ea344b46fa45f67"} 16:31:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:31:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:31:43 INFO - Contents: 16:31:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef784a3d0562d153f4cedfc80a9e6537aa635a0cdb59c6eab909daf204705a119b69da61e8714112ce12bc9e92071de36ccd201dd00be662713e41b64d8e8c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a14ac036be75cfa6a05efcec8ec875563f2129a61a632240235baf63222eddabe2a50f3070844275cd3344f54173a0ce7fdc14959b122544e780d2e7180fc89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecc484f9c1d9f5dd067932c79b796cbc382c0bf0554aa8bdfac58ecd877416e5204fcbe9123db3bd6ef15b742cf9fd62ce8a378a44e6dabd3ea344b46fa45f67"} 16:31:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:31:43 INFO - Copying logs to upload dir... 16:31:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:31:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.469949 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-11-22 16:31:43.498943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 16:31:43.502449) ========= 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.FERxgMM9YC/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.RMSkRfohln/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef784a3d0562d153f4cedfc80a9e6537aa635a0cdb59c6eab909daf204705a119b69da61e8714112ce12bc9e92071de36ccd201dd00be662713e41b64d8e8c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a14ac036be75cfa6a05efcec8ec875563f2129a61a632240235baf63222eddabe2a50f3070844275cd3344f54173a0ce7fdc14959b122544e780d2e7180fc89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecc484f9c1d9f5dd067932c79b796cbc382c0bf0554aa8bdfac58ecd877416e5204fcbe9123db3bd6ef15b742cf9fd62ce8a378a44e6dabd3ea344b46fa45f67"} build_url:https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011038 build_url: 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ef784a3d0562d153f4cedfc80a9e6537aa635a0cdb59c6eab909daf204705a119b69da61e8714112ce12bc9e92071de36ccd201dd00be662713e41b64d8e8c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a14ac036be75cfa6a05efcec8ec875563f2129a61a632240235baf63222eddabe2a50f3070844275cd3344f54173a0ce7fdc14959b122544e780d2e7180fc89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecc484f9c1d9f5dd067932c79b796cbc382c0bf0554aa8bdfac58ecd877416e5204fcbe9123db3bd6ef15b742cf9fd62ce8a378a44e6dabd3ea344b46fa45f67"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 16:31:43.541130) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:31:43.541480) ========= 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.FERxgMM9YC/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.RMSkRfohln/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005110 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:31:43.592839) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 16:31:43.593318) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 16:31:43.593656) ========= ========= Total master_lag: 0.19 =========