builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0188 starttime: 1481043343.53 results: success (0) buildid: 20161206074238 builduid: 102ce60d1e0144dc88a7a6d94029fef9 revision: 65b9d6f30e5201f6ee0208956a29890c8c0f0283 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.534582) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.535093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.535401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.561805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.562201) ========= 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.OlRqCdBje3/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.8AIXJOeMPT/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-06 08:55:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2016-12-06 08:55:43 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095081 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.682057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.682396) ========= 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.OlRqCdBje3/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.8AIXJOeMPT/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.027794 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:43.731756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 08:55:43.732144) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 65b9d6f30e5201f6ee0208956a29890c8c0f0283 --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 65b9d6f30e5201f6ee0208956a29890c8c0f0283 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OlRqCdBje3/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.8AIXJOeMPT/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-06 08:55:43,822 truncating revision to first 12 chars 2016-12-06 08:55:43,823 Setting DEBUG logging. 2016-12-06 08:55:43,823 attempt 1/10 2016-12-06 08:55:43,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/65b9d6f30e52?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-06 08:55:45,035 unpacking tar archive at: mozilla-aurora-65b9d6f30e52/testing/mozharness/ program finished with exit code 0 elapsedTime=1.474406 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 08:55:45.229621) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:45.230853) ========= script_repo_revision: 65b9d6f30e5201f6ee0208956a29890c8c0f0283 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:45.231302) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:45.231586) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 08:55:45.253177) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-06 08:55:45.253601) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OlRqCdBje3/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.8AIXJOeMPT/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:55:45 INFO - MultiFileLogger online at 20161206 08:55:45 in /builds/slave/test 08:55:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:55:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:55:45 INFO - {'append_to_log': False, 08:55:45 INFO - 'base_work_dir': '/builds/slave/test', 08:55:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:55:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:55:45 INFO - 'branch': 'Mozilla-Aurora', 08:55:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:55:45 INFO - 'config_files': ('talos/mac_config.py',), 08:55:45 INFO - 'default_actions': ('clobber', 08:55:45 INFO - 'read-buildbot-config', 08:55:45 INFO - 'download-and-extract', 08:55:45 INFO - 'populate-webroot', 08:55:45 INFO - 'create-virtualenv', 08:55:45 INFO - 'install', 08:55:45 INFO - 'run-tests'), 08:55:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:55:45 INFO - 'download_minidump_stackwalk': True, 08:55:45 INFO - 'download_symbols': 'ondemand', 08:55:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:55:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:55:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:55:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:55:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:55:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:55:45 INFO - 'installer_path': 'installer.exe', 08:55:45 INFO - 'log_level': 'info', 08:55:45 INFO - 'log_name': 'talos', 08:55:45 INFO - 'log_to_console': True, 08:55:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:55:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:55:45 INFO - 'opt_config_files': (), 08:55:45 INFO - 'pip_index': False, 08:55:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:55:45 INFO - 'cmd': ('bash', 08:55:45 INFO - '-c', 08:55:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:55:45 INFO - 'enabled': True, 08:55:45 INFO - 'halt_on_failure': False, 08:55:45 INFO - 'name': 'check_screen_resolution'},), 08:55:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:55:45 INFO - 'cmd': ('bash', 08:55:45 INFO - '-c', 08:55:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:55:45 INFO - 'enabled': True, 08:55:45 INFO - 'halt_on_failure': False, 08:55:45 INFO - 'name': 'check_screen_resolution'},), 08:55:45 INFO - 'run_cmd_checks_enabled': True, 08:55:45 INFO - 'sps_profile': False, 08:55:45 INFO - 'sps_profile_interval': 0, 08:55:45 INFO - 'suite': 'dromaeojs', 08:55:45 INFO - 'system_bits': '32', 08:55:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:55:45 INFO - 'title': 't-yosemite-r7-0188', 08:55:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:55:45 INFO - 'use_talos_json': True, 08:55:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:55:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:55:45 INFO - 'work_dir': 'build'} 08:55:45 INFO - [mozharness: 2016-12-06 16:55:45.452520Z] Running clobber step. 08:55:45 INFO - Running pre-action listener: _resource_record_pre_action 08:55:45 INFO - Running main action method: clobber 08:55:45 INFO - rmtree: /builds/slave/test/build 08:55:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:55:47 INFO - Running post-action listener: _resource_record_post_action 08:55:47 INFO - [mozharness: 2016-12-06 16:55:47.693539Z] Finished clobber step (success) 08:55:47 INFO - [mozharness: 2016-12-06 16:55:47.693849Z] Running read-buildbot-config step. 08:55:47 INFO - Running pre-action listener: _resource_record_pre_action 08:55:47 INFO - Running main action method: read_buildbot_config 08:55:47 INFO - Using buildbot properties: 08:55:47 INFO - { 08:55:47 INFO - "project": "", 08:55:47 INFO - "product": "firefox", 08:55:47 INFO - "script_repo_revision": "production", 08:55:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:55:47 INFO - "repository": "", 08:55:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:55:47 INFO - "buildid": "20161206074238", 08:55:47 INFO - "pgo_build": "False", 08:55:47 INFO - "basedir": "/builds/slave/test", 08:55:47 INFO - "buildnumber": 58, 08:55:47 INFO - "slavename": "t-yosemite-r7-0188", 08:55:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:55:47 INFO - "platform": "yosemite_r7", 08:55:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:55:47 INFO - "branch": "mozilla-aurora", 08:55:47 INFO - "repo_path": "releases/mozilla-aurora", 08:55:47 INFO - "revision": "65b9d6f30e5201f6ee0208956a29890c8c0f0283", 08:55:47 INFO - "stage_platform": "macosx64", 08:55:47 INFO - "builduid": "102ce60d1e0144dc88a7a6d94029fef9", 08:55:47 INFO - "slavebuilddir": "test" 08:55:47 INFO - } 08:55:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:55:47 INFO - Running post-action listener: _resource_record_post_action 08:55:47 INFO - [mozharness: 2016-12-06 16:55:47.699545Z] Finished read-buildbot-config step (success) 08:55:47 INFO - [mozharness: 2016-12-06 16:55:47.699732Z] Running download-and-extract step. 08:55:47 INFO - Running pre-action listener: _resource_record_pre_action 08:55:47 INFO - Running main action method: download_and_extract 08:55:47 INFO - mkdir: /builds/slave/test/build/tests 08:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:47 INFO - trying https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:55:47 INFO - Downloading https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/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 08:55:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/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 08:55:48 INFO - Downloaded 1199 bytes. 08:55:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:55:48 INFO - Using the following test package requirements: 08:55:48 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:55:48 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:55:48 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:55:48 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'jsshell-mac.zip'], 08:55:48 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:55:48 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:55:48 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:55:48 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:55:48 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:55:48 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:55:48 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:55:48 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:55:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:55:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:55:48 INFO - Fetch https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:55:49 INFO - Http code: 200 08:55:49 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 08:55:49 INFO - x-amz-cf-id: d_wlVn0HY22LpHyrJvc6Sz0a-6MWKmkJXbd1aB1KPtLMTG7N4wAbyQ== 08:55:49 INFO - x-amz-version-id: 7ITFIrxeXyBLakGrU0xeQI0fOlo73ixX 08:55:49 INFO - Expected file size: 16281898 08:55:49 INFO - Obtained file size: 16281898 08:55:56 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 08:55:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:55:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:55:56 INFO - Fetch https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:55:57 INFO - Http code: 200 08:55:57 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 08:55:57 INFO - x-amz-cf-id: f9MI7oz3BTTpv1D3_Q_0JcuRGWhtDUZy6MZbYx2cgqtjfV_SlYGn5Q== 08:55:57 INFO - x-amz-version-id: 7ITFIrxeXyBLakGrU0xeQI0fOlo73ixX 08:55:57 INFO - Expected file size: 16281898 08:55:57 INFO - Obtained file size: 16281898 08:56:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:56:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:56:03 INFO - Fetch https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:56:04 INFO - Http code: 200 08:56:04 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 08:56:04 INFO - x-amz-cf-id: dc6gjFUXbfk55JDkFfDFw79fD1YHDyryXWALeGTJw7SAYIJO4M5UXA== 08:56:04 INFO - x-amz-version-id: GKmM6YmczgAojDPKnKoAZR2J58pLEGNg 08:56:04 INFO - Expected file size: 14409038 08:56:04 INFO - Obtained file size: 14409038 08:56: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')]} 08:56:04 INFO - trying https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:56:04 INFO - Downloading https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:56:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/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 08:56:07 INFO - Downloaded 109761115 bytes. 08:56:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:56:07 INFO - mkdir: /builds/slave/test/properties 08:56:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:56:07 INFO - Writing to file /builds/slave/test/properties/build_url 08:56:07 INFO - Contents: 08:56:07 INFO - build_url:https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:56:08 INFO - Running post-action listener: _resource_record_post_action 08:56:08 INFO - Running post-action listener: set_extra_try_arguments 08:56:08 INFO - [mozharness: 2016-12-06 16:56:08.612583Z] Finished download-and-extract step (success) 08:56:08 INFO - [mozharness: 2016-12-06 16:56:08.612677Z] Running populate-webroot step. 08:56:08 INFO - Running pre-action listener: _resource_record_pre_action 08:56:08 INFO - Running main action method: populate_webroot 08:56:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:56:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:56:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:56:08 INFO - '--tppagecycles', 08:56:08 INFO - '7'], 08:56:08 INFO - 'tests': ['tsvgm']}}, 08:56:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:56:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:56:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:56:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:56:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['damp', 'tps']}, 08:56:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'tests': ['damp', 'tps']}, 08:56:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['dromaeo_dom']}, 08:56:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:56:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['basic_compositor_video']}, 08:56:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:56:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['a11yr', 08:56:08 INFO - 'ts_paint', 08:56:08 INFO - 'tpaint', 08:56:08 INFO - 'sessionrestore', 08:56:08 INFO - 'sessionrestore_no_auto_restore', 08:56:08 INFO - 'tabpaint']}, 08:56:08 INFO - 'other-e10s': {'tests': ['a11yr', 08:56:08 INFO - 'ts_paint', 08:56:08 INFO - 'tpaint', 08:56:08 INFO - 'sessionrestore', 08:56:08 INFO - 'sessionrestore_no_auto_restore', 08:56:08 INFO - 'tabpaint']}, 08:56:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:56:08 INFO - 'ts_paint', 08:56:08 INFO - 'tpaint', 08:56:08 INFO - 'sessionrestore', 08:56:08 INFO - 'sessionrestore_no_auto_restore', 08:56:08 INFO - 'tabpaint']}, 08:56:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:56:08 INFO - 'ts_paint', 08:56:08 INFO - 'tpaint', 08:56:08 INFO - 'sessionrestore', 08:56:08 INFO - 'sessionrestore_no_auto_restore', 08:56:08 INFO - 'tabpaint']}, 08:56:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['a11yr', 08:56:08 INFO - 'ts_paint', 08:56:08 INFO - 'tpaint', 08:56:08 INFO - 'sessionrestore', 08:56:08 INFO - 'sessionrestore_no_auto_restore', 08:56:08 INFO - 'tabpaint']}, 08:56:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['a11yr', 08:56:08 INFO - 'ts_paint', 08:56:08 INFO - 'tpaint', 08:56:08 INFO - 'sessionrestore', 08:56:08 INFO - 'sessionrestore_no_auto_restore', 08:56:08 INFO - 'tabpaint']}, 08:56:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['tsvgx', 08:56:08 INFO - 'tsvgr_opacity', 08:56:08 INFO - 'tart', 08:56:08 INFO - 'tscrollx', 08:56:08 INFO - 'cart']}, 08:56:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:56:08 INFO - 'tsvgr_opacity', 08:56:08 INFO - 'tart', 08:56:08 INFO - 'tscrollx', 08:56:08 INFO - 'cart']}, 08:56:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'talos_options': ['--disable-e10s'], 08:56:08 INFO - 'tests': ['tp5o']}, 08:56:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'tests': ['tp5o']}, 08:56:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'talos_options': ['--disable-e10s', 08:56:08 INFO - '--xperf_path', 08:56:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:08 INFO - 'tests': ['tp5n']}, 08:56:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:08 INFO - 'talos_options': ['--xperf_path', 08:56:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:08 INFO - 'tests': ['tp5n']}}, 08:56:08 INFO - 'talos.zip': {'path': '', 08:56:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:56:08 INFO - Running post-action listener: _resource_record_post_action 08:56:08 INFO - [mozharness: 2016-12-06 16:56:08.631600Z] Finished populate-webroot step (success) 08:56:08 INFO - [mozharness: 2016-12-06 16:56:08.631727Z] Running create-virtualenv step. 08:56:08 INFO - Running pre-action listener: _resource_record_pre_action 08:56:08 INFO - Running main action method: create_virtualenv 08:56:08 INFO - Creating virtualenv /builds/slave/test/build/venv 08:56:08 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 08:56:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:56:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:56:08 INFO - Using real prefix '/tools/python27' 08:56:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:56:09 INFO - Installing distribute.............................................................................................................................................................................................done. 08:56:12 INFO - Installing pip.................done. 08:56:12 INFO - Return code: 0 08:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:56:12 INFO - Reading from file tmpfile_stdout 08:56:12 INFO - Output received: 08:56:12 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) 08:56:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:12 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:12 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 08:56:12 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 08:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:12 INFO - 'HOME': '/Users/cltbld', 08:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:12 INFO - 'LOGNAME': 'cltbld', 08:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:12 INFO - 'MOZ_NO_REMOTE': '1', 08:56:12 INFO - 'NO_EM_RESTART': '1', 08:56:12 INFO - 'PAGER': '/bin/cat', 08:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:12 INFO - 'PWD': '/builds/slave/test', 08:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:12 INFO - 'SHELL': '/bin/bash', 08:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:12 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:12 INFO - 'USER': 'cltbld', 08:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:12 INFO - 'XPC_FLAGS': '0x0', 08:56:12 INFO - 'XPC_SERVICE_NAME': '0', 08:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:12 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 08:56:12 INFO - Cleaning up... 08:56:12 INFO - Return code: 0 08:56:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:12 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:12 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 08:56:12 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 08:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:12 INFO - 'HOME': '/Users/cltbld', 08:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:12 INFO - 'LOGNAME': 'cltbld', 08:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:12 INFO - 'MOZ_NO_REMOTE': '1', 08:56:12 INFO - 'NO_EM_RESTART': '1', 08:56:12 INFO - 'PAGER': '/bin/cat', 08:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:12 INFO - 'PWD': '/builds/slave/test', 08:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:12 INFO - 'SHELL': '/bin/bash', 08:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:12 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:12 INFO - 'USER': 'cltbld', 08:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:12 INFO - 'XPC_FLAGS': '0x0', 08:56:12 INFO - 'XPC_SERVICE_NAME': '0', 08:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:13 INFO - Downloading/unpacking psutil>=3.1.1 08:56:13 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 08:56:13 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 08:56:13 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 08:56:13 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 08:56:13 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 08:56:13 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 08:56:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:56:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:16 INFO - Installing collected packages: psutil 08:56:16 INFO - Running setup.py install for psutil 08:56:16 INFO - building 'psutil._psutil_osx' extension 08:56:16 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 08:56:17 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 08:56:17 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 08:56:17 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 08:56:17 INFO - building 'psutil._psutil_posix' extension 08:56:17 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 08:56:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:56:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:56:17 INFO - ^ 08:56:17 INFO - 1 warning generated. 08:56:17 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 08:56:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:17 INFO - Successfully installed psutil 08:56:17 INFO - Cleaning up... 08:56:17 INFO - Return code: 0 08:56:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:56:17 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')]} 08:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:17 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')]} 08:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:17 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:17 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 08:56:17 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 08:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:17 INFO - 'HOME': '/Users/cltbld', 08:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:17 INFO - 'LOGNAME': 'cltbld', 08:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:17 INFO - 'MOZ_NO_REMOTE': '1', 08:56:17 INFO - 'NO_EM_RESTART': '1', 08:56:17 INFO - 'PAGER': '/bin/cat', 08:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:17 INFO - 'PWD': '/builds/slave/test', 08:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:17 INFO - 'SHELL': '/bin/bash', 08:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:17 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:17 INFO - 'USER': 'cltbld', 08:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:17 INFO - 'XPC_FLAGS': '0x0', 08:56:17 INFO - 'XPC_SERVICE_NAME': '0', 08:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:56:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:56:20 INFO - Installing collected packages: mozsystemmonitor 08:56:20 INFO - Running setup.py install for mozsystemmonitor 08:56:20 INFO - Successfully installed mozsystemmonitor 08:56:20 INFO - Cleaning up... 08:56:20 INFO - Return code: 0 08:56:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:56:20 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')]} 08:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:20 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')]} 08:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:20 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:20 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 08:56:20 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 08:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:20 INFO - 'HOME': '/Users/cltbld', 08:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:20 INFO - 'LOGNAME': 'cltbld', 08:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:20 INFO - 'MOZ_NO_REMOTE': '1', 08:56:20 INFO - 'NO_EM_RESTART': '1', 08:56:20 INFO - 'PAGER': '/bin/cat', 08:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:20 INFO - 'PWD': '/builds/slave/test', 08:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:20 INFO - 'SHELL': '/bin/bash', 08:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:20 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:20 INFO - 'USER': 'cltbld', 08:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:20 INFO - 'XPC_FLAGS': '0x0', 08:56:20 INFO - 'XPC_SERVICE_NAME': '0', 08:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:56:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:56: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 08:56: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 08:56: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 08:56: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 08:56: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 08:56: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 08:56:24 INFO - Downloading functools32-3.2.3-2.tar.gz 08:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:56:24 INFO - warning: no files found matching '*.txt' 08:56:24 INFO - no previously-included directories found matching 'build' 08:56:24 INFO - no previously-included directories found matching 'dist' 08:56:24 INFO - no previously-included directories found matching '.git*' 08:56:24 INFO - Installing collected packages: jsonschema, functools32 08:56:24 INFO - Running setup.py install for functools32 08:56:24 INFO - warning: no files found matching '*.txt' 08:56:24 INFO - no previously-included directories found matching 'build' 08:56:24 INFO - no previously-included directories found matching 'dist' 08:56:24 INFO - no previously-included directories found matching '.git*' 08:56:24 INFO - Successfully installed jsonschema functools32 08:56:24 INFO - Cleaning up... 08:56:24 INFO - Return code: 0 08:56:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:24 INFO - 'HOME': '/Users/cltbld', 08:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:24 INFO - 'LOGNAME': 'cltbld', 08:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:24 INFO - 'MOZ_NO_REMOTE': '1', 08:56:24 INFO - 'NO_EM_RESTART': '1', 08:56:24 INFO - 'PAGER': '/bin/cat', 08:56:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:24 INFO - 'PWD': '/builds/slave/test', 08:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:24 INFO - 'SHELL': '/bin/bash', 08:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:24 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:24 INFO - 'USER': 'cltbld', 08:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:24 INFO - 'XPC_FLAGS': '0x0', 08:56:24 INFO - 'XPC_SERVICE_NAME': '0', 08:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:56:24 INFO - Cleaning up... 08:56:24 INFO - Return code: 0 08:56:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:24 INFO - 'HOME': '/Users/cltbld', 08:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:24 INFO - 'LOGNAME': 'cltbld', 08:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:24 INFO - 'MOZ_NO_REMOTE': '1', 08:56:24 INFO - 'NO_EM_RESTART': '1', 08:56:24 INFO - 'PAGER': '/bin/cat', 08:56:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:24 INFO - 'PWD': '/builds/slave/test', 08:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:24 INFO - 'SHELL': '/bin/bash', 08:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:24 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:24 INFO - 'USER': 'cltbld', 08:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:24 INFO - 'XPC_FLAGS': '0x0', 08:56:24 INFO - 'XPC_SERVICE_NAME': '0', 08:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:25 INFO - Downloading/unpacking blobuploader==1.2.4 08:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:27 INFO - Downloading blobuploader-1.2.4.tar.gz 08:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:56:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:56:27 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 08:56:27 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 08:56:27 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 08:56:27 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 08:56:27 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 08:56:27 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 08:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:56:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:56:28 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 08:56:28 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 08:56:28 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 08:56:28 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 08:56:28 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 08:56:28 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 08:56:28 INFO - Downloading docopt-0.6.1.tar.gz 08:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:56:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:56:29 INFO - Running setup.py install for blobuploader 08:56:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:56:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:56:29 INFO - Running setup.py install for requests 08:56:29 INFO - Running setup.py install for docopt 08:56:29 INFO - Successfully installed blobuploader requests docopt 08:56:29 INFO - Cleaning up... 08:56:29 INFO - Return code: 0 08:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:29 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:29 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 08:56:29 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 08:56:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:29 INFO - 'HOME': '/Users/cltbld', 08:56:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:29 INFO - 'LOGNAME': 'cltbld', 08:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:29 INFO - 'MOZ_NO_REMOTE': '1', 08:56:29 INFO - 'NO_EM_RESTART': '1', 08:56:29 INFO - 'PAGER': '/bin/cat', 08:56:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:29 INFO - 'PWD': '/builds/slave/test', 08:56:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:29 INFO - 'SHELL': '/bin/bash', 08:56:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:29 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:29 INFO - 'USER': 'cltbld', 08:56:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:29 INFO - 'XPC_FLAGS': '0x0', 08:56:29 INFO - 'XPC_SERVICE_NAME': '0', 08:56:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-xB6ucU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-LpGdz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Ob8kgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-RpUpun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-JohpTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-N4PQHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-OyeXT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-vHDcHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:56:30 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-lXYbu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-lFjsa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-4Hq2mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Lfbzpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZCQTLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7Ek2Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-U_mTIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-IPctdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:56:31 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-S6bgkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:56:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:56:31 INFO - Running setup.py install for manifestparser 08:56:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:56:32 INFO - Running setup.py install for mozcrash 08:56:32 INFO - Running setup.py install for mozdebug 08:56:32 INFO - Running setup.py install for mozdevice 08:56:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:56:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:56:32 INFO - Running setup.py install for mozfile 08:56:32 INFO - Running setup.py install for mozhttpd 08:56:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:56:32 INFO - Running setup.py install for mozinfo 08:56:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:56:32 INFO - Running setup.py install for mozInstall 08:56:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Running setup.py install for mozleak 08:56:33 INFO - Running setup.py install for mozlog 08:56:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Running setup.py install for moznetwork 08:56:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Running setup.py install for mozprocess 08:56:33 INFO - Running setup.py install for mozprofile 08:56:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:56:33 INFO - Running setup.py install for mozrunner 08:56:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:56:34 INFO - Running setup.py install for mozscreenshot 08:56:34 INFO - Running setup.py install for moztest 08:56:34 INFO - Running setup.py install for mozversion 08:56:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:56:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:56:34 INFO - Cleaning up... 08:56:34 INFO - Return code: 0 08:56:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:56:34 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')]} 08:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:34 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')]} 08:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:34 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:34 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 08:56:34 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 08:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:34 INFO - 'HOME': '/Users/cltbld', 08:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:34 INFO - 'LOGNAME': 'cltbld', 08:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:34 INFO - 'MOZ_NO_REMOTE': '1', 08:56:34 INFO - 'NO_EM_RESTART': '1', 08:56:34 INFO - 'PAGER': '/bin/cat', 08:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:34 INFO - 'PWD': '/builds/slave/test', 08:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:34 INFO - 'SHELL': '/bin/bash', 08:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:34 INFO - 'USER': 'cltbld', 08:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:34 INFO - 'XPC_FLAGS': '0x0', 08:56:34 INFO - 'XPC_SERVICE_NAME': '0', 08:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:56:34 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-R7KMCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:56:34 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)) 08:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:56:34 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-AS9veY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-C9YNQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-yaALJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-PKmXXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-c7tO9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-WVv_FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-G4cGKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-l9OPon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-qRbCbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1WckN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:56:35 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)) 08:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:56:35 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-GP9zCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:56:36 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)) 08:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:56:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Sk7Cba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:56:36 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)) 08:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:56:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-eiLQt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:56:36 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)) 08:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-SukxO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:36 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)) 08:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:56:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-PLWKr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:56:36 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)) 08:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:56:36 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-4SMSxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:56:36 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)) 08:56:36 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)) 08:56:36 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)) 08:56:36 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)) 08:56:36 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)) 08:56:36 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)) 08:56:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:56:36 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 08:56:36 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 08:56:36 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 08:56:36 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 08:56:36 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 08:56:36 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 08:56:39 INFO - Downloading blessings-1.6.tar.gz 08:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:56:39 INFO - Installing collected packages: blessings 08:56:39 INFO - Running setup.py install for blessings 08:56:39 INFO - Successfully installed blessings 08:56:39 INFO - Cleaning up... 08:56:39 INFO - Return code: 0 08:56:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:56:39 INFO - Reading from file tmpfile_stdout 08:56:39 INFO - Current package versions: 08:56:39 INFO - blessings == 1.6 08:56:39 INFO - blobuploader == 1.2.4 08:56:39 INFO - docopt == 0.6.1 08:56:39 INFO - functools32 == 3.2.3-2 08:56:39 INFO - jsonschema == 2.5.1 08:56:39 INFO - manifestparser == 1.1 08:56:39 INFO - mozInstall == 1.12 08:56:39 INFO - mozcrash == 1.0 08:56:39 INFO - mozdebug == 0.1 08:56:39 INFO - mozdevice == 0.48 08:56:39 INFO - mozfile == 1.2 08:56:39 INFO - mozhttpd == 0.7 08:56:39 INFO - mozinfo == 0.9 08:56:39 INFO - mozleak == 0.1 08:56:39 INFO - mozlog == 3.4 08:56:39 INFO - moznetwork == 0.27 08:56:39 INFO - mozprocess == 0.23 08:56:39 INFO - mozprofile == 0.28 08:56:39 INFO - mozrunner == 6.13 08:56:39 INFO - mozscreenshot == 0.1 08:56:39 INFO - mozsystemmonitor == 0.3 08:56:39 INFO - moztest == 0.7 08:56:39 INFO - mozversion == 1.4 08:56:39 INFO - psutil == 3.1.1 08:56:39 INFO - requests == 1.2.3 08:56:39 INFO - wsgiref == 0.1.2 08:56:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:56:39 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')]} 08:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:40 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')]} 08:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:40 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 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:40 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 08:56:40 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 08:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:40 INFO - 'HOME': '/Users/cltbld', 08:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:40 INFO - 'LOGNAME': 'cltbld', 08:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:40 INFO - 'MOZ_NO_REMOTE': '1', 08:56:40 INFO - 'NO_EM_RESTART': '1', 08:56:40 INFO - 'PAGER': '/bin/cat', 08:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:40 INFO - 'PWD': '/builds/slave/test', 08:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:40 INFO - 'SHELL': '/bin/bash', 08:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:40 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:40 INFO - 'USER': 'cltbld', 08:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:40 INFO - 'XPC_FLAGS': '0x0', 08:56:40 INFO - 'XPC_SERVICE_NAME': '0', 08:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 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)) 08:56:40 INFO - Cleaning up... 08:56:40 INFO - Return code: 0 08:56:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:56:40 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')]} 08:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:40 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')]} 08:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d0c5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe938c8a940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, '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.OlRqCdBje3/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.8AIXJOeMPT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:56:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:41 INFO - 'HOME': '/Users/cltbld', 08:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:41 INFO - 'LOGNAME': 'cltbld', 08:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:41 INFO - 'MOZ_NO_REMOTE': '1', 08:56:41 INFO - 'NO_EM_RESTART': '1', 08:56:41 INFO - 'PAGER': '/bin/cat', 08:56:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:41 INFO - 'PWD': '/builds/slave/test', 08:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:41 INFO - 'SHELL': '/bin/bash', 08:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:56:41 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:41 INFO - 'USER': 'cltbld', 08:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:41 INFO - 'XPC_FLAGS': '0x0', 08:56:41 INFO - 'XPC_SERVICE_NAME': '0', 08:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:56:41 INFO - Cleaning up... 08:56:41 INFO - Return code: 0 08:56:41 INFO - Running post-action listener: _resource_record_post_action 08:56:41 INFO - Running post-action listener: _start_resource_monitoring 08:56:41 INFO - Starting resource monitoring. 08:56:41 INFO - [mozharness: 2016-12-06 16:56:41.646889Z] Finished create-virtualenv step (success) 08:56:41 INFO - [mozharness: 2016-12-06 16:56:41.647882Z] Running install step. 08:56:41 INFO - Running pre-action listener: _resource_record_pre_action 08:56:41 INFO - Running main action method: install 08:56:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:56:41 INFO - Reading from file tmpfile_stdout 08:56:41 INFO - Detecting whether we're running mozinstall >=1.0... 08:56:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:56:42 INFO - Reading from file tmpfile_stdout 08:56:42 INFO - Output received: 08:56:42 INFO - Usage: mozinstall [options] installer 08:56:42 INFO - Options: 08:56:42 INFO - -h, --help show this help message and exit 08:56:42 INFO - -d DEST, --destination=DEST 08:56:42 INFO - Directory to install application into. [default: 08:56:42 INFO - "/builds/slave/test"] 08:56:42 INFO - --app=APP Application being installed. [default: firefox] 08:56:42 INFO - mkdir: /builds/slave/test/build/application 08:56:42 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'] 08:56:42 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 08:57:13 INFO - Reading from file tmpfile_stdout 08:57:13 INFO - Output received: 08:57:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:57:13 INFO - Running post-action listener: _resource_record_post_action 08:57:13 INFO - [mozharness: 2016-12-06 16:57:13.128789Z] Finished install step (success) 08:57:13 INFO - [mozharness: 2016-12-06 16:57:13.128948Z] Running run-tests step. 08:57:13 INFO - Running pre-action listener: _resource_record_pre_action 08:57:13 INFO - Running main action method: run_tests 08:57:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:57:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:57:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:57:13 INFO - 2016-12-06 08:57:13.143 screenresolution[1622:7295] starting screenresolution argv=screenresolution get 08:57:13 INFO - 2016-12-06 08:57:13.146 screenresolution[1622:7295] Display 0: 1600x1200x32@60 08:57:13 INFO - 2016-12-06 08:57:13.152 screenresolution[1623:7298] starting screenresolution argv=screenresolution list 08:57:13 INFO - Available Modes on Display 0 08:57:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:57:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:57:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:57:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:57:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:57:13 INFO - Intel Iris: 08:57:13 INFO - Chipset Model: Intel Iris 08:57:13 INFO - Type: GPU 08:57:13 INFO - Bus: Built-In 08:57:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:57:13 INFO - Vendor: Intel (0x8086) 08:57:13 INFO - Device ID: 0x0a2e 08:57:13 INFO - Revision ID: 0x0009 08:57:13 INFO - Displays: 08:57:13 INFO - Display: 08:57:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:57:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:57:13 INFO - Display Serial Number: 1600x1200 60 08:57:13 INFO - Main Display: Yes 08:57:13 INFO - Mirror: Off 08:57:13 INFO - Online: Yes 08:57:13 INFO - Rotation: Supported 08:57:13 INFO - Return code: 0 08:57:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:57:13 INFO - Python 2.7.3 08:57:13 INFO - Return code: 0 08:57:13 INFO - grabbing minidump binary from tooltool 08:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:13 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 0x10ced9d30>, '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 0x10cd3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ced1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:57:13 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 08:57:13 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 08:57:13 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 08:57:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:57:13 INFO - Return code: 0 08:57:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:57:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:57:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:57:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:57:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:57:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:57:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OlRqCdBje3/Render', 08:57:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:13 INFO - 'HOME': '/Users/cltbld', 08:57:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:13 INFO - 'LOGNAME': 'cltbld', 08:57:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:57:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:13 INFO - 'MOZ_NO_REMOTE': '1', 08:57:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:57:13 INFO - 'NO_EM_RESTART': '1', 08:57:13 INFO - 'PAGER': '/bin/cat', 08:57:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:13 INFO - 'PWD': '/builds/slave/test', 08:57:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:57:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:13 INFO - 'SHELL': '/bin/bash', 08:57:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AIXJOeMPT/Listeners', 08:57:13 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:57:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:13 INFO - 'USER': 'cltbld', 08:57:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:13 INFO - 'XPC_FLAGS': '0x0', 08:57:13 INFO - 'XPC_SERVICE_NAME': '0', 08:57:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:57:13 INFO - mozversion application_buildid: 20161206074238 08:57:13 INFO - mozversion application_changeset: 65b9d6f30e5201f6ee0208956a29890c8c0f0283 08:57:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:57:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:57:13 INFO - mozversion application_name: Firefox 08:57:13 INFO - mozversion application_remotingname: firefox-dev 08:57:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:57:13 INFO - mozversion application_vendor: Mozilla 08:57:13 INFO - mozversion application_version: 52.0a2 08:57:13 INFO - mozversion platform_buildid: 20161206074238 08:57:13 INFO - mozversion platform_changeset: 65b9d6f30e5201f6ee0208956a29890c8c0f0283 08:57:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:57:13 INFO - mozversion platform_version: 52.0a2 08:57:13 INFO - using testdate: 1481043433 08:57:13 INFO - actual date: 1481043433 08:57:13 INFO - starting webserver on 'localhost:49236' 08:57:13 INFO - SUITE-START | Running 2 tests 08:57:13 INFO - TEST-START | dromaeo_css 08:57:13 INFO - operating with platform_type : mac_ 08:57:13 INFO - Initialising browser for dromaeo_css test... 08:57:13 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpZKAail/profile http://localhost:49236/getInfo.html) 08:57:16 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 08:57:16 INFO - PROCESS | 1633 | colorDepth:24 08:57:16 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 08:57:16 INFO - PROCESS | 1633 | __metrics 08:57:16 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:57:16 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:57:17 INFO - TEST-INFO | 1633: exit 0 08:57:17 INFO - Browser initialized. 08:57:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:57:17 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpZKAail/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:29 INFO - PROCESS | 1635 | RSS: Main: 222842880 08:57:29 INFO - PROCESS | 1635 | 08:58:20 INFO - PROCESS | 1635 | 1481043500709 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] 09:00:34 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:00:37 INFO - PROCESS | 1635 | RSS: Main: 435974144 09:00:37 INFO - PROCESS | 1635 | 09:03:39 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:03:40 INFO - PROCESS | 1635 | RSS: Main: 348815360 09:03:40 INFO - PROCESS | 1635 | 09:06:42 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:06:43 INFO - PROCESS | 1635 | RSS: Main: 330018816 09:06:43 INFO - PROCESS | 1635 | 09:09:45 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:09:46 INFO - PROCESS | 1635 | RSS: Main: 330260480 09:09:46 INFO - PROCESS | 1635 | 09:12:49 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:12:50 INFO - PROCESS | 1635 | RSS: Main: 311123968 09:12:50 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:15:53 INFO - PROCESS | 1635 | RSS: Main: 593235968 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | __start_tp_report 09:15:53 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 09:15:53 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 09:15:53 INFO - PROCESS | 1635 | |i|pagename|runs| 09:15:53 INFO - PROCESS | 1635 | |0;dojo.html;3362.932741345173;3378.155461134717;3395.3379091077236;3411.710004649605;3450.689437950584;5178.782985998288;5732.0255556555385;5800.390958949313;5821.738021789024;5844.21103151075;5355.410904800468;5777.480026797587;5790.594658373915;5812.041013232812;5815.709214539277;5215.765290561915;5221.791128354861;5258.55302299305;5263.763130659129;5286.735663216824;5194.194899790519;5204.063268611667;5231.633785635007;5236.764345604457;5305.124654432014;5270.446603106676;5324.787008519655;5328.733563321838;5343.358796944372;5378.300820893279;5297.17893726473;5307.389650190239;5307.495787900143;5322.361316642027;5380.784768609251;5072;5169.844904652867;5266.683998960075;5272.051030814452;5285.524302812765;5702.714864256812;5722.027255366597;5727.856803579903;5731.541476681856;5746.138079288098;6135.796305541688;6191.195144631214;6192.473639740628;6217.906731399033;6313.684315784239;6363.459105975998;6365.777197798055;6374.521910856658;6392.6484043377595;6421.197350331208;5905.6751878646655;5921.822345329647;5927.525797936155;5934.554908381889;5936.881262374728;6240.687965601793;6262.812115636538;6280.780172694025;6304.905426418607;6314.55798094129;6232.719527621268;6241.594296370721;6245.344238854946;6248.187735594343;6253.906191407133;5285.179256244892;6308.968455157695;6330.8417289567205;6347.301796802348;6352.6823658816875;6079.118527813443;6109.480694140959;6153.630782152995;6194.59735117225;6281.842953926097;6251.531135164881;6324.494040476751;6327.557071004985;6338.809835704937;6341.936580634227;4870.342503762017;4978.029284289529;4980.576650984707;4981.9252711209365;5023.572996295283;4420;4509.706869053501;4513.887152821206;4527.864164075083;4530.048689775184;4892.241702536113;5756.884862302731;5819.214406055128;5829.5044921181325;5861.17943487912;6313.621182729051;6317.4946004319545;6345.1116843641;6356.4279214870885;6363.204599425072;5832.562557808096;5843.5325173985975;5860.589758716934;5883.117532370093;5904.232449781587;5314.681119132864;5349.946500535023;5354.464553544614;5363.329583802025;5401.972990134946;6222.688865556795;6352.142460767737;6370.90443643355;6370.968145159244;6392.137061496825;6332.461740752087;6332.873342533216;6353.269374021862;6381.521385895983;6382.840428989312;4864.929715462734;6238.376162383725;6269.404406581368;6338.207724034496;6408.00675895237;4919.581835544019;5210.817621383233;6167.6916154193;6273.843153921189;6294.181756371826;6205.720742566505;6356.745730170742;6358.109864618865;6358.936410635834;6393.936060639334;5921.526277897672;6003.189569408074;6084.239470066242;6151.077338399097;6234.501239900706;6107.961646520013;6124.112003759341;6124.5406594506485;6135.84660383494;6144.662043587646;5911.586188966731;5936.406359364029;5951.702414879325;5951.702414879325;5968.403159683997;6051.304100028553;6113.841195312599;6125.387461253839;6141.969290153521;6144.477719393902;5920.911186332295;5945.227120474311;6001.399860013964;6018.247719035121;6073.5748497604745 09:15:53 INFO - PROCESS | 1635 | |1;ext.html;465.67402818027387;482.7658585585991;516.326194316417;525.1649876696054;538.571835390864;38267.61732382675;39709.808705738804;39711.20577588447;39792.60207397925;39841.80079099608;41847.58152418475;42326.7883660582;42348.941276468104;42364;42400;9336.346455748084;9483.81032379352;9499.810003799954;9508.239340852715;9518.238540916711;9345.43927364357;9458.62165513382;9475.763105922373;9485;9512.143907048365;9317.161455469006;9469.10043545862;9474.810503789955;9504.429734215935;9525.618975240983;9417.434953902753;9470.668526601605;9482.952585237064;9486.241100711926;9487.335886487948;9350.298727595458;9447.90552094477;9450.810983780286;9454.810903781887;9477.957424683349;39331;39608;39642.40536391956;39659.60340396588;39676;3167.4615315396436;3284.770066095374;3290.3090351026312;3457.7233821294235;3689.6494832990825;28905.710942890513;29100;29876.55185172224;29943.850280748673;30450.781968721225;3144.5754823099046;3157.179133425303;3232.8060316381093;3247.1232767152974;3268.6271765858396;477349;480470;480932;482305;482382.17617823725;58242;58524;58562.70718646465;58576;58586.7070664644;34507.13732156666;34968.776092837135;35208.64791352105;35909.64090359115;62866.37133628605;15969.041857488588;16086.597835053983;16134.515964521082;16155.111468869325;16160.676786464204;12196.695082623006;12278.938605306916;12285.38573071343;12319.384030798425;12321.630351089481;18512.814871851377;18612.720809187602;18616.06919654012;18619.534511637317;18703.877767334005;7029.5430796998035;7079.92920070803;7509.774706758806;7613.200613935568;7687.692492300357;818.7461886815106;841.7264389073575;855.9871601925976;925.2135684668112;1039.9964034706545;23856.990764812064;24628.883233147288;29716.851415743215;30242.84878575593;31967.680323196935;1023.4371095897227;1052.1267348101057;1054.99472502637;1070.4487189097622;1100.9394483303495;14738.705225895637;15278.770818437732;15285.770713439308;15304.540863774104;15624.3750249991;548019;683687;688231;688628;689211;56164;56685.43314566884;56710.14934775982;56840.147397789064;56861.57846053799;1222.8349172861729;1262.72851336963;1264.7723409786327;1333.053531992277;1358.083293776677;29489.410211796072;30604.387912242077;30609.081727548208;30993.53509697311;31236;49432.50567494279;49957;50027.24959125543;50118.74940625273;50130;560050;565723;565893;566348;568151;22158.56062685573;23533.76466235316;24525.264242072768;26071.478570428866;26184.86907565526;43542.78228608837;44345;44534.77732611316;44966.77516612396;44984.550154499346;37214.44178337273;37382.2523549522;37396.252074958895;37583.06042348854;37644.81177594094;37069.997605554854;37502.81248593739;37851.62148378481;38015.61984380231;38613.2277354457;36066.8196659015;36581;37289.25421491501;37352;38362.42456363101;32356.676433235367;32614;34062.829685851415;34727.65272347345;34780.13049673779;25549;25783.226503204933;25808.61287080733;25959.22122336333;26327.07855225058 09:15:53 INFO - PROCESS | 1635 | |2;jquery.html;352.22861932368096;365.11641826779197;375.67128762332976;379.8328735356444;401.4520179954361;14685.33915973781;14855.480058197965;15636.061836289817;19789.50526236845;20664.276750313744;19079.427617171506;19814.60370792583;20404.693929591067;20423.489412764688;20608.587828243428;5522.144067669507;5792.247007888989;5793.449622285876;5807.0998995155815;5810;4651.581357677808;6550.574212676184;6636.270010298863;6704.463642908556;6704.927211646138;5671.546276297886;5999.310079340888;6636.070950066995;6658.167729033871;6669;5914.733836977346;5993.160957465958;6001.189839371695;6010.729517171738;6029.155918171459;5086.702890762832;5113.284140220372;5409.43200963897;5471.042567550702;5793.362730099686;12724.936375318157;12890.03324750638;12900.419481123372;22072.88963555188;23491.41594424629;2144.6997420361163;2159.1039718516795;2388.1760792526725;2584.4185058361904;2867.4695181391303;6614.503912206604;9821.464272861764;10175.84736228957;10234.89765102347;10237.23220758446;1676.647903940174;1681.209831379262;1758.7010208264626;1795.9371422000168;1882.7270045843277;101590;117096.82903170945;122886;124861;130880.6911930878;8939.642414303355;10841.728956776144;11354.772904541864;11511.424428778528;11696.35670038156;14418.711625767426;14437.133771973715;14446.205458699873;14449;14453.78319325211;8004.879926801103;8285.42002059862;8308.418410711312;8376.413651044368;8421.15788421153;6816.659167041628;8197.836043279101;8210.712625058095;8220.095789463154;8389.119142490074;3629.602602997865;4389.561043895649;4391;4411.801468933906;4460.330950357407;1841.3463220556778;1888.10315100329;1901.961960760777;1930.903454827253;1939.3406241877547;819.3076850061689;914.4833169259347;921.8248532778885;930.6835675870101;942.5569982108398;20101.597968040558;21229.68155477669;21329.040193191344;21355;21396.465088372915;901.4411065139656;904.5386852705168;926.9119433653927;928.7585227840675;929.0477260807726;10158.187345012382;10971.83542246867;11106;11162.441877906072;11248.94375528117;580024;584801;588624;595919;596613;129459;130511;135439.6456035427;137832;138990.3050484741;864.7016779211162;980.5146452506056;991.3704797453669;1001.7896241789306;1003.4631472162358;18844.115588441047;19210.615787684448;19555.217791288756;19711.408657740292;19749;53639.463605363446;56567;58753.41246587651;59136.40863591481;60143;64197.03704444344;80528.59735701284;80554;81153.59423202847;82468.52594422176;10349.844752328872;11621.24461909973;11727.648170554898;11833.940830295793;12411.317377544321;13082.607521774362;13194.670133246746;13607.387667554785;13608.727825443239;13739.96950228717;11256.865461867497;11652.825207621723;11829.34938578352;11845.23006004607;11848.99283560907;11148.275362101438;11307.547698091985;11415.258008229439;11835.171537992257;11929.701757456132;9954;11454.484548195518;11521.078313734713;11588.304701717923;12125.575604853788;11225.943870280596;12012.219205751599;12177.147599668293;12291.201071930296;12351.505939762308;9195.908040919505;11331.830022549504;11531.942340288246;11690.357030363411;11786 09:15:53 INFO - PROCESS | 1635 | |3;mootools.html;1287.806828975653;1318.9868101318987;1418.546065259117;1529.3423827754068;1762.1717792637455;7398.926010739891;7756.883646745289;7757.418193635479;7762.805929851756;7764;7897.447178697494;8181;8198.098209196998;8223.177682231788;8311.002679678448;2269.387265438331;2325.8953347099423;2325.976740232593;2327.5577640248384;2330.0563271874835;2301.5857145713767;2339.4619237575284;2351.0830775997288;2351.400392899817;2351.58847201739;2283.6117859963847;2292.1633603734685;2316.3051084674617;2320.0719712115206;2320.8143348532076;2275.453891066132;2278.6354183330673;2305.804006659436;2307.58463476575;2309.8614083155067;2309.5842748305467;2335.883205839701;2336.953260934789;2339.883005849718;2341.789238968501;8155.796105097361;8209.09699933007;8238.588070596506;8260.586970651504;8266.83466330676;5010.799568017312;5052.621053420971;5208.218767184915;5220.895582088337;5225.608079394061;3451.1027132945364;3531.523244362004;3550.1302180965827;3550.55618047744;3555.8933232003083;1059.9470026498643;1078.4068762180768;1094.9013992064206;1107.180686292138;1110.5946329589722;10663.826979032301;10848.132149427947;10869.945650271777;10878.347299162075;10905.127589792797;4346.434963454731;4653.744044077608;4715.858524244278;4723.622110231173;4731.692439991459;9205.447673139633;9384.577694003787;9453.101955314372;9461.432314061178;9504.097110774464;5217.426083130853;5220.791168353223;5220.791168353299;5225.843224703265;5232.110541208004;3999.8600048998146;4049.7165198436414;4749.002709430989;4758.928616070762;4776.140294746979;3253.7310448924936;3327.8851584718905;3816.713746469026;3827.138893748884;3896.8441262349756;1079.379356869803;1235.849621658308;1272.179444258448;1278.4630455208835;1286.601153642374;796.6176235406921;840.9453385529921;859.9312055035582;873;899.397403739483;5278.894422111536;5373.3551973762615;5426.457354264542;5456.317960254968;5671.9716401418555;744.8659241336503;767.4742801181097;892.607252808762;913.8081002989313;921.7648351209411;3297.0768184908266;3569.660882216185;3597.0827439002887;3610.5306310180035;3621.203335266237;9481.715548533555;9769.316147869724;10096.990300969992;10101.545430455648;10117.645882394081;10062.899371006342;10151.086402223835;10164.33931794431;10164.847527287096;10167.898321016695;7696.499727517694;7700.037495313086;7830.373570114377;7901.762947111596;7918.129005809472;4650.907036846271;5249.370075590953;5382.138857782893;5411.404745478073;5439.102548079522;9056.909430905871;9218;9305.162535371848;9321.860172097287;9357.39176953496;9061.501617411106;9308.720738377859;9396.919354274345;9404.435733855991;9506.809863802824;7031.542949708252;7116.146062472537;7126.073610430611;7180.533265337736;7194;7732.884006740016;7800.609969501389;7807.102183248999;7826.099998500018;7833.5691536966015;6968.303169682991;7043.2604576518725;7091.184513780981;7155.606441645759;7214.567125972459;6804.285550017375;6953.304669533006;6972.930270697431;6995.79012629622;7025.437964962893;5192.896142077213;6547.0179473079415;6622.1722284714415;6628.038934354395;6646.335366463315;4593.379893714306;4628.861134165981;4649.3026046093355;4703.670743048091;4778.641601879804;3707;4607.792649330721;4754.619630429488;4865;4945.4312754033745 09:15:53 INFO - PROCESS | 1635 | |4;prototype.html;300.55067673827625;302.15396888711587;309.3488207323583;314.27402699763536;314.5643284051588;4711.492322456816;4964.602831773458;5063.8480845574695;5166.689998600086;5289.1917819170185;4225.218334608092;5062;5062.772175252113;5081.364829396325;5108.489151084899;3413.7439692023136;3778.754380965242;3794.8102594870284;3905.2384784966944;3929.76421414716;3304.306095719902;3307.891856228154;3859.247446747885;3989.4813674222455;3999.7800120993334;3441.6386279440676;3889.8444062237477;3946.4474973503734;3954.782486963216;3965.662918651918;3756.530433695788;3861.942070868939;3886.261610294035;3939.2515422069714;3958.802059897008;3486.1110416843794;3490.7556471047037;3500.4924285978645;3500.649935006479;3501.6848483636595;3657.6780783959275;4810.879728006793;4969.478204788517;4969.925451118236;5016.2224855147515;3453.188500702358;4144.943039524932;4493.370928070073;4506.805696490433;4593.63250939924;4574.4968053514085;5163.948360516422;5197.8440646780655;5248.895022099575;5284.735763211824;3653.355989804572;3698.8890333290046;3701.9259614807825;3709.8330575124182;3718.925621487555;5496.917546236809;5655.349634792011;5703;5738.512226460756;5755.625884317506;3460.602030766444;3711.46183803347;3731.552213734369;3749.1564398010773;3762.454444105644;3206.214477453039;3549.574051113831;3814.427835824593;3830.291396091732;3858.82635281413;3386.9322613548084;3731.8880433587037;3900.854530008484;4461.902905634347;4464.888377790581;5237.921431178535;5303.920441193385;5443.495991843717;5460.863478412992;5577.498025177753;1142.348861149137;1163.528770847808;1196.5034510677972;1214.8056310990198;1215.5806246844825;717.9231153270094;794.082834326354;805.6737021506182;807.725373373056;814.7311387242196;4745.833895813699;4765.594924431392;4854.5873600744335;4859.052484765437;4866.902661946741;5263.736703191283;5281.366236051698;5283.618484170134;5291.417944026154;5347.946520534822;5024.2463630454995;5230.3985041719925;5295.232191332235;5309.522143007071;5345;2512.2212114244644;2769.9446011079667;2776.319801648609;2786.4148528808773;2878.7121287871464;4741.652881823519;5328.362460582037;5369.067135831097;5496.5053145217125;5507.5593952483705;5176.7411629418375;5367.329083864517;5377.462253774669;5377.7580008899695;5379.757910894019;4516.390287311302;4756.976215118972;4931.161702510582;5198.766055527511;5432.891342173134;4437.400950871591;4921.876953076058;5064.569511591409;5160.174372100446;5245.35447863731;4263.3818096375235;4939.876503087307;4976.129177393869;5173.137670438714;5179.637425380187;4494.0337827367275;5124.12889808726;5375.247465354775;5396.8666580018635;5524.751386187711;3194.712475877089;3510.78935263885;3538.4161613333513;3777.0934975605105;3828.425736139601;4960.652754307308;5245.475452454724;5247.763850626808;5513.766924395626;5560.805371812129;5443.673379597237;5472.562195024469;5533.280673512418;5534.723263836872;5550.250716153268;4406;5312.973435132954;5477.219903531448;5508.91736623959;5527.309086364205;4446.844360447498;4801.887169645345;4833.444153922203;5218.608604354613;5384.273123128328;4580.404547408815;4805;5265.763040663256;5390.137577987503;5537.861553461195;5283.286756288006;5349.251104845403;5435.885643443164;5452.509274165344;5458.808941687022 09:15:53 INFO - PROCESS | 1635 | |5;yui.html;713.1157364867563;719.9956061294491;730.3062091013537;732.611715790631;765.6401491299088;14128.152310861355;14274.785878211835;14391.136531808099;14425.422983080678;14452.27738613069;12651.24092554445;12756.93621531891;12761.553345632905;12765.553205637805;12774.552890648829;638.7445021991194;660.9173853268342;789.1280135450331;806.9556174410391;818.2880893622556;810.2828996338225;812.0661239574478;812.4353574265888;813.9918600813975;815.388458656008;877.3463769491738;877.9070057778075;879.9824003519926;880.2649787427506;881.6958149438466;675.7026908160418;699.8810202265602;701.554512884317;702.1994925784559;702.4661257444328;627.2253766598247;642.065794269337;645.7255666341786;648.4779752299397;649.665422307512;13296.867031329659;13352.599422017354;13365.933170334183;13366.997475189302;13446.52937147205;11204.93553112453;12812.55156069533;12855.357232138449;13056.412461439162;13115.737685246242;11612.419379031098;11803.23278986863;11951;12148.3318417487;12342.876571234352;3753.549574051104;4295.613394794453;4325.659045695845;4449.866504004885;4464.977675111603;84094;86861.56569217113;87450;92960.53519732358;93535.53232233864;10215.846762298572;10326.173906087495;10426;10530.947345263377;10712.839307410395;19605.31381401644;19673.508162296057;19705;19780.50548736293;19784.505387365432;7240.420766338681;7282.96358518204;7387.445941554405;7456.142543607446;7692.230776922375;4100.630943215125;4142.896427589334;4228.302330115558;4253.425787518645;4374.759388233678;3184.5541624172633;3222.194451387153;3246.8376581170846;3287.72054375376;3291.045596776908;594.3224723814855;619.1146660275759;619.8388419011089;628.7736414890636;633.9239291285076;597.5727354941238;600.639616230267;615.1572345886165;630.7350912616712;637.9489640828723;7706.576138312446;8132.715354962548;8215.671373145127;8641.524716140548;8658.653653853902;534.4415086234894;609.7926704920349;616.0758861707438;637.4645297949744;640.8205702403242;4154.667626589866;4198.979005105016;4288.399624052635;4334.891627709269;4426.535213802569;73056;96687;109773.35339969907;111140;112872;69880;78527;80897;82410;93591;80928;81738.59130704308;82273.58863205646;84369;86465.13534864571;8998.137802607076;9691.778278414466;9706.825811264454;9733.377123577982;9762.316637835283;38443.038924027125;46005.08956738715;55589.44410556005;57118;58274.708626456595;43493.91265218395;53585;65759.67120164368;74636.25363746293;75352;11717.179797414441;12079.094067945114;12340.81488777651;12523.874761252271;12950.870491294967;13760.93119534396;13838;13843.4462621494;14476.927615361856;15198.240087995777;10641.840372394265;12379.504819807467;12387.69030774238;12858.871411286142;13282.203067815963;12492.687682808002;13072.477100915858;13104.510143072786;13208.669783255495;13576.59270221895;11644.2431241969;12462.189957652723;12621.305828179538;12942.611721648365;12962;10845;11118.666440006813;11297.830532541853;11408.771824563628;11421.314721116427;8329.583520823813;9513.286503512127;9972.202223822222;11456.656300311004;11604.245724027911 09:15:53 INFO - PROCESS | 1635 | __end_tp_report 09:15:53 INFO - PROCESS | 1635 | __start_cc_report 09:15:53 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,2753 09:15:53 INFO - PROCESS | 1635 | __end_cc_report 09:15:53 INFO - PROCESS | 1635 | __startTimestamp1481044553835__endTimestamp 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | ------- Summary: start ------- 09:15:53 INFO - PROCESS | 1635 | Number of tests: 6 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5765.39 Median:5936.88 stddev:652.33 (11.0%) stddev-sans-first:626.51 09:15:53 INFO - PROCESS | 1635 | Values: 3362.9 3378.2 3395.3 3411.7 3450.7 5178.8 5732.0 5800.4 5821.7 5844.2 5355.4 5777.5 5790.6 5812.0 5815.7 5215.8 5221.8 5258.6 5263.8 5286.7 5194.2 5204.1 5231.6 5236.8 5305.1 5270.4 5324.8 5328.7 5343.4 5378.3 5297.2 5307.4 5307.5 5322.4 5380.8 5072.0 5169.8 5266.7 5272.1 5285.5 5702.7 5722.0 5727.9 5731.5 5746.1 6135.8 6191.2 6192.5 6217.9 6313.7 6363.5 6365.8 6374.5 6392.6 6421.2 5905.7 5921.8 5927.5 5934.6 5936.9 6240.7 6262.8 6280.8 6304.9 6314.6 6232.7 6241.6 6245.3 6248.2 6253.9 5285.2 6309.0 6330.8 6347.3 6352.7 6079.1 6109.5 6153.6 6194.6 6281.8 6251.5 6324.5 6327.6 6338.8 6341.9 4870.3 4978.0 4980.6 4981.9 5023.6 4420.0 4509.7 4513.9 4527.9 4530.0 4892.2 5756.9 5819.2 5829.5 5861.2 6313.6 6317.5 6345.1 6356.4 6363.2 5832.6 5843.5 5860.6 5883.1 5904.2 5314.7 5349.9 5354.5 5363.3 5402.0 6222.7 6352.1 6370.9 6371.0 6392.1 6332.5 6332.9 6353.3 6381.5 6382.8 4864.9 6238.4 6269.4 6338.2 6408.0 4919.6 5210.8 6167.7 6273.8 6294.2 6205.7 6356.7 6358.1 6358.9 6393.9 5921.5 6003.2 6084.2 6151.1 6234.5 6108.0 6124.1 6124.5 6135.8 6144.7 5911.6 5936.4 5951.7 5951.7 5968.4 6051.3 6113.8 6125.4 6142.0 6144.5 5920.9 5945.2 6001.4 6018.2 6073.6 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:69141.17 Median:38972.11 stddev:153645.98 (394.2%) stddev-sans-first:153990.47 09:15:53 INFO - PROCESS | 1635 | Values: 465.7 482.8 516.3 525.2 538.6 38267.6 39709.8 39711.2 39792.6 39841.8 41847.6 42326.8 42348.9 42364.0 42400.0 9336.3 9483.8 9499.8 9508.2 9518.2 9345.4 9458.6 9475.8 9485.0 9512.1 9317.2 9469.1 9474.8 9504.4 9525.6 9417.4 9470.7 9483.0 9486.2 9487.3 9350.3 9447.9 9450.8 9454.8 9478.0 39331.0 39608.0 39642.4 39659.6 39676.0 3167.5 3284.8 3290.3 3457.7 3689.6 28905.7 29100.0 29876.6 29943.9 30450.8 3144.6 3157.2 3232.8 3247.1 3268.6 477349.0 480470.0 480932.0 482305.0 482382.2 58242.0 58524.0 58562.7 58576.0 58586.7 34507.1 34968.8 35208.6 35909.6 62866.4 15969.0 16086.6 16134.5 16155.1 16160.7 12196.7 12278.9 12285.4 12319.4 12321.6 18512.8 18612.7 18616.1 18619.5 18703.9 7029.5 7079.9 7509.8 7613.2 7687.7 818.7 841.7 856.0 925.2 1040.0 23857.0 24628.9 29716.9 30242.8 31967.7 1023.4 1052.1 1055.0 1070.4 1100.9 14738.7 15278.8 15285.8 15304.5 15624.4 548019.0 683687.0 688231.0 688628.0 689211.0 56164.0 56685.4 56710.1 56840.1 56861.6 1222.8 1262.7 1264.8 1333.1 1358.1 29489.4 30604.4 30609.1 30993.5 31236.0 49432.5 49957.0 50027.2 50118.7 50130.0 560050.0 565723.0 565893.0 566348.0 568151.0 22158.6 23533.8 24525.3 26071.5 26184.9 43542.8 44345.0 44534.8 44966.8 44984.6 37214.4 37382.3 37396.3 37583.1 37644.8 37070.0 37502.8 37851.6 38015.6 38613.2 36066.8 36581.0 37289.3 37352.0 38362.4 32356.7 32614.0 34062.8 34727.7 34780.1 25549.0 25783.2 25808.6 25959.2 26327.1 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:35023.19 Median:20946.98 stddev:98680.77 (471.1%) stddev-sans-first:98923.25 09:15:53 INFO - PROCESS | 1635 | Values: 352.2 365.1 375.7 379.8 401.5 14685.3 14855.5 15636.1 19789.5 20664.3 19079.4 19814.6 20404.7 20423.5 20608.6 5522.1 5792.2 5793.4 5807.1 5810.0 4651.6 6550.6 6636.3 6704.5 6704.9 5671.5 5999.3 6636.1 6658.2 6669.0 5914.7 5993.2 6001.2 6010.7 6029.2 5086.7 5113.3 5409.4 5471.0 5793.4 12724.9 12890.0 12900.4 22072.9 23491.4 2144.7 2159.1 2388.2 2584.4 2867.5 6614.5 9821.5 10175.8 10234.9 10237.2 1676.6 1681.2 1758.7 1795.9 1882.7 101590.0 117096.8 122886.0 124861.0 130880.7 8939.6 10841.7 11354.8 11511.4 11696.4 14418.7 14437.1 14446.2 14449.0 14453.8 8004.9 8285.4 8308.4 8376.4 8421.2 6816.7 8197.8 8210.7 8220.1 8389.1 3629.6 4389.6 4391.0 4411.8 4460.3 1841.3 1888.1 1902.0 1930.9 1939.3 819.3 914.5 921.8 930.7 942.6 20101.6 21229.7 21329.0 21355.0 21396.5 901.4 904.5 926.9 928.8 929.0 10158.2 10971.8 11106.0 11162.4 11248.9 580024.0 584801.0 588624.0 595919.0 596613.0 129459.0 130511.0 135439.6 137832.0 138990.3 864.7 980.5 991.4 1001.8 1003.5 18844.1 19210.6 19555.2 19711.4 19749.0 53639.5 56567.0 58753.4 59136.4 60143.0 64197.0 80528.6 80554.0 81153.6 82468.5 10349.8 11621.2 11727.6 11833.9 12411.3 13082.6 13194.7 13607.4 13608.7 13740.0 11256.9 11652.8 11829.3 11845.2 11849.0 11148.3 11307.5 11415.3 11835.2 11929.7 9954.0 11454.5 11521.1 11588.3 12125.6 11225.9 12012.2 12177.1 12291.2 12351.5 9195.9 11331.8 11531.9 11690.4 11786.0 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5367.10 Median:5122.76 stddev:2964.39 (57.9%) stddev-sans-first:2956.85 09:15:53 INFO - PROCESS | 1635 | Values: 1287.8 1319.0 1418.5 1529.3 1762.2 7398.9 7756.9 7757.4 7762.8 7764.0 7897.4 8181.0 8198.1 8223.2 8311.0 2269.4 2325.9 2326.0 2327.6 2330.1 2301.6 2339.5 2351.1 2351.4 2351.6 2283.6 2292.2 2316.3 2320.1 2320.8 2275.5 2278.6 2305.8 2307.6 2309.9 2309.6 2335.9 2337.0 2339.9 2341.8 8155.8 8209.1 8238.6 8260.6 8266.8 5010.8 5052.6 5208.2 5220.9 5225.6 3451.1 3531.5 3550.1 3550.6 3555.9 1059.9 1078.4 1094.9 1107.2 1110.6 10663.8 10848.1 10869.9 10878.3 10905.1 4346.4 4653.7 4715.9 4723.6 4731.7 9205.4 9384.6 9453.1 9461.4 9504.1 5217.4 5220.8 5220.8 5225.8 5232.1 3999.9 4049.7 4749.0 4758.9 4776.1 3253.7 3327.9 3816.7 3827.1 3896.8 1079.4 1235.8 1272.2 1278.5 1286.6 796.6 840.9 859.9 873.0 899.4 5278.9 5373.4 5426.5 5456.3 5672.0 744.9 767.5 892.6 913.8 921.8 3297.1 3569.7 3597.1 3610.5 3621.2 9481.7 9769.3 10097.0 10101.5 10117.6 10062.9 10151.1 10164.3 10164.8 10167.9 7696.5 7700.0 7830.4 7901.8 7918.1 4650.9 5249.4 5382.1 5411.4 5439.1 9056.9 9218.0 9305.2 9321.9 9357.4 9061.5 9308.7 9396.9 9404.4 9506.8 7031.5 7116.1 7126.1 7180.5 7194.0 7732.9 7800.6 7807.1 7826.1 7833.6 6968.3 7043.3 7091.2 7155.6 7214.6 6804.3 6953.3 6972.9 6995.8 7025.4 5192.9 6547.0 6622.2 6628.0 6646.3 4593.4 4628.9 4649.3 4703.7 4778.6 3707.0 4607.8 4754.6 4865.0 4945.4 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4301.05 Median:4862.98 stddev:1323.77 (27.2%) stddev-sans-first:1292.98 09:15:53 INFO - PROCESS | 1635 | Values: 300.6 302.2 309.3 314.3 314.6 4711.5 4964.6 5063.8 5166.7 5289.2 4225.2 5062.0 5062.8 5081.4 5108.5 3413.7 3778.8 3794.8 3905.2 3929.8 3304.3 3307.9 3859.2 3989.5 3999.8 3441.6 3889.8 3946.4 3954.8 3965.7 3756.5 3861.9 3886.3 3939.3 3958.8 3486.1 3490.8 3500.5 3500.6 3501.7 3657.7 4810.9 4969.5 4969.9 5016.2 3453.2 4144.9 4493.4 4506.8 4593.6 4574.5 5163.9 5197.8 5248.9 5284.7 3653.4 3698.9 3701.9 3709.8 3718.9 5496.9 5655.3 5703.0 5738.5 5755.6 3460.6 3711.5 3731.6 3749.2 3762.5 3206.2 3549.6 3814.4 3830.3 3858.8 3386.9 3731.9 3900.9 4461.9 4464.9 5237.9 5303.9 5443.5 5460.9 5577.5 1142.3 1163.5 1196.5 1214.8 1215.6 717.9 794.1 805.7 807.7 814.7 4745.8 4765.6 4854.6 4859.1 4866.9 5263.7 5281.4 5283.6 5291.4 5347.9 5024.2 5230.4 5295.2 5309.5 5345.0 2512.2 2769.9 2776.3 2786.4 2878.7 4741.7 5328.4 5369.1 5496.5 5507.6 5176.7 5367.3 5377.5 5377.8 5379.8 4516.4 4757.0 4931.2 5198.8 5432.9 4437.4 4921.9 5064.6 5160.2 5245.4 4263.4 4939.9 4976.1 5173.1 5179.6 4494.0 5124.1 5375.2 5396.9 5524.8 3194.7 3510.8 3538.4 3777.1 3828.4 4960.7 5245.5 5247.8 5513.8 5560.8 5443.7 5472.6 5533.3 5534.7 5550.3 4406.0 5313.0 5477.2 5508.9 5527.3 4446.8 4801.9 4833.4 5218.6 5384.3 4580.4 4805.0 5265.8 5390.1 5537.9 5283.3 5349.3 5435.9 5452.5 5458.8 09:15:53 INFO - PROCESS | 1635 | 09:15:53 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19339.20 Median:4457.42 stddev:27873.47 (625.3%) stddev-sans-first:27916.58 09:15:53 INFO - PROCESS | 1635 | Values: 713.1 720.0 730.3 732.6 765.6 14128.2 14274.8 14391.1 14425.4 14452.3 12651.2 12756.9 12761.6 12765.6 12774.6 638.7 660.9 789.1 807.0 818.3 810.3 812.1 812.4 814.0 815.4 877.3 877.9 880.0 880.3 881.7 675.7 699.9 701.6 702.2 702.5 627.2 642.1 645.7 648.5 649.7 13296.9 13352.6 13365.9 13367.0 13446.5 11204.9 12812.6 12855.4 13056.4 13115.7 11612.4 11803.2 11951.0 12148.3 12342.9 3753.5 4295.6 4325.7 4449.9 4465.0 84094.0 86861.6 87450.0 92960.5 93535.5 10215.8 10326.2 10426.0 10530.9 10712.8 19605.3 19673.5 19705.0 19780.5 19784.5 7240.4 7283.0 7387.4 7456.1 7692.2 4100.6 4142.9 4228.3 4253.4 4374.8 3184.6 3222.2 3246.8 3287.7 3291.0 594.3 619.1 619.8 628.8 633.9 597.6 600.6 615.2 630.7 637.9 7706.6 8132.7 8215.7 8641.5 8658.7 534.4 609.8 616.1 637.5 640.8 4154.7 4199.0 4288.4 4334.9 4426.5 73056.0 96687.0 109773.4 111140.0 112872.0 69880.0 78527.0 80897.0 82410.0 93591.0 80928.0 81738.6 82273.6 84369.0 86465.1 8998.1 9691.8 9706.8 9733.4 9762.3 38443.0 46005.1 55589.4 57118.0 58274.7 43493.9 53585.0 65759.7 74636.3 75352.0 11717.2 12079.1 12340.8 12523.9 12950.9 13760.9 13838.0 13843.4 14476.9 15198.2 10641.8 12379.5 12387.7 12858.9 13282.2 12492.7 13072.5 13104.5 13208.7 13576.6 11644.2 12462.2 12621.3 12942.6 12962.0 10845.0 11118.7 11297.8 11408.8 11421.3 8329.6 9513.3 9972.2 11456.7 11604.2 09:15:53 INFO - PROCESS | 1635 | -------- Summary: end -------- 09:15:53 INFO - PROCESS | 1635 | 09:15:54 INFO - TEST-INFO | 1635: exit 0 09:15:54 INFO - TEST-OK | dromaeo_css | took 1120769ms 09:15:54 INFO - TEST-START | kraken 09:15:54 INFO - operating with platform_type : mac_ 09:15:54 INFO - Initialising browser for kraken test... 09:15:54 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpGYwY6D/profile http://localhost:49236/getInfo.html) 09:15:57 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 09:15:57 INFO - PROCESS | 1645 | colorDepth:24 09:15:57 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 09:15:57 INFO - PROCESS | 1645 | __metrics 09:15:57 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:15:57 INFO - PROCESS | 1645 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:58 INFO - TEST-INFO | 1645: exit 0 09:15:58 INFO - Browser initialized. 09:15:58 INFO - Running cycle 1/1 for kraken test... 09:15:58 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpGYwY6D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:10 INFO - PROCESS | 1646 | RSS: Main: 222519296 09:16:10 INFO - PROCESS | 1646 | 09:16:13 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 09:16:14 INFO - PROCESS | 1646 | RSS: Main: 272257024 09:16:14 INFO - PROCESS | 1646 | 09:16:16 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 09:16:17 INFO - PROCESS | 1646 | RSS: Main: 283009024 09:16:17 INFO - PROCESS | 1646 | 09:16:20 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 09:16:21 INFO - PROCESS | 1646 | RSS: Main: 379465728 09:16:21 INFO - PROCESS | 1646 | 09:16:23 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 09:16:23 INFO - PROCESS | 1646 | RSS: Main: 283275264 09:16:23 INFO - PROCESS | 1646 | 09:16:26 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:16:26 INFO - PROCESS | 1646 | RSS: Main: 284647424 09:16:26 INFO - PROCESS | 1646 | 09:16:29 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 09:16:30 INFO - PROCESS | 1646 | RSS: Main: 326000640 09:16:30 INFO - PROCESS | 1646 | 09:16:32 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 09:16:33 INFO - PROCESS | 1646 | RSS: Main: 326389760 09:16:33 INFO - PROCESS | 1646 | 09:16:36 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 09:16:36 INFO - PROCESS | 1646 | RSS: Main: 323244032 09:16:36 INFO - PROCESS | 1646 | 09:16:38 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:16:39 INFO - PROCESS | 1646 | RSS: Main: 299741184 09:16:39 INFO - PROCESS | 1646 | 09:16:40 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:16:41 INFO - PROCESS | 1646 | RSS: Main: 298426368 09:16:41 INFO - PROCESS | 1646 | 09:16:43 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:16:44 INFO - PROCESS | 1646 | RSS: Main: 302579712 09:16:44 INFO - PROCESS | 1646 | 09:16:47 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:16:48 INFO - PROCESS | 1646 | RSS: Main: 321179648 09:16:48 INFO - PROCESS | 1646 | 09:16:51 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:16:51 INFO - PROCESS | 1646 | RSS: Main: 314884096 09:16:51 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 09:16:53 INFO - PROCESS | 1646 | RSS: Main: 359108608 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | __start_tp_report 09:16:53 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 09:16:53 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 09:16:53 INFO - PROCESS | 1646 | |i|pagename|runs| 09:16:53 INFO - PROCESS | 1646 | |0;ai-astar;116;115;116;114;115;115;114;114;115;114 09:16:53 INFO - PROCESS | 1646 | |1;audio-beat-detection;152;115;114;148;117;115;114;115;144;114 09:16:53 INFO - PROCESS | 1646 | |2;audio-dft;181;183;183;186;185;182;184;184;184;184 09:16:53 INFO - PROCESS | 1646 | |3;audio-fft;82;59;59;88;62;59;60;48;83;73 09:16:53 INFO - PROCESS | 1646 | |4;audio-oscillator;81;71;94;69;68;71;118;98;101;91 09:16:53 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;89;85;88;89;89;85;89;86;88;91 09:16:53 INFO - PROCESS | 1646 | |6;imaging-darkroom;93;95;95;93;93;94;95;94;93;95 09:16:53 INFO - PROCESS | 1646 | |7;imaging-desaturate;83;98;130;82;84;84;83;82;82;85 09:16:53 INFO - PROCESS | 1646 | |8;json-parse-financial;52;53;55;53;56;58;58;56;57;57 09:16:53 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;68;73;64;76;72;69;70;71;71;71 09:16:53 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;86;85;86;85;84;85;85;85;89;87 09:16:53 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;149;146;168;156;169;158;156;161;197;154 09:16:53 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;169;174;169;166;170;169;166;169;168;166 09:16:53 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;70;70;70;67;68;68;67;68;67;67 09:16:53 INFO - PROCESS | 1646 | __end_tp_report 09:16:53 INFO - PROCESS | 1646 | __start_cc_report 09:16:53 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2064 09:16:53 INFO - PROCESS | 1646 | __end_cc_report 09:16:53 INFO - PROCESS | 1646 | __startTimestamp1481044613657__endTimestamp 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | ------- Summary: start ------- 09:16:53 INFO - PROCESS | 1646 | Number of tests: 14 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 09:16:53 INFO - PROCESS | 1646 | Values: 116.0 115.0 116.0 114.0 115.0 115.0 114.0 114.0 115.0 114.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:116.00 stddev:16.14 (13.9%) stddev-sans-first:13.80 09:16:53 INFO - PROCESS | 1646 | Values: 152.0 115.0 114.0 148.0 117.0 115.0 114.0 115.0 144.0 114.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.43 (0.8%) stddev-sans-first:1.17 09:16:53 INFO - PROCESS | 1646 | Values: 181.0 183.0 183.0 186.0 185.0 182.0 184.0 184.0 184.0 184.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:67.30 Median:67.50 stddev:13.27 (19.7%) stddev-sans-first:12.96 09:16:53 INFO - PROCESS | 1646 | Values: 82.0 59.0 59.0 88.0 62.0 59.0 60.0 48.0 83.0 73.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:76.00 stddev:16.90 (22.2%) stddev-sans-first:17.82 09:16:53 INFO - PROCESS | 1646 | Values: 81.0 71.0 94.0 69.0 68.0 71.0 118.0 98.0 101.0 91.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.97 (2.2%) stddev-sans-first:2.05 09:16:53 INFO - PROCESS | 1646 | Values: 89.0 85.0 88.0 89.0 89.0 85.0 89.0 86.0 88.0 91.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.50 stddev:0.94 (1.0%) stddev-sans-first:0.93 09:16:53 INFO - PROCESS | 1646 | Values: 93.0 95.0 95.0 93.0 93.0 94.0 95.0 94.0 93.0 95.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:89.30 Median:83.50 stddev:15.08 (18.1%) stddev-sans-first:15.82 09:16:53 INFO - PROCESS | 1646 | Values: 83.0 98.0 130.0 82.0 84.0 84.0 83.0 82.0 82.0 85.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.50 stddev:2.17 (3.8%) stddev-sans-first:1.90 09:16:53 INFO - PROCESS | 1646 | Values: 52.0 53.0 55.0 53.0 56.0 58.0 58.0 56.0 57.0 57.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:3.17 (4.5%) stddev-sans-first:3.23 09:16:53 INFO - PROCESS | 1646 | Values: 68.0 73.0 64.0 76.0 72.0 69.0 70.0 71.0 71.0 71.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.50 stddev:1.42 (1.7%) stddev-sans-first:1.50 09:16:53 INFO - PROCESS | 1646 | Values: 86.0 85.0 86.0 85.0 84.0 85.0 85.0 85.0 89.0 87.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:161.40 Median:159.50 stddev:14.47 (9.1%) stddev-sans-first:14.64 09:16:53 INFO - PROCESS | 1646 | Values: 149.0 146.0 168.0 156.0 169.0 158.0 156.0 161.0 197.0 154.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.00 stddev:2.41 (1.4%) stddev-sans-first:2.55 09:16:53 INFO - PROCESS | 1646 | Values: 169.0 174.0 169.0 166.0 170.0 169.0 166.0 169.0 168.0 166.0 09:16:53 INFO - PROCESS | 1646 | 09:16:53 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.32 (1.9%) stddev-sans-first:1.22 09:16:53 INFO - PROCESS | 1646 | Values: 70.0 70.0 70.0 67.0 68.0 68.0 67.0 68.0 67.0 67.0 09:16:53 INFO - PROCESS | 1646 | -------- Summary: end -------- 09:16:53 INFO - PROCESS | 1646 | 09:16:54 INFO - TEST-INFO | 1646: exit 0 09:16:54 INFO - TEST-OK | kraken | took 60316ms 09:16:54 INFO - SUITE-END | took 1181s 09:16:54 INFO - Completed test suite (00:19:41) 09:16:54 INFO - javascript benchmark 09:16:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.932741345173, 3378.155461134717, 3395.3379091077236, 3411.710004649605, 3450.689437950584, 5178.782985998288, 5732.0255556555385, 5800.390958949313, 5821.738021789024, 5844.21103151075, 5355.410904800468, 5777.480026797587, 5790.594658373915, 5812.041013232812, 5815.709214539277, 5215.765290561915, 5221.791128354861, 5258.55302299305, 5263.763130659129, 5286.735663216824, 5194.194899790519, 5204.063268611667, 5231.633785635007, 5236.764345604457, 5305.124654432014, 5270.446603106676, 5324.787008519655, 5328.733563321838, 5343.358796944372, 5378.300820893279, 5297.17893726473, 5307.389650190239, 5307.495787900143, 5322.361316642027, 5380.784768609251, 5072.0, 5169.844904652867, 5266.683998960075, 5272.051030814452, 5285.524302812765, 5702.714864256812, 5722.027255366597, 5727.856803579903, 5731.541476681856, 5746.138079288098, 6135.796305541688, 6191.195144631214, 6192.473639740628, 6217.906731399033, 6313.684315784239, 6363.459105975998, 6365.777197798055, 6374.521910856658, 6392.6484043377595, 6421.197350331208, 5905.6751878646655, 5921.822345329647, 5927.525797936155, 5934.554908381889, 5936.881262374728, 6240.687965601793, 6262.812115636538, 6280.780172694025, 6304.905426418607, 6314.55798094129, 6232.719527621268, 6241.594296370721, 6245.344238854946, 6248.187735594343, 6253.906191407133, 5285.179256244892, 6308.968455157695, 6330.8417289567205, 6347.301796802348, 6352.6823658816875, 6079.118527813443, 6109.480694140959, 6153.630782152995, 6194.59735117225, 6281.842953926097, 6251.531135164881, 6324.494040476751, 6327.557071004985, 6338.809835704937, 6341.936580634227, 4870.342503762017, 4978.029284289529, 4980.576650984707, 4981.9252711209365, 5023.572996295283, 4420.0, 4509.706869053501, 4513.887152821206, 4527.864164075083, 4530.048689775184, 4892.241702536113, 5756.884862302731, 5819.214406055128, 5829.5044921181325, 5861.17943487912, 6313.621182729051, 6317.4946004319545, 6345.1116843641, 6356.4279214870885, 6363.204599425072, 5832.562557808096, 5843.5325173985975, 5860.589758716934, 5883.117532370093, 5904.232449781587, 5314.681119132864, 5349.946500535023, 5354.464553544614, 5363.329583802025, 5401.972990134946, 6222.688865556795, 6352.142460767737, 6370.90443643355, 6370.968145159244, 6392.137061496825, 6332.461740752087, 6332.873342533216, 6353.269374021862, 6381.521385895983, 6382.840428989312, 4864.929715462734, 6238.376162383725, 6269.404406581368, 6338.207724034496, 6408.00675895237, 4919.581835544019, 5210.817621383233, 6167.6916154193, 6273.843153921189, 6294.181756371826, 6205.720742566505, 6356.745730170742, 6358.109864618865, 6358.936410635834, 6393.936060639334, 5921.526277897672, 6003.189569408074, 6084.239470066242, 6151.077338399097, 6234.501239900706, 6107.961646520013, 6124.112003759341, 6124.5406594506485, 6135.84660383494, 6144.662043587646, 5911.586188966731, 5936.406359364029, 5951.702414879325, 5951.702414879325, 5968.403159683997, 6051.304100028553, 6113.841195312599, 6125.387461253839, 6141.969290153521, 6144.477719393902, 5920.911186332295, 5945.227120474311, 6001.399860013964, 6018.247719035121, 6073.5748497604745], "value": 5725.355236669723, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.67402818027387, 482.7658585585991, 516.326194316417, 525.1649876696054, 538.571835390864, 38267.61732382675, 39709.808705738804, 39711.20577588447, 39792.60207397925, 39841.80079099608, 41847.58152418475, 42326.7883660582, 42348.941276468104, 42364.0, 42400.0, 9336.346455748084, 9483.81032379352, 9499.810003799954, 9508.239340852715, 9518.238540916711, 9345.43927364357, 9458.62165513382, 9475.763105922373, 9485.0, 9512.143907048365, 9317.161455469006, 9469.10043545862, 9474.810503789955, 9504.429734215935, 9525.618975240983, 9417.434953902753, 9470.668526601605, 9482.952585237064, 9486.241100711926, 9487.335886487948, 9350.298727595458, 9447.90552094477, 9450.810983780286, 9454.810903781887, 9477.957424683349, 39331.0, 39608.0, 39642.40536391956, 39659.60340396588, 39676.0, 3167.4615315396436, 3284.770066095374, 3290.3090351026312, 3457.7233821294235, 3689.6494832990825, 28905.710942890513, 29100.0, 29876.55185172224, 29943.850280748673, 30450.781968721225, 3144.5754823099046, 3157.179133425303, 3232.8060316381093, 3247.1232767152974, 3268.6271765858396, 477349.0, 480470.0, 480932.0, 482305.0, 482382.17617823725, 58242.0, 58524.0, 58562.70718646465, 58576.0, 58586.7070664644, 34507.13732156666, 34968.776092837135, 35208.64791352105, 35909.64090359115, 62866.37133628605, 15969.041857488588, 16086.597835053983, 16134.515964521082, 16155.111468869325, 16160.676786464204, 12196.695082623006, 12278.938605306916, 12285.38573071343, 12319.384030798425, 12321.630351089481, 18512.814871851377, 18612.720809187602, 18616.06919654012, 18619.534511637317, 18703.877767334005, 7029.5430796998035, 7079.92920070803, 7509.774706758806, 7613.200613935568, 7687.692492300357, 818.7461886815106, 841.7264389073575, 855.9871601925976, 925.2135684668112, 1039.9964034706545, 23856.990764812064, 24628.883233147288, 29716.851415743215, 30242.84878575593, 31967.680323196935, 1023.4371095897227, 1052.1267348101057, 1054.99472502637, 1070.4487189097622, 1100.9394483303495, 14738.705225895637, 15278.770818437732, 15285.770713439308, 15304.540863774104, 15624.3750249991, 548019.0, 683687.0, 688231.0, 688628.0, 689211.0, 56164.0, 56685.43314566884, 56710.14934775982, 56840.147397789064, 56861.57846053799, 1222.8349172861729, 1262.72851336963, 1264.7723409786327, 1333.053531992277, 1358.083293776677, 29489.410211796072, 30604.387912242077, 30609.081727548208, 30993.53509697311, 31236.0, 49432.50567494279, 49957.0, 50027.24959125543, 50118.74940625273, 50130.0, 560050.0, 565723.0, 565893.0, 566348.0, 568151.0, 22158.56062685573, 23533.76466235316, 24525.264242072768, 26071.478570428866, 26184.86907565526, 43542.78228608837, 44345.0, 44534.77732611316, 44966.77516612396, 44984.550154499346, 37214.44178337273, 37382.2523549522, 37396.252074958895, 37583.06042348854, 37644.81177594094, 37069.997605554854, 37502.81248593739, 37851.62148378481, 38015.61984380231, 38613.2277354457, 36066.8196659015, 36581.0, 37289.25421491501, 37352.0, 38362.42456363101, 32356.676433235367, 32614.0, 34062.829685851415, 34727.65272347345, 34780.13049673779, 25549.0, 25783.226503204933, 25808.61287080733, 25959.22122336333, 26327.07855225058], "value": 19363.513692767745, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.22861932368096, 365.11641826779197, 375.67128762332976, 379.8328735356444, 401.4520179954361, 14685.33915973781, 14855.480058197965, 15636.061836289817, 19789.50526236845, 20664.276750313744, 19079.427617171506, 19814.60370792583, 20404.693929591067, 20423.489412764688, 20608.587828243428, 5522.144067669507, 5792.247007888989, 5793.449622285876, 5807.0998995155815, 5810.0, 4651.581357677808, 6550.574212676184, 6636.270010298863, 6704.463642908556, 6704.927211646138, 5671.546276297886, 5999.310079340888, 6636.070950066995, 6658.167729033871, 6669.0, 5914.733836977346, 5993.160957465958, 6001.189839371695, 6010.729517171738, 6029.155918171459, 5086.702890762832, 5113.284140220372, 5409.43200963897, 5471.042567550702, 5793.362730099686, 12724.936375318157, 12890.03324750638, 12900.419481123372, 22072.88963555188, 23491.41594424629, 2144.6997420361163, 2159.1039718516795, 2388.1760792526725, 2584.4185058361904, 2867.4695181391303, 6614.503912206604, 9821.464272861764, 10175.84736228957, 10234.89765102347, 10237.23220758446, 1676.647903940174, 1681.209831379262, 1758.7010208264626, 1795.9371422000168, 1882.7270045843277, 101590.0, 117096.82903170945, 122886.0, 124861.0, 130880.6911930878, 8939.642414303355, 10841.728956776144, 11354.772904541864, 11511.424428778528, 11696.35670038156, 14418.711625767426, 14437.133771973715, 14446.205458699873, 14449.0, 14453.78319325211, 8004.879926801103, 8285.42002059862, 8308.418410711312, 8376.413651044368, 8421.15788421153, 6816.659167041628, 8197.836043279101, 8210.712625058095, 8220.095789463154, 8389.119142490074, 3629.602602997865, 4389.561043895649, 4391.0, 4411.801468933906, 4460.330950357407, 1841.3463220556778, 1888.10315100329, 1901.961960760777, 1930.903454827253, 1939.3406241877547, 819.3076850061689, 914.4833169259347, 921.8248532778885, 930.6835675870101, 942.5569982108398, 20101.597968040558, 21229.68155477669, 21329.040193191344, 21355.0, 21396.465088372915, 901.4411065139656, 904.5386852705168, 926.9119433653927, 928.7585227840675, 929.0477260807726, 10158.187345012382, 10971.83542246867, 11106.0, 11162.441877906072, 11248.94375528117, 580024.0, 584801.0, 588624.0, 595919.0, 596613.0, 129459.0, 130511.0, 135439.6456035427, 137832.0, 138990.3050484741, 864.7016779211162, 980.5146452506056, 991.3704797453669, 1001.7896241789306, 1003.4631472162358, 18844.115588441047, 19210.615787684448, 19555.217791288756, 19711.408657740292, 19749.0, 53639.463605363446, 56567.0, 58753.41246587651, 59136.40863591481, 60143.0, 64197.03704444344, 80528.59735701284, 80554.0, 81153.59423202847, 82468.52594422176, 10349.844752328872, 11621.24461909973, 11727.648170554898, 11833.940830295793, 12411.317377544321, 13082.607521774362, 13194.670133246746, 13607.387667554785, 13608.727825443239, 13739.96950228717, 11256.865461867497, 11652.825207621723, 11829.34938578352, 11845.23006004607, 11848.99283560907, 11148.275362101438, 11307.547698091985, 11415.258008229439, 11835.171537992257, 11929.701757456132, 9954.0, 11454.484548195518, 11521.078313734713, 11588.304701717923, 12125.575604853788, 11225.943870280596, 12012.219205751599, 12177.147599668293, 12291.201071930296, 12351.505939762308, 9195.908040919505, 11331.830022549504, 11531.942340288246, 11690.357030363411, 11786.0], "value": 9633.222283775021, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.806828975653, 1318.9868101318987, 1418.546065259117, 1529.3423827754068, 1762.1717792637455, 7398.926010739891, 7756.883646745289, 7757.418193635479, 7762.805929851756, 7764.0, 7897.447178697494, 8181.0, 8198.098209196998, 8223.177682231788, 8311.002679678448, 2269.387265438331, 2325.8953347099423, 2325.976740232593, 2327.5577640248384, 2330.0563271874835, 2301.5857145713767, 2339.4619237575284, 2351.0830775997288, 2351.400392899817, 2351.58847201739, 2283.6117859963847, 2292.1633603734685, 2316.3051084674617, 2320.0719712115206, 2320.8143348532076, 2275.453891066132, 2278.6354183330673, 2305.804006659436, 2307.58463476575, 2309.8614083155067, 2309.5842748305467, 2335.883205839701, 2336.953260934789, 2339.883005849718, 2341.789238968501, 8155.796105097361, 8209.09699933007, 8238.588070596506, 8260.586970651504, 8266.83466330676, 5010.799568017312, 5052.621053420971, 5208.218767184915, 5220.895582088337, 5225.608079394061, 3451.1027132945364, 3531.523244362004, 3550.1302180965827, 3550.55618047744, 3555.8933232003083, 1059.9470026498643, 1078.4068762180768, 1094.9013992064206, 1107.180686292138, 1110.5946329589722, 10663.826979032301, 10848.132149427947, 10869.945650271777, 10878.347299162075, 10905.127589792797, 4346.434963454731, 4653.744044077608, 4715.858524244278, 4723.622110231173, 4731.692439991459, 9205.447673139633, 9384.577694003787, 9453.101955314372, 9461.432314061178, 9504.097110774464, 5217.426083130853, 5220.791168353223, 5220.791168353299, 5225.843224703265, 5232.110541208004, 3999.8600048998146, 4049.7165198436414, 4749.002709430989, 4758.928616070762, 4776.140294746979, 3253.7310448924936, 3327.8851584718905, 3816.713746469026, 3827.138893748884, 3896.8441262349756, 1079.379356869803, 1235.849621658308, 1272.179444258448, 1278.4630455208835, 1286.601153642374, 796.6176235406921, 840.9453385529921, 859.9312055035582, 873.0, 899.397403739483, 5278.894422111536, 5373.3551973762615, 5426.457354264542, 5456.317960254968, 5671.9716401418555, 744.8659241336503, 767.4742801181097, 892.607252808762, 913.8081002989313, 921.7648351209411, 3297.0768184908266, 3569.660882216185, 3597.0827439002887, 3610.5306310180035, 3621.203335266237, 9481.715548533555, 9769.316147869724, 10096.990300969992, 10101.545430455648, 10117.645882394081, 10062.899371006342, 10151.086402223835, 10164.33931794431, 10164.847527287096, 10167.898321016695, 7696.499727517694, 7700.037495313086, 7830.373570114377, 7901.762947111596, 7918.129005809472, 4650.907036846271, 5249.370075590953, 5382.138857782893, 5411.404745478073, 5439.102548079522, 9056.909430905871, 9218.0, 9305.162535371848, 9321.860172097287, 9357.39176953496, 9061.501617411106, 9308.720738377859, 9396.919354274345, 9404.435733855991, 9506.809863802824, 7031.542949708252, 7116.146062472537, 7126.073610430611, 7180.533265337736, 7194.0, 7732.884006740016, 7800.609969501389, 7807.102183248999, 7826.099998500018, 7833.5691536966015, 6968.303169682991, 7043.2604576518725, 7091.184513780981, 7155.606441645759, 7214.567125972459, 6804.285550017375, 6953.304669533006, 6972.930270697431, 6995.79012629622, 7025.437964962893, 5192.896142077213, 6547.0179473079415, 6622.1722284714415, 6628.038934354395, 6646.335366463315, 4593.379893714306, 4628.861134165981, 4649.3026046093355, 4703.670743048091, 4778.641601879804, 3707.0, 4607.792649330721, 4754.619630429488, 4865.0, 4945.4312754033745], "value": 4341.670421346566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.55067673827625, 302.15396888711587, 309.3488207323583, 314.27402699763536, 314.5643284051588, 4711.492322456816, 4964.602831773458, 5063.8480845574695, 5166.689998600086, 5289.1917819170185, 4225.218334608092, 5062.0, 5062.772175252113, 5081.364829396325, 5108.489151084899, 3413.7439692023136, 3778.754380965242, 3794.8102594870284, 3905.2384784966944, 3929.76421414716, 3304.306095719902, 3307.891856228154, 3859.247446747885, 3989.4813674222455, 3999.7800120993334, 3441.6386279440676, 3889.8444062237477, 3946.4474973503734, 3954.782486963216, 3965.662918651918, 3756.530433695788, 3861.942070868939, 3886.261610294035, 3939.2515422069714, 3958.802059897008, 3486.1110416843794, 3490.7556471047037, 3500.4924285978645, 3500.649935006479, 3501.6848483636595, 3657.6780783959275, 4810.879728006793, 4969.478204788517, 4969.925451118236, 5016.2224855147515, 3453.188500702358, 4144.943039524932, 4493.370928070073, 4506.805696490433, 4593.63250939924, 4574.4968053514085, 5163.948360516422, 5197.8440646780655, 5248.895022099575, 5284.735763211824, 3653.355989804572, 3698.8890333290046, 3701.9259614807825, 3709.8330575124182, 3718.925621487555, 5496.917546236809, 5655.349634792011, 5703.0, 5738.512226460756, 5755.625884317506, 3460.602030766444, 3711.46183803347, 3731.552213734369, 3749.1564398010773, 3762.454444105644, 3206.214477453039, 3549.574051113831, 3814.427835824593, 3830.291396091732, 3858.82635281413, 3386.9322613548084, 3731.8880433587037, 3900.854530008484, 4461.902905634347, 4464.888377790581, 5237.921431178535, 5303.920441193385, 5443.495991843717, 5460.863478412992, 5577.498025177753, 1142.348861149137, 1163.528770847808, 1196.5034510677972, 1214.8056310990198, 1215.5806246844825, 717.9231153270094, 794.082834326354, 805.6737021506182, 807.725373373056, 814.7311387242196, 4745.833895813699, 4765.594924431392, 4854.5873600744335, 4859.052484765437, 4866.902661946741, 5263.736703191283, 5281.366236051698, 5283.618484170134, 5291.417944026154, 5347.946520534822, 5024.2463630454995, 5230.3985041719925, 5295.232191332235, 5309.522143007071, 5345.0, 2512.2212114244644, 2769.9446011079667, 2776.319801648609, 2786.4148528808773, 2878.7121287871464, 4741.652881823519, 5328.362460582037, 5369.067135831097, 5496.5053145217125, 5507.5593952483705, 5176.7411629418375, 5367.329083864517, 5377.462253774669, 5377.7580008899695, 5379.757910894019, 4516.390287311302, 4756.976215118972, 4931.161702510582, 5198.766055527511, 5432.891342173134, 4437.400950871591, 4921.876953076058, 5064.569511591409, 5160.174372100446, 5245.35447863731, 4263.3818096375235, 4939.876503087307, 4976.129177393869, 5173.137670438714, 5179.637425380187, 4494.0337827367275, 5124.12889808726, 5375.247465354775, 5396.8666580018635, 5524.751386187711, 3194.712475877089, 3510.78935263885, 3538.4161613333513, 3777.0934975605105, 3828.425736139601, 4960.652754307308, 5245.475452454724, 5247.763850626808, 5513.766924395626, 5560.805371812129, 5443.673379597237, 5472.562195024469, 5533.280673512418, 5534.723263836872, 5550.250716153268, 4406.0, 5312.973435132954, 5477.219903531448, 5508.91736623959, 5527.309086364205, 4446.844360447498, 4801.887169645345, 4833.444153922203, 5218.608604354613, 5384.273123128328, 4580.404547408815, 4805.0, 5265.763040663256, 5390.137577987503, 5537.861553461195, 5283.286756288006, 5349.251104845403, 5435.885643443164, 5452.509274165344, 5458.808941687022], "value": 3877.5374775820364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.1157364867563, 719.9956061294491, 730.3062091013537, 732.611715790631, 765.6401491299088, 14128.152310861355, 14274.785878211835, 14391.136531808099, 14425.422983080678, 14452.27738613069, 12651.24092554445, 12756.93621531891, 12761.553345632905, 12765.553205637805, 12774.552890648829, 638.7445021991194, 660.9173853268342, 789.1280135450331, 806.9556174410391, 818.2880893622556, 810.2828996338225, 812.0661239574478, 812.4353574265888, 813.9918600813975, 815.388458656008, 877.3463769491738, 877.9070057778075, 879.9824003519926, 880.2649787427506, 881.6958149438466, 675.7026908160418, 699.8810202265602, 701.554512884317, 702.1994925784559, 702.4661257444328, 627.2253766598247, 642.065794269337, 645.7255666341786, 648.4779752299397, 649.665422307512, 13296.867031329659, 13352.599422017354, 13365.933170334183, 13366.997475189302, 13446.52937147205, 11204.93553112453, 12812.55156069533, 12855.357232138449, 13056.412461439162, 13115.737685246242, 11612.419379031098, 11803.23278986863, 11951.0, 12148.3318417487, 12342.876571234352, 3753.549574051104, 4295.613394794453, 4325.659045695845, 4449.866504004885, 4464.977675111603, 84094.0, 86861.56569217113, 87450.0, 92960.53519732358, 93535.53232233864, 10215.846762298572, 10326.173906087495, 10426.0, 10530.947345263377, 10712.839307410395, 19605.31381401644, 19673.508162296057, 19705.0, 19780.50548736293, 19784.505387365432, 7240.420766338681, 7282.96358518204, 7387.445941554405, 7456.142543607446, 7692.230776922375, 4100.630943215125, 4142.896427589334, 4228.302330115558, 4253.425787518645, 4374.759388233678, 3184.5541624172633, 3222.194451387153, 3246.8376581170846, 3287.72054375376, 3291.045596776908, 594.3224723814855, 619.1146660275759, 619.8388419011089, 628.7736414890636, 633.9239291285076, 597.5727354941238, 600.639616230267, 615.1572345886165, 630.7350912616712, 637.9489640828723, 7706.576138312446, 8132.715354962548, 8215.671373145127, 8641.524716140548, 8658.653653853902, 534.4415086234894, 609.7926704920349, 616.0758861707438, 637.4645297949744, 640.8205702403242, 4154.667626589866, 4198.979005105016, 4288.399624052635, 4334.891627709269, 4426.535213802569, 73056.0, 96687.0, 109773.35339969907, 111140.0, 112872.0, 69880.0, 78527.0, 80897.0, 82410.0, 93591.0, 80928.0, 81738.59130704308, 82273.58863205646, 84369.0, 86465.13534864571, 8998.137802607076, 9691.778278414466, 9706.825811264454, 9733.377123577982, 9762.316637835283, 38443.038924027125, 46005.08956738715, 55589.44410556005, 57118.0, 58274.708626456595, 43493.91265218395, 53585.0, 65759.67120164368, 74636.25363746293, 75352.0, 11717.179797414441, 12079.094067945114, 12340.81488777651, 12523.874761252271, 12950.870491294967, 13760.93119534396, 13838.0, 13843.4462621494, 14476.927615361856, 15198.240087995777, 10641.840372394265, 12379.504819807467, 12387.69030774238, 12858.871411286142, 13282.203067815963, 12492.687682808002, 13072.477100915858, 13104.510143072786, 13208.669783255495, 13576.59270221895, 11644.2431241969, 12462.189957652723, 12621.305828179538, 12942.611721648365, 12962.0, 10845.0, 11118.666440006813, 11297.830532541853, 11408.771824563628, 11421.314721116427, 8329.583520823813, 9513.286503512127, 9972.202223822222, 11456.656300311004, 11604.245724027911], "value": 7056.205788028664, "unit": "score"}], "name": "dromaeo_css", "value": 7088.678335247787, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 114.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 114.0, 148.0, 117.0, 115.0, 114.0, 115.0, 144.0, 114.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 183.0, 186.0, 185.0, 182.0, 184.0, 184.0, 184.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 88.0, 62.0, 59.0, 60.0, 48.0, 83.0, 73.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 94.0, 69.0, 68.0, 71.0, 118.0, 98.0, 101.0, 91.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 89.0, 89.0, 85.0, 89.0, 86.0, 88.0, 91.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 93.0, 93.0, 94.0, 95.0, 94.0, 93.0, 95.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 98.0, 130.0, 82.0, 84.0, 84.0, 83.0, 82.0, 82.0, 85.0], "value": 89.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 55.0, 53.0, 56.0, 58.0, 58.0, 56.0, 57.0, 57.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 64.0, 76.0, 72.0, 69.0, 70.0, 71.0, 71.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 84.0, 85.0, 85.0, 85.0, 89.0, 87.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 168.0, 156.0, 169.0, 158.0, 156.0, 161.0, 197.0, 154.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 174.0, 169.0, 166.0, 170.0, 169.0, 166.0, 169.0, 168.0, 166.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1457.8, "alertThreshold": 2.0}]} 09:16:54 INFO - Return code: 0 09:16:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:16:54 INFO - # TBPL SUCCESS # 09:16:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:54 INFO - 2016-12-06 09:16:54.983 screenresolution[1650:9745] starting screenresolution argv=screenresolution get 09:16:54 INFO - 2016-12-06 09:16:54.988 screenresolution[1650:9745] Display 0: 1600x1200x32@60 09:16:54 INFO - 2016-12-06 09:16:54.994 screenresolution[1651:9749] starting screenresolution argv=screenresolution list 09:16:54 INFO - Available Modes on Display 0 09:16:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:55 INFO - Intel Iris: 09:16:55 INFO - Chipset Model: Intel Iris 09:16:55 INFO - Type: GPU 09:16:55 INFO - Bus: Built-In 09:16:55 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:55 INFO - Vendor: Intel (0x8086) 09:16:55 INFO - Device ID: 0x0a2e 09:16:55 INFO - Revision ID: 0x0009 09:16:55 INFO - Displays: 09:16:55 INFO - Display: 09:16:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:55 INFO - Display Serial Number: 1600x1200 60 09:16:55 INFO - Main Display: Yes 09:16:55 INFO - Mirror: Off 09:16:55 INFO - Online: Yes 09:16:55 INFO - Rotation: Supported 09:16:55 INFO - Return code: 0 09:16:55 INFO - Running post-action listener: _resource_record_post_action 09:16:55 INFO - [mozharness: 2016-12-06 17:16:55.238981Z] Finished run-tests step (success) 09:16:55 INFO - Running post-run listener: _resource_record_post_run 09:16:55 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 36922368; Write bytes: 660250624; Read time: 374; Write time: 1898 09:16:55 INFO - TinderboxPrint: CPU usage
53.5% 09:16:55 INFO - TinderboxPrint: I/O read bytes / time
36,922,368 / 374 09:16:55 INFO - TinderboxPrint: I/O write bytes / time
660,250,624 / 1,898 09:16:55 INFO - TinderboxPrint: CPU idle
2,256.8 (46.5%) 09:16:55 INFO - TinderboxPrint: CPU system
972.6 (20.0%) 09:16:55 INFO - TinderboxPrint: CPU user
1,624.3 (33.5%) 09:16:55 INFO - TinderboxPrint: Swap in / out
385,376,256 / 0 09:16:55 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 344836608; Write bytes: 346138624; Read time: 26849; Write time: 692 09:16:55 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 34903040; Write bytes: 307136512; Read time: 353; Write time: 1178 09:16:55 INFO - Running post-run listener: _upload_blobber_files 09:16:55 INFO - Blob upload gear active. 09:16:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:16:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:16:56 INFO - (blobuploader) - INFO - Open directory for files ... 09:16:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:16:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:16:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:57 INFO - (blobuploader) - INFO - Done attempting. 09:16:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:16:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:16:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:58 INFO - (blobuploader) - INFO - Done attempting. 09:16:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:16:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:01 INFO - (blobuploader) - INFO - Done attempting. 09:17:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:01 INFO - Return code: 0 09:17:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed76092fa948c3855fda1347bb34b8987096fa32b79c25378c43eec854f32c493b34ea9a1af08f65e904f09c796d3d6fc4660ba378d7ed5781ee7100b8a89894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50c5003e8ed3e59555a5d2fe334515389b6e26e425624f207f3a4bbcadb5119490599caa66370f84487d4c404f6ed40de5eb7f26abd369d7f606b7960ef9f10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8bcf265bb36981ca92b49e7cb4fc1b924da1fba77f0ef7e321b4c964993a7c81b26586561487c2fa491d67ac68b9fe3e59586c2da4f784b9658abffe5adf21f"} 09:17:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:01 INFO - Contents: 09:17:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed76092fa948c3855fda1347bb34b8987096fa32b79c25378c43eec854f32c493b34ea9a1af08f65e904f09c796d3d6fc4660ba378d7ed5781ee7100b8a89894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50c5003e8ed3e59555a5d2fe334515389b6e26e425624f207f3a4bbcadb5119490599caa66370f84487d4c404f6ed40de5eb7f26abd369d7f606b7960ef9f10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8bcf265bb36981ca92b49e7cb4fc1b924da1fba77f0ef7e321b4c964993a7c81b26586561487c2fa491d67ac68b9fe3e59586c2da4f784b9658abffe5adf21f"} 09:17:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:01 INFO - Copying logs to upload dir... 09:17:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.835005 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-06 09:17:01.143602) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 09:17:01.147749) ========= 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.OlRqCdBje3/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.8AIXJOeMPT/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed76092fa948c3855fda1347bb34b8987096fa32b79c25378c43eec854f32c493b34ea9a1af08f65e904f09c796d3d6fc4660ba378d7ed5781ee7100b8a89894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50c5003e8ed3e59555a5d2fe334515389b6e26e425624f207f3a4bbcadb5119490599caa66370f84487d4c404f6ed40de5eb7f26abd369d7f606b7960ef9f10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8bcf265bb36981ca92b49e7cb4fc1b924da1fba77f0ef7e321b4c964993a7c81b26586561487c2fa491d67ac68b9fe3e59586c2da4f784b9658abffe5adf21f"} build_url:https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011028 build_url: 'https://queue.taskcluster.net/v1/task/TDdtOjubRyS8XcUQ-TSStA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed76092fa948c3855fda1347bb34b8987096fa32b79c25378c43eec854f32c493b34ea9a1af08f65e904f09c796d3d6fc4660ba378d7ed5781ee7100b8a89894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50c5003e8ed3e59555a5d2fe334515389b6e26e425624f207f3a4bbcadb5119490599caa66370f84487d4c404f6ed40de5eb7f26abd369d7f606b7960ef9f10b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8bcf265bb36981ca92b49e7cb4fc1b924da1fba77f0ef7e321b4c964993a7c81b26586561487c2fa491d67ac68b9fe3e59586c2da4f784b9658abffe5adf21f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 09:17:01.191671) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:17:01.192317) ========= 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.OlRqCdBje3/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.8AIXJOeMPT/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.004988 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:17:01.243422) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 09:17:01.243993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 09:17:01.244606) ========= ========= Total master_lag: 0.20 =========