builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0258 starttime: 1483588163.54 results: success (0) buildid: 20170104184138 builduid: 6d80230c4e744a068a45722c6a48191a revision: 23eae10f94cb96e2d9abe166e073fb0584c8bc3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.543310) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.543843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.544152) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.576399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.576870) ========= 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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 19:49:23-- 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% 159M=0s 2017-01-04 19:49:23 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.722188) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.725644) ========= 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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.027815 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:23.775200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 19:49:23.778727) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 23eae10f94cb96e2d9abe166e073fb0584c8bc3c --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 23eae10f94cb96e2d9abe166e073fb0584c8bc3c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NkCGNRwv5w/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.yCjnNcVJR7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 19:49:23,877 truncating revision to first 12 chars 2017-01-04 19:49:23,877 Setting DEBUG logging. 2017-01-04 19:49:23,877 attempt 1/10 2017-01-04 19:49:23,877 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/23eae10f94cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 19:49:24,911 unpacking tar archive at: mozilla-aurora-23eae10f94cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.308970 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 19:49:25.114670) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:25.115121) ========= script_repo_revision: 23eae10f94cb96e2d9abe166e073fb0584c8bc3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:25.115575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:25.115853) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 19:49:25.136024) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-04 19:49:25.136416) ========= /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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:49:25 INFO - MultiFileLogger online at 20170104 19:49:25 in /builds/slave/test 19:49:25 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 19:49:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:49:25 INFO - {'append_to_log': False, 19:49:25 INFO - 'base_work_dir': '/builds/slave/test', 19:49:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:49:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:49:25 INFO - 'branch': 'Mozilla-Aurora', 19:49:25 INFO - 'buildbot_json_path': 'buildprops.json', 19:49:25 INFO - 'config_files': ('talos/mac_config.py',), 19:49:25 INFO - 'default_actions': ('clobber', 19:49:25 INFO - 'read-buildbot-config', 19:49:25 INFO - 'download-and-extract', 19:49:25 INFO - 'populate-webroot', 19:49:25 INFO - 'create-virtualenv', 19:49:25 INFO - 'install', 19:49:25 INFO - 'run-tests'), 19:49:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:49:25 INFO - 'download_minidump_stackwalk': True, 19:49:25 INFO - 'download_symbols': 'ondemand', 19:49:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:49:25 INFO - 'tooltool.py': '/tools/tooltool.py', 19:49:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:49:25 INFO - '/tools/misc-python/virtualenv.py')}, 19:49:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:49:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:49:25 INFO - 'installer_path': 'installer.exe', 19:49:25 INFO - 'log_level': 'info', 19:49:25 INFO - 'log_name': 'talos', 19:49:25 INFO - 'log_to_console': True, 19:49:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:49:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:49:25 INFO - 'opt_config_files': (), 19:49:25 INFO - 'pip_index': False, 19:49:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:49:25 INFO - 'cmd': ('bash', 19:49:25 INFO - '-c', 19:49:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:49:25 INFO - 'enabled': True, 19:49:25 INFO - 'halt_on_failure': False, 19:49:25 INFO - 'name': 'check_screen_resolution'},), 19:49:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:49:25 INFO - 'cmd': ('bash', 19:49:25 INFO - '-c', 19:49:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:49:25 INFO - 'enabled': True, 19:49:25 INFO - 'halt_on_failure': False, 19:49:25 INFO - 'name': 'check_screen_resolution'},), 19:49:25 INFO - 'run_cmd_checks_enabled': True, 19:49:25 INFO - 'sps_profile': False, 19:49:25 INFO - 'sps_profile_interval': 0, 19:49:25 INFO - 'suite': 'dromaeojs', 19:49:25 INFO - 'system_bits': '32', 19:49:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:49:25 INFO - 'title': 't-yosemite-r7-0258', 19:49:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:49:25 INFO - 'use_talos_json': True, 19:49:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:49:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:49:25 INFO - 'work_dir': 'build'} 19:49:25 INFO - [mozharness: 2017-01-05 03:49:25.340454Z] Running clobber step. 19:49:25 INFO - Running pre-action listener: _resource_record_pre_action 19:49:25 INFO - Running main action method: clobber 19:49:25 INFO - rmtree: /builds/slave/test/build 19:49:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:49:27 INFO - Running post-action listener: _resource_record_post_action 19:49:27 INFO - [mozharness: 2017-01-05 03:49:27.824193Z] Finished clobber step (success) 19:49:27 INFO - [mozharness: 2017-01-05 03:49:27.824321Z] Running read-buildbot-config step. 19:49:27 INFO - Running pre-action listener: _resource_record_pre_action 19:49:27 INFO - Running main action method: read_buildbot_config 19:49:27 INFO - Using buildbot properties: 19:49:27 INFO - { 19:49:27 INFO - "project": "", 19:49:27 INFO - "product": "firefox", 19:49:27 INFO - "script_repo_revision": "production", 19:49:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 19:49:27 INFO - "repository": "", 19:49:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 19:49:27 INFO - "buildid": "20170104184138", 19:49:27 INFO - "pgo_build": "False", 19:49:27 INFO - "basedir": "/builds/slave/test", 19:49:27 INFO - "buildnumber": 13, 19:49:27 INFO - "slavename": "t-yosemite-r7-0258", 19:49:27 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:49:27 INFO - "platform": "yosemite_r7", 19:49:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:49:27 INFO - "branch": "mozilla-aurora", 19:49:27 INFO - "repo_path": "releases/mozilla-aurora", 19:49:27 INFO - "revision": "23eae10f94cb96e2d9abe166e073fb0584c8bc3c", 19:49:27 INFO - "stage_platform": "macosx64", 19:49:27 INFO - "builduid": "6d80230c4e744a068a45722c6a48191a", 19:49:27 INFO - "slavebuilddir": "test" 19:49:27 INFO - } 19:49:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 19:49:27 INFO - Running post-action listener: _resource_record_post_action 19:49:27 INFO - [mozharness: 2017-01-05 03:49:27.827544Z] Finished read-buildbot-config step (success) 19:49:27 INFO - [mozharness: 2017-01-05 03:49:27.827679Z] Running download-and-extract step. 19:49:27 INFO - Running pre-action listener: _resource_record_pre_action 19:49:27 INFO - Running main action method: download_and_extract 19:49:27 INFO - mkdir: /builds/slave/test/build/tests 19:49:27 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')]} 19:49:27 INFO - trying https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 19:49:27 INFO - Downloading https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/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 19:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/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 19:49:29 INFO - Downloaded 1199 bytes. 19:49:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:49:29 INFO - Using the following test package requirements: 19:49:29 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:49:29 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 19:49:29 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 19:49:29 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'jsshell-mac.zip'], 19:49:29 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 19:49:29 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:49:29 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 19:49:29 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 19:49:29 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 19:49:29 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:49:29 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 19:49:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:49:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:49:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:49:29 INFO - Fetch https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:49:29 INFO - Http code: 200 19:49:29 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 19:49:29 INFO - x-amz-cf-id: 0_oqRIjDcPsGZmMAciI8i-YSBm72svBKoG5XCMlYDkHR_CrEzBwaWQ== 19:49:29 INFO - x-amz-version-id: MiNsWRz5eAP1PVP.G4cL4.RKdgMdjs.q 19:49:29 INFO - Expected file size: 16269653 19:49:29 INFO - Obtained file size: 16269653 19:49:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:49:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:49:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:49:36 INFO - Fetch https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:49:37 INFO - Http code: 200 19:49:37 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 19:49:37 INFO - x-amz-cf-id: Ndtfc67j5yV8YFbt1sHxCBKLBzx2xWs9IlJqyLwPUi2zms1Fb1LPuA== 19:49:37 INFO - x-amz-version-id: MiNsWRz5eAP1PVP.G4cL4.RKdgMdjs.q 19:49:37 INFO - Expected file size: 16269653 19:49:37 INFO - Obtained file size: 16269653 19:49:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 19:49:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:49:43 INFO - Fetch https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 19:49:44 INFO - Http code: 200 19:49:44 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 19:49:44 INFO - x-amz-cf-id: lIR_iLIzBkmrQ85EyglaEI_pdot6lgu_pkSKQ-2kiCUm9sC1BGlL_w== 19:49:44 INFO - x-amz-version-id: _e_TUznLI2RFi4VwrRqKH6Yew0n_y3fM 19:49:44 INFO - Expected file size: 14409038 19:49:44 INFO - Obtained file size: 14409038 19:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:49:45 INFO - trying https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:49:45 INFO - Downloading https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 19:49:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/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 19:49:48 INFO - Downloaded 109837502 bytes. 19:49:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:49:48 INFO - mkdir: /builds/slave/test/properties 19:49:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:49:48 INFO - Writing to file /builds/slave/test/properties/build_url 19:49:48 INFO - Contents: 19:49:48 INFO - build_url:https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:49:48 INFO - Running post-action listener: _resource_record_post_action 19:49:48 INFO - Running post-action listener: set_extra_try_arguments 19:49:48 INFO - [mozharness: 2017-01-05 03:49:48.702580Z] Finished download-and-extract step (success) 19:49:48 INFO - [mozharness: 2017-01-05 03:49:48.702675Z] Running populate-webroot step. 19:49:48 INFO - Running pre-action listener: _resource_record_pre_action 19:49:48 INFO - Running main action method: populate_webroot 19:49:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:49:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:49:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:49:48 INFO - '--tppagecycles', 19:49:48 INFO - '7'], 19:49:48 INFO - 'tests': ['tsvgm']}}, 19:49:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:49:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:49:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:49:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:49:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:49:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:49:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['damp', 'tps']}, 19:49:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'tests': ['damp', 'tps']}, 19:49:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['dromaeo_dom']}, 19:49:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:49:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['basic_compositor_video']}, 19:49:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:49:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['a11yr', 19:49:48 INFO - 'ts_paint', 19:49:48 INFO - 'tpaint', 19:49:48 INFO - 'sessionrestore', 19:49:48 INFO - 'sessionrestore_no_auto_restore', 19:49:48 INFO - 'tabpaint']}, 19:49:48 INFO - 'other-e10s': {'tests': ['a11yr', 19:49:48 INFO - 'ts_paint', 19:49:48 INFO - 'tpaint', 19:49:48 INFO - 'sessionrestore', 19:49:48 INFO - 'sessionrestore_no_auto_restore', 19:49:48 INFO - 'tabpaint']}, 19:49:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:49:48 INFO - 'ts_paint', 19:49:48 INFO - 'tpaint', 19:49:48 INFO - 'sessionrestore', 19:49:48 INFO - 'sessionrestore_no_auto_restore', 19:49:48 INFO - 'tabpaint']}, 19:49:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:49:48 INFO - 'ts_paint', 19:49:48 INFO - 'tpaint', 19:49:48 INFO - 'sessionrestore', 19:49:48 INFO - 'sessionrestore_no_auto_restore', 19:49:48 INFO - 'tabpaint']}, 19:49:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['a11yr', 19:49:48 INFO - 'ts_paint', 19:49:48 INFO - 'tpaint', 19:49:48 INFO - 'sessionrestore', 19:49:48 INFO - 'sessionrestore_no_auto_restore', 19:49:48 INFO - 'tabpaint']}, 19:49:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['a11yr', 19:49:48 INFO - 'ts_paint', 19:49:48 INFO - 'tpaint', 19:49:48 INFO - 'sessionrestore', 19:49:48 INFO - 'sessionrestore_no_auto_restore', 19:49:48 INFO - 'tabpaint']}, 19:49:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['tsvgx', 19:49:48 INFO - 'tsvgr_opacity', 19:49:48 INFO - 'tart', 19:49:48 INFO - 'tscrollx', 19:49:48 INFO - 'cart']}, 19:49:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:49:48 INFO - 'tsvgr_opacity', 19:49:48 INFO - 'tart', 19:49:48 INFO - 'tscrollx', 19:49:48 INFO - 'cart']}, 19:49:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'talos_options': ['--disable-e10s'], 19:49:48 INFO - 'tests': ['tp5o']}, 19:49:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'tests': ['tp5o']}, 19:49:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'talos_options': ['--disable-e10s', 19:49:48 INFO - '--xperf_path', 19:49:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:49:48 INFO - 'tests': ['tp5n']}, 19:49:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:49:48 INFO - 'talos_options': ['--xperf_path', 19:49:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:49:48 INFO - 'tests': ['tp5n']}}, 19:49:48 INFO - 'talos.zip': {'path': '', 19:49:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:49:48 INFO - Running post-action listener: _resource_record_post_action 19:49:48 INFO - [mozharness: 2017-01-05 03:49:48.721103Z] Finished populate-webroot step (success) 19:49:48 INFO - [mozharness: 2017-01-05 03:49:48.721234Z] Running create-virtualenv step. 19:49:48 INFO - Running pre-action listener: _resource_record_pre_action 19:49:48 INFO - Running main action method: create_virtualenv 19:49:48 INFO - Creating virtualenv /builds/slave/test/build/venv 19:49:48 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 19:49:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:49:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:49:48 INFO - Using real prefix '/tools/python27' 19:49:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:49:49 INFO - Installing distribute.............................................................................................................................................................................................done. 19:49:52 INFO - Installing pip.................done. 19:49:52 INFO - Return code: 0 19:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:49:52 INFO - Reading from file tmpfile_stdout 19:49:52 INFO - Output received: 19:49:52 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) 19:49:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:49:52 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')]} 19:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:52 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')]} 19:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:52 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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:49:52 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 19:49:52 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 19:49:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:49:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:52 INFO - 'HOME': '/Users/cltbld', 19:49:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:52 INFO - 'LOGNAME': 'cltbld', 19:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:52 INFO - 'MOZ_NO_REMOTE': '1', 19:49:52 INFO - 'NO_EM_RESTART': '1', 19:49:52 INFO - 'PAGER': '/bin/cat', 19:49:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:52 INFO - 'PWD': '/builds/slave/test', 19:49:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:52 INFO - 'SHELL': '/bin/bash', 19:49:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:49:52 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:49:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:52 INFO - 'USER': 'cltbld', 19:49:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:52 INFO - 'XPC_FLAGS': '0x0', 19:49:52 INFO - 'XPC_SERVICE_NAME': '0', 19:49:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:52 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 19:49:52 INFO - Cleaning up... 19:49:52 INFO - Return code: 0 19:49:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:49:52 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')]} 19:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:52 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')]} 19:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:52 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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:49:52 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 19:49:52 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 19:49:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:49:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:52 INFO - 'HOME': '/Users/cltbld', 19:49:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:52 INFO - 'LOGNAME': 'cltbld', 19:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:52 INFO - 'MOZ_NO_REMOTE': '1', 19:49:52 INFO - 'NO_EM_RESTART': '1', 19:49:52 INFO - 'PAGER': '/bin/cat', 19:49:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:52 INFO - 'PWD': '/builds/slave/test', 19:49:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:52 INFO - 'SHELL': '/bin/bash', 19:49:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:49:52 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:49:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:52 INFO - 'USER': 'cltbld', 19:49:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:52 INFO - 'XPC_FLAGS': '0x0', 19:49:52 INFO - 'XPC_SERVICE_NAME': '0', 19:49:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:53 INFO - Downloading/unpacking psutil>=3.1.1 19:49:53 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 19:49:53 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 19:49:53 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 19:49:53 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 19:49:53 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 19:49:53 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 19:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:49:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:49:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:49:56 INFO - Installing collected packages: psutil 19:49:56 INFO - Running setup.py install for psutil 19:49:56 INFO - building 'psutil._psutil_osx' extension 19:49:56 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 19:49:57 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 19:49:57 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 19:49:57 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 19:49:57 INFO - building 'psutil._psutil_posix' extension 19:49:57 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 19:49:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:49:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:49:57 INFO - ^ 19:49:57 INFO - 1 warning generated. 19:49:57 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 19:49:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:49:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:49:57 INFO - Successfully installed psutil 19:49:57 INFO - Cleaning up... 19:49:57 INFO - Return code: 0 19:49:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:49:57 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')]} 19:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:57 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')]} 19:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:57 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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:49:57 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 19:49:57 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 19:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:49:57 INFO - 'HOME': '/Users/cltbld', 19:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:49:57 INFO - 'LOGNAME': 'cltbld', 19:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:57 INFO - 'MOZ_NO_REMOTE': '1', 19:49:57 INFO - 'NO_EM_RESTART': '1', 19:49:57 INFO - 'PAGER': '/bin/cat', 19:49:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:57 INFO - 'PWD': '/builds/slave/test', 19:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:49:57 INFO - 'SHELL': '/bin/bash', 19:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:49:57 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:49:57 INFO - 'USER': 'cltbld', 19:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:57 INFO - 'XPC_FLAGS': '0x0', 19:49:57 INFO - 'XPC_SERVICE_NAME': '0', 19:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:49:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:49:58 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 19:49:58 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 19:49:58 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 19:49:58 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 19:49:58 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 19:49:58 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 19:50:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:50:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:50:00 INFO - Installing collected packages: mozsystemmonitor 19:50:00 INFO - Running setup.py install for mozsystemmonitor 19:50:00 INFO - Successfully installed mozsystemmonitor 19:50:00 INFO - Cleaning up... 19:50:01 INFO - Return code: 0 19:50:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:01 INFO - 'HOME': '/Users/cltbld', 19:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:01 INFO - 'LOGNAME': 'cltbld', 19:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:01 INFO - 'MOZ_NO_REMOTE': '1', 19:50:01 INFO - 'NO_EM_RESTART': '1', 19:50:01 INFO - 'PAGER': '/bin/cat', 19:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:01 INFO - 'PWD': '/builds/slave/test', 19:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:01 INFO - 'SHELL': '/bin/bash', 19:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:01 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:01 INFO - 'USER': 'cltbld', 19:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:01 INFO - 'XPC_FLAGS': '0x0', 19:50:01 INFO - 'XPC_SERVICE_NAME': '0', 19:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:01 INFO - Downloading/unpacking jsonschema==2.5.1 19:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:50:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:50:03 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 19:50:03 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 19:50:03 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 19:50:03 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 19:50:03 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 19:50:03 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 19:50:04 INFO - Downloading functools32-3.2.3-2.tar.gz 19:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:50:04 INFO - warning: no files found matching '*.txt' 19:50:04 INFO - no previously-included directories found matching 'build' 19:50:04 INFO - no previously-included directories found matching 'dist' 19:50:04 INFO - no previously-included directories found matching '.git*' 19:50:04 INFO - Installing collected packages: jsonschema, functools32 19:50:04 INFO - Running setup.py install for functools32 19:50:04 INFO - warning: no files found matching '*.txt' 19:50:04 INFO - no previously-included directories found matching 'build' 19:50:04 INFO - no previously-included directories found matching 'dist' 19:50:04 INFO - no previously-included directories found matching '.git*' 19:50:04 INFO - Successfully installed jsonschema functools32 19:50:04 INFO - Cleaning up... 19:50:04 INFO - Return code: 0 19:50:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:50: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')]} 19:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50: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')]} 19:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:04 INFO - 'HOME': '/Users/cltbld', 19:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:04 INFO - 'LOGNAME': 'cltbld', 19:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:04 INFO - 'MOZ_NO_REMOTE': '1', 19:50:04 INFO - 'NO_EM_RESTART': '1', 19:50:04 INFO - 'PAGER': '/bin/cat', 19:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:04 INFO - 'PWD': '/builds/slave/test', 19:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:04 INFO - 'SHELL': '/bin/bash', 19:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:04 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:04 INFO - 'USER': 'cltbld', 19:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:04 INFO - 'XPC_FLAGS': '0x0', 19:50:04 INFO - 'XPC_SERVICE_NAME': '0', 19:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:50:04 INFO - Cleaning up... 19:50:04 INFO - Return code: 0 19:50:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:50: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')]} 19:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50: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')]} 19:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:04 INFO - 'HOME': '/Users/cltbld', 19:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:04 INFO - 'LOGNAME': 'cltbld', 19:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:04 INFO - 'MOZ_NO_REMOTE': '1', 19:50:04 INFO - 'NO_EM_RESTART': '1', 19:50:04 INFO - 'PAGER': '/bin/cat', 19:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:04 INFO - 'PWD': '/builds/slave/test', 19:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:04 INFO - 'SHELL': '/bin/bash', 19:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:04 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:04 INFO - 'USER': 'cltbld', 19:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:04 INFO - 'XPC_FLAGS': '0x0', 19:50:04 INFO - 'XPC_SERVICE_NAME': '0', 19:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:05 INFO - Downloading/unpacking blobuploader==1.2.4 19:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:07 INFO - Downloading blobuploader-1.2.4.tar.gz 19:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:50:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:50:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:50:08 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 19:50:08 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 19:50:08 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 19:50:08 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 19:50:08 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 19:50:08 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 19:50:09 INFO - Downloading docopt-0.6.1.tar.gz 19:50:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:50:09 INFO - Installing collected packages: blobuploader, requests, docopt 19:50:09 INFO - Running setup.py install for blobuploader 19:50:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:50:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:50:09 INFO - Running setup.py install for requests 19:50:09 INFO - Running setup.py install for docopt 19:50:09 INFO - Successfully installed blobuploader requests docopt 19:50:09 INFO - Cleaning up... 19:50:10 INFO - Return code: 0 19:50:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:10 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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:10 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 19:50:10 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 19:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:10 INFO - 'HOME': '/Users/cltbld', 19:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:10 INFO - 'LOGNAME': 'cltbld', 19:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:10 INFO - 'MOZ_NO_REMOTE': '1', 19:50:10 INFO - 'NO_EM_RESTART': '1', 19:50:10 INFO - 'PAGER': '/bin/cat', 19:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:10 INFO - 'PWD': '/builds/slave/test', 19:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:10 INFO - 'SHELL': '/bin/bash', 19:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:10 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:10 INFO - 'USER': 'cltbld', 19:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:10 INFO - 'XPC_FLAGS': '0x0', 19:50:10 INFO - 'XPC_SERVICE_NAME': '0', 19:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-rNT08x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-wvXTZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-seaPiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-oHDSNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-wlypMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-QEobri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:50:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:50:10 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-3mR1PY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-VpZbiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-CgjeZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-IvAHGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-nm83Ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-GqB0nE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ATlRh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-3l1iMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-VS5cgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-2rhCTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:50:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:50:11 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-lz6ctM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:50:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:50:12 INFO - Running setup.py install for manifestparser 19:50:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:50:12 INFO - Running setup.py install for mozcrash 19:50:12 INFO - Running setup.py install for mozdebug 19:50:12 INFO - Running setup.py install for mozdevice 19:50:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:50:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:50:12 INFO - Running setup.py install for mozfile 19:50:12 INFO - Running setup.py install for mozhttpd 19:50:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:50:12 INFO - Running setup.py install for mozinfo 19:50:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Running setup.py install for mozInstall 19:50:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Running setup.py install for mozleak 19:50:13 INFO - Running setup.py install for mozlog 19:50:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Running setup.py install for moznetwork 19:50:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:50:13 INFO - Running setup.py install for mozprocess 19:50:13 INFO - Running setup.py install for mozprofile 19:50:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:50:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:50:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:50:14 INFO - Running setup.py install for mozrunner 19:50:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:50:14 INFO - Running setup.py install for mozscreenshot 19:50:14 INFO - Running setup.py install for moztest 19:50:14 INFO - Running setup.py install for mozversion 19:50:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:50:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:50:14 INFO - Cleaning up... 19:50:14 INFO - Return code: 0 19:50:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:14 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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:14 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 19:50:14 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 19:50:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:14 INFO - 'HOME': '/Users/cltbld', 19:50:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:14 INFO - 'LOGNAME': 'cltbld', 19:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:14 INFO - 'MOZ_NO_REMOTE': '1', 19:50:14 INFO - 'NO_EM_RESTART': '1', 19:50:14 INFO - 'PAGER': '/bin/cat', 19:50:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:14 INFO - 'PWD': '/builds/slave/test', 19:50:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:14 INFO - 'SHELL': '/bin/bash', 19:50:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:14 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:14 INFO - 'USER': 'cltbld', 19:50:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:14 INFO - 'XPC_FLAGS': '0x0', 19:50:14 INFO - 'XPC_SERVICE_NAME': '0', 19:50:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:50:14 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-W8teUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-va_0xL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-qZD_Hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ZXvK7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-XMgdDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-46jLFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-huMhte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-XHuBNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-LTf5LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Q6L5hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:50:15 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)) 19:50:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:50:15 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-dZelAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:50:16 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)) 19:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:50:16 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ekdfoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:50:16 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)) 19:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:50:16 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-hTzpqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:50:16 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)) 19:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:50:16 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-JE4puj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:50:16 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)) 19:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:50:16 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-AkPEsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:50:16 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)) 19:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:50:16 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Aby456-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:50:16 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-l6BKuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:50:16 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)) 19:50:16 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)) 19:50:16 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)) 19:50:16 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)) 19:50:16 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)) 19:50:16 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)) 19:50:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:50:16 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 19:50:16 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 19:50:16 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 19:50:16 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 19:50:16 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 19:50:16 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 19:50:19 INFO - Downloading blessings-1.6.tar.gz 19:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:50:19 INFO - Installing collected packages: blessings 19:50:19 INFO - Running setup.py install for blessings 19:50:19 INFO - Successfully installed blessings 19:50:19 INFO - Cleaning up... 19:50:19 INFO - Return code: 0 19:50:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:50:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:50:19 INFO - Reading from file tmpfile_stdout 19:50:19 INFO - Current package versions: 19:50:19 INFO - blessings == 1.6 19:50:19 INFO - blobuploader == 1.2.4 19:50:19 INFO - docopt == 0.6.1 19:50:19 INFO - functools32 == 3.2.3-2 19:50:19 INFO - jsonschema == 2.5.1 19:50:19 INFO - manifestparser == 1.1 19:50:19 INFO - mozInstall == 1.12 19:50:19 INFO - mozcrash == 1.0 19:50:19 INFO - mozdebug == 0.1 19:50:19 INFO - mozdevice == 0.48 19:50:19 INFO - mozfile == 1.2 19:50:19 INFO - mozhttpd == 0.7 19:50:19 INFO - mozinfo == 0.9 19:50:19 INFO - mozleak == 0.1 19:50:19 INFO - mozlog == 3.4 19:50:19 INFO - moznetwork == 0.27 19:50:19 INFO - mozprocess == 0.23 19:50:19 INFO - mozprofile == 0.28 19:50:19 INFO - mozrunner == 6.13 19:50:19 INFO - mozscreenshot == 0.1 19:50:19 INFO - mozsystemmonitor == 0.3 19:50:19 INFO - moztest == 0.8 19:50:19 INFO - mozversion == 1.4 19:50:19 INFO - psutil == 3.1.1 19:50:19 INFO - requests == 1.2.3 19:50:19 INFO - wsgiref == 0.1.2 19:50:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:50:19 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')]} 19:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50: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')]} 19:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:20 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 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:20 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 19:50:20 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 19:50:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:20 INFO - 'HOME': '/Users/cltbld', 19:50:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:20 INFO - 'LOGNAME': 'cltbld', 19:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:20 INFO - 'MOZ_NO_REMOTE': '1', 19:50:20 INFO - 'NO_EM_RESTART': '1', 19:50:20 INFO - 'PAGER': '/bin/cat', 19:50:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:20 INFO - 'PWD': '/builds/slave/test', 19:50:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:20 INFO - 'SHELL': '/bin/bash', 19:50:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:20 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:20 INFO - 'USER': 'cltbld', 19:50:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:20 INFO - 'XPC_FLAGS': '0x0', 19:50:20 INFO - 'XPC_SERVICE_NAME': '0', 19:50:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 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)) 19:50:20 INFO - Cleaning up... 19:50:20 INFO - Return code: 0 19:50:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:50: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')]} 19:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50: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')]} 19:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:50:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10723c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee34b6bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, '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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 19:50:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:50:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:21 INFO - 'HOME': '/Users/cltbld', 19:50:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:21 INFO - 'LOGNAME': 'cltbld', 19:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:21 INFO - 'MOZ_NO_REMOTE': '1', 19:50:21 INFO - 'NO_EM_RESTART': '1', 19:50:21 INFO - 'PAGER': '/bin/cat', 19:50:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:21 INFO - 'PWD': '/builds/slave/test', 19:50:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:21 INFO - 'SHELL': '/bin/bash', 19:50:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:21 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:21 INFO - 'USER': 'cltbld', 19:50:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:21 INFO - 'XPC_FLAGS': '0x0', 19:50:21 INFO - 'XPC_SERVICE_NAME': '0', 19:50:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:50:21 INFO - Cleaning up... 19:50:21 INFO - Return code: 0 19:50:21 INFO - Running post-action listener: _resource_record_post_action 19:50:21 INFO - Running post-action listener: _start_resource_monitoring 19:50:21 INFO - Starting resource monitoring. 19:50:21 INFO - [mozharness: 2017-01-05 03:50:21.471355Z] Finished create-virtualenv step (success) 19:50:21 INFO - [mozharness: 2017-01-05 03:50:21.472307Z] Running install step. 19:50:21 INFO - Running pre-action listener: _resource_record_pre_action 19:50:21 INFO - Running main action method: install 19:50:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:50:21 INFO - Reading from file tmpfile_stdout 19:50:21 INFO - Detecting whether we're running mozinstall >=1.0... 19:50:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:50:21 INFO - Reading from file tmpfile_stdout 19:50:21 INFO - Output received: 19:50:21 INFO - Usage: mozinstall [options] installer 19:50:21 INFO - Options: 19:50:21 INFO - -h, --help show this help message and exit 19:50:21 INFO - -d DEST, --destination=DEST 19:50:21 INFO - Directory to install application into. [default: 19:50:21 INFO - "/builds/slave/test"] 19:50:21 INFO - --app=APP Application being installed. [default: firefox] 19:50:21 INFO - mkdir: /builds/slave/test/build/application 19:50:21 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'] 19:50:21 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 19:50:52 INFO - Reading from file tmpfile_stdout 19:50:52 INFO - Output received: 19:50:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:50:52 INFO - Running post-action listener: _resource_record_post_action 19:50:52 INFO - [mozharness: 2017-01-05 03:50:52.727745Z] Finished install step (success) 19:50:52 INFO - [mozharness: 2017-01-05 03:50:52.727886Z] Running run-tests step. 19:50:52 INFO - Running pre-action listener: _resource_record_pre_action 19:50:52 INFO - Running main action method: run_tests 19:50:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:50:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:50:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:50:52 INFO - 2017-01-04 19:50:52.742 screenresolution[1640:7334] starting screenresolution argv=screenresolution get 19:50:52 INFO - 2017-01-04 19:50:52.744 screenresolution[1640:7334] Display 0: 1600x1200x32@60 19:50:52 INFO - 2017-01-04 19:50:52.750 screenresolution[1641:7336] starting screenresolution argv=screenresolution list 19:50:52 INFO - Available Modes on Display 0 19:50:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:50:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:50:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:50:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:50:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:50:52 INFO - Intel Iris: 19:50:52 INFO - Chipset Model: Intel Iris 19:50:52 INFO - Type: GPU 19:50:52 INFO - Bus: Built-In 19:50:52 INFO - VRAM (Dynamic, Max): 1536 MB 19:50:52 INFO - Vendor: Intel (0x8086) 19:50:52 INFO - Device ID: 0x0a2e 19:50:52 INFO - Revision ID: 0x0009 19:50:52 INFO - Displays: 19:50:52 INFO - Display: 19:50:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:50:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:50:52 INFO - Display Serial Number: 1600x1200 60 19:50:52 INFO - Main Display: Yes 19:50:52 INFO - Mirror: Off 19:50:52 INFO - Online: Yes 19:50:52 INFO - Rotation: Supported 19:50:52 INFO - Return code: 0 19:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:50:52 INFO - Python 2.7.3 19:50:52 INFO - Return code: 0 19:50:52 INFO - grabbing minidump binary from tooltool 19:50:52 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')]} 19:50:52 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 0x107050d30>, '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 0x106eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107048b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:50:52 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 19:50:52 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 19:50:52 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 19:50:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:50:53 INFO - Return code: 0 19:50:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:50:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:50:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:50:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:50:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:50:53 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/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 19:50:53 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/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 19:50:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NkCGNRwv5w/Render', 19:50:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:50:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:50:53 INFO - 'HOME': '/Users/cltbld', 19:50:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:50:53 INFO - 'LOGNAME': 'cltbld', 19:50:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:50:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:50:53 INFO - 'MOZ_NO_REMOTE': '1', 19:50:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:50:53 INFO - 'NO_EM_RESTART': '1', 19:50:53 INFO - 'PAGER': '/bin/cat', 19:50:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:50:53 INFO - 'PWD': '/builds/slave/test', 19:50:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:50:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:50:53 INFO - 'SHELL': '/bin/bash', 19:50:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yCjnNcVJR7/Listeners', 19:50:53 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 19:50:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:50:53 INFO - 'USER': 'cltbld', 19:50:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:50:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:50:53 INFO - 'XPC_FLAGS': '0x0', 19:50:53 INFO - 'XPC_SERVICE_NAME': '0', 19:50:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:50:53 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/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 19:50:53 INFO - mozversion application_buildid: 20170104184138 19:50:53 INFO - mozversion application_changeset: 23eae10f94cb96e2d9abe166e073fb0584c8bc3c 19:50:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:50:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:50:53 INFO - mozversion application_name: Firefox 19:50:53 INFO - mozversion application_remotingname: firefox-dev 19:50:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:50:53 INFO - mozversion application_vendor: Mozilla 19:50:53 INFO - mozversion application_version: 52.0a2 19:50:53 INFO - mozversion platform_buildid: 20170104184138 19:50:53 INFO - mozversion platform_changeset: 23eae10f94cb96e2d9abe166e073fb0584c8bc3c 19:50:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:50:53 INFO - mozversion platform_version: 52.0a2 19:50:53 INFO - using testdate: 1483588253 19:50:53 INFO - actual date: 1483588253 19:50:53 INFO - starting webserver on 'localhost:49247' 19:50:53 INFO - SUITE-START | Running 2 tests 19:50:53 INFO - TEST-START | dromaeo_css 19:50:53 INFO - operating with platform_type : mac_ 19:50:53 INFO - Initialising browser for dromaeo_css test... 19:50:53 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpLKYhOC/profile http://localhost:49247/getInfo.html) 19:50:55 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 19:50:55 INFO - PROCESS | 1651 | colorDepth:24 19:50:55 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 19:50:55 INFO - PROCESS | 1651 | __metrics 19:50:56 INFO - PROCESS | 1651 | 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" 19:50:56 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:50:56 INFO - TEST-INFO | 1651: exit 0 19:50:56 INFO - Browser initialized. 19:50:56 INFO - Running cycle 1/1 for dromaeo_css test... 19:50:56 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpLKYhOC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:51:09 INFO - PROCESS | 1653 | RSS: Main: 222699520 19:51:09 INFO - PROCESS | 1653 | 19:54:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 19:54:15 INFO - PROCESS | 1653 | RSS: Main: 373334016 19:54:15 INFO - PROCESS | 1653 | 19:57:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 19:57:18 INFO - PROCESS | 1653 | RSS: Main: 348114944 19:57:18 INFO - PROCESS | 1653 | 20:00:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 20:00:21 INFO - PROCESS | 1653 | RSS: Main: 316030976 20:00:21 INFO - PROCESS | 1653 | 20:03:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 20:03:24 INFO - PROCESS | 1653 | RSS: Main: 322240512 20:03:24 INFO - PROCESS | 1653 | 20:06:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 20:06:28 INFO - PROCESS | 1653 | RSS: Main: 310583296 20:06:28 INFO - PROCESS | 1653 | 20:09:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 20:09:32 INFO - PROCESS | 1653 | RSS: Main: 463147008 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | __start_tp_report 20:09:32 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 20:09:32 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 20:09:32 INFO - PROCESS | 1653 | |i|pagename|runs| 20:09:32 INFO - PROCESS | 1653 | |0;dojo.html;2811.212860399088;2859.4424087302973;3369.89890303291;3384.052465309711;3392.5759280089987;5671.007573674606;5714.51426628737;5743.798967036155;5758;5791.7972870949525;5646.491815736593;5733.799317023905;5753.079507278826;5760.7407666655;5800.53595712343;5205.37535495741;5215.243789650498;5246.95060987802;5269.525742683158;5294.5764338853;5190.091733946563;5220.4779522047875;5222.32109825724;5228.2680424740565;5327.840164795062;5261.86845328866;5311.097113490697;5316.707581083039;5333.386660534049;5371.301730774994;5320.14877619581;5326.6537675050995;5334;5357.544608708284;5376.301080859503;5060.569851562622;5160.045391602565;5242.292290540767;5260.684358938476;5287.233351164098;5659.405762394971;5680.715964201815;5690.06113991191;5702.714864256771;5704.828855134353;6112.299199283841;6123.282621474291;6124.625058545644;6166.568340216187;6237.407446292595;6285.0886621440095;6295.716692748837;6310.432061114476;6322.652254126021;6332.620042797447;5370.516653501204;5815.127730840365;5835.255123943025;5837.416258374171;5883.529317654663;5613.635113717595;6241.843953901098;6250.812475625738;6311.211098612674;6327.462165715873;6165.969170154121;6184.721687524072;6186.4741496972365;6190.380961903864;6198.659073750987;6289.528285378524;6291.5281353898645;6294.2446906370615;6306.400891915261;6321.810345689637;6044.516438684895;6119.938800611937;6130.693465326716;6136.417040381157;6223.253209614875;6195.81412557624;6268.968655156787;6276.529260305495;6279.4662453691035;6294;4878.902421951541;4890.657653964188;4928.852134435972;4930.2851086592245;4953.083679519301;4314.654827613784;4364.716293440916;4379.912401751947;4448.377227188196;4456;5635.774569017275;5646.491815736604;5647.745851436695;5653.123765816305;5660.8867822643315;6206.193194884636;6218.31598524162;6263.342349053284;6301.40136687014;6330.272018717815;5696.458836410535;5706.3152421709665;5709.51469125129;5709.885802284014;5715.799947001835;4670.462896766848;5276.92084618731;5367.35591728995;5412.431694672082;5427.6472029317965;6255.56211065221;6274.968625157027;6281.623102613858;6290.937090629127;6293.181886354744;6221.346758590283;6236.18929539157;6240.375962403905;6241.469475094486;6246.781362652286;4973.254011898244;5259;6196.535259855619;6234.251889773256;6277.2153480814895;6200.3799620037635;6225.564210505221;6249.062640603764;6252.218472690914;6256.0303153011355;6273.247210334789;6327.715252813542;6332.556721029484;6336.936630633819;6345.270293916259;5970.223870896755;6009.909851352146;6034.879302414015;6118.051701986199;6158.846028849314;6025.78909738157;6099.512039036955;6107.54193435503;6109.908351374822;6138.478229350555;5279.683219006872;5614.803481878114;5945.34601193877;5958.672273024852;5960.4635582797755;5918.408159184047;6003.7898673546215;6109.75560977556;6122.6938653068055;6145.293291271561;5894.086416605433;5916.911246331395;5939.406059394026;5992.910106348321;6033.78881739137 20:09:32 INFO - PROCESS | 1653 | |1;ext.html;482.1514135122185;490.78650786907696;492.3722254125991;497.7113274300906;522.9869253268668;31124.53313200301;38893;40147;40354.79822600887;41981;41515.16969660605;41929.99999999993;41954.58045419545;42057.94855128623;42113.947151321154;9222.769430764218;9363.250939924823;9401.24790016797;9405.294602904809;9415.152636262736;7935.563544005106;9423.340366174336;9429.481378524179;9431.056894310548;9451.621935122586;8063.919360806375;9410.247180225566;9421.670241541548;9440.622375105022;9470.384425012386;8146.75559733209;9341.906580934206;9348.672796452092;9364.578593963288;9431.858522122173;9282.303827212918;9350.57922393494;9363.53182340887;9367.859482107773;9373.906260937372;39083;39326.80336598299;39340.81977540678;39471.802640986614;39537.60462395368;3180.872765089394;3306.206510437502;3459.7405194610506;3576.535050443452;3627.6009638939695;28645.856770716222;29161.708382916113;29453;29746.40507189844;30132.69867301321;3131.170239886432;3136.466800643896;3206.7915585487103;3213.5766379663055;3237.4981877808978;481767;484874;485203.5739821314;486373.56813216064;486869;31363.84318078395;60511.39488605146;60760;60865.69567152136;60904.08643870345;31467.52798708021;33310.333793324484;34353;34592.82703586466;34597.481037784455;14332.928335358465;14552.927235363755;14569.708605827824;14572.85427145715;14579.635509112357;12208.755824883452;12251.938740306241;12271.079669024859;12273.263604183778;12318.75362492763;7775;7776.766696999099;7785.961070194613;7842.215778422112;7880.172581878935;3214.2928555717704;3233.4988076848126;3238.70851623355;3248.2204270974794;3279.294951585373;894.4499133033138;896.2381975320925;906.3519583497831;933.286036182321;1024.4314405504867;28647.57028644572;29665;29671.664775085173;30413.543796843063;30509.847450762605;873.7640836973982;913.8446464101119;958.0276019839868;958.4153666263574;1037.7820657661825;12368.567100151631;15092.849071509365;15119.848801512064;15410.768838467431;15415.614609634624;569013;570137;571132;573191;574292;57501.71249143811;57933.71033144807;57951.4204857946;57969.13046304309;57990;1212.993935030319;1258.3959699344366;1316.098472546328;1342.536824795444;1375.8968077394036;26392.604110937966;29857.70142298549;30297.545536816524;30301.09096727102;30457.84771076219;49593;50151.24773128333;50173;50231.497685024144;50238.74880625573;491571;492568;494816;494907;497486;26299.07953221628;26980.32549186286;27162.32094197661;27242;27467;45286.547134528235;45694.543054569025;45752.771236143606;45856.541434585226;46201.76899115483;34028.149296267795;34823;36889.63110368862;37615.05962350854;37971.62028379681;33190.00429987104;35639.64360356363;37552.624473756005;37762.86711398663;38328.23343533058;36039.81980090083;36722.26555468822;37437.2512549742;37583.62416375801;37872.053198670255;31209.374396649888;34800.47799282962;35385.82307088448;35446.46830297496;35635.287294254485;25111.618860962823;25416.364590884637;25536;25588.744112558637;25837.612435813855 20:09:32 INFO - PROCESS | 1653 | |2;jquery.html;343.29624270245995;348.6287104233989;371.48363774353635;379.2907263417409;396.89482287193897;11970.700732481682;15262.847371526295;15786.052836829784;18645.533861653465;20297.797022029812;19758.407247782554;19923.900380498115;20452.18191272351;20452.693209601868;20823.27118550847;5652.5195358394485;5970.820875373745;5981.940180598203;5982.401759824004;5992;5546.500814926656;5806;5834.5915785895;5857.648541087548;5865.853353666171;4108.15782764534;4349.608535231829;5810.622309549887;5825.388334224909;6216.875662486747;6468.967655161718;6561.835954101186;6578.440832529241;6597.93402065978;6614.603123812539;4528.411306530163;5698.116791897262;5745.368009518949;5752.913706294409;5760.654360738369;24516;25230;25348.11281605135;25470.36324091894;25579.232623021155;2299.5171014087064;2359.7168339799164;2425.223928342931;2534.759197876199;2913.965542232498;14758.778618320734;14868.851311486855;15259;15656.686866262611;15870.603234919105;1818.4181062060145;1838.6046999894952;1866.5893503429224;1878.7839398469214;1885.5191926058767;68638.94083177463;78540.71674266044;118650.40674796657;120124.39937800255;123235;10612.628558000588;10757.623483178051;11120.666380008613;11139;11270;13834.792478112837;13845.030847840755;13852.653683657787;13855.16868987864;13881.652958675912;6772.864542709217;6871.896921546181;8461.365397595082;8580.871286930702;8606.182412670787;8218.21926916942;8226.218509241613;8344.249017588447;8366.53984030872;8547.230749232598;6598.868022639616;6687.465002799764;6756.189257289058;6757.256701762803;6861.313868613199;1574.2443627058847;1802.7295905614262;1841.1438681013392;1843.4377514858097;1844.1516902224857;871.3551971541013;888.2938064238946;967.3905439573165;1009.3590569988111;1031.4533297352416;21134.260300889702;21224.681629775565;21936;21949.780502194775;22055.558888822456;862.9611667474978;949.1315446366635;949.7293271417751;989.6288891665625;1034.3276870034388;9338.299627527962;11304.943475282571;11339.786951260625;11365.204435689588;11469.369184694922;528613;533680;539583;545285;548456;88116.1188388108;97404;102971.97028029774;104793;106240;891.2647066170435;929.6839074714629;960.9903900961041;963.2486660404895;988.8516722491684;19105.522361941064;19306.517337066125;19817.702734458708;19855.404337869888;19958.60082798365;32860.50709239411;50072.49927500824;50509.242361365345;51409;56151;73283.63358183175;74557.6272118636;74926.12684682927;74994;75687.62156189184;6220.875582488235;7631.198724133886;7642.847143057219;7710.036245469317;7833.960830195812;9342.95328523353;11892.821607675718;11974.826670932383;11980.28118312904;12370.567030153901;10182;10366.378017318984;10556.472176391304;10708.03627673513;10804.081653059578;10250.641227557;10411.583536658449;10509.737256568647;10601.41692206936;10787.6224332148;10023.749406264902;10034.849477257992;10339.483025848827;10352.223583231138;10620.575176992834;7645.770626881203;7752.224777522202;7800.570968596781;7928.960355198187;7929.92945952289;9045.321600879828;10453.408007626225;10568.947155264175;10627;10721.731956700833 20:09:32 INFO - PROCESS | 1653 | |3;mootools.html;1457.7813328000798;1469.8603632654897;1622.6511300070483;1795.3177792438871;1810.9909450452747;8046.517208967459;8451.873221901677;8462.19609137131;8468.40721149519;8478.576071196447;7385.224551422104;7606.771796846089;7618.4286178536695;7711.228877112286;8183.590820458983;2080.0535756230956;2085.6037352902977;2159.6328624133857;2232.2075663139594;2233.4081468410886;2356.257778799681;2360.3509035015336;2360.9173678921243;2361.669366288721;2365.349528879555;2281.7147856517936;2336.976630233693;2343.8359314847967;2346.3547524430746;2348.0842471436154;2306.042992158261;2306.7231932168165;2338.590746619335;2344.5310937812505;2348.178137651825;2332.941676458085;2336.941576460585;2355;2360.8229382796358;2363.0429675981254;8203.876941845878;8403.453775504573;8405.327573794082;8407.075221725605;8432.072472028072;5060;5215.191645294985;5217.869553261161;5237.13587258102;5237.921431178497;3234.110619579634;3447.3967055765393;3448.189675426273;3459.8616055357757;3554.893353199408;1021.4854059549137;1036.9481525923673;1063.3407287481734;1082.7130810335268;1085.5603480590373;10881.891181088167;10897.291676041032;10919.890801091968;10924.071453926346;11002;4401.889952751143;4583.977080114645;4675.4155730533685;4681.555252251031;4793.352897358882;9388.765280868032;9398;9399.389039712396;9411.43531388119;9440.811183776286;5190.948090519047;5201.947980520146;5206.921896171561;5209.140491818884;5222.764975576109;3959.366501359769;4485.977570112129;4706.764661766898;4774.856754297377;4779.187538118528;3509.5613048368955;3658.926821463556;3658.9451158232646;3671.596124426311;3685;1014.705735336744;1075.6611667324767;1189.0547015122825;1279.3411393132544;1286.5175559165314;758.2834221660478;769.445998880805;823.3948048184579;908.0692290402391;916.3356566489375;5484.341878974548;5641.351244606841;5756.798512052058;5813.09896965971;5970.731317090741;901.5221932375852;919.8436265834824;945.4847108325979;948.3551185193996;985.7831277119022;3588.1747198144053;3688.889333320005;3713.313037088147;3788.8673896413493;3842.193139440749;9729.854052189223;10235.539400726986;10256.58973641061;10269.897301026895;10271.845922311171;9611.279154063333;10199.235057370726;10213.38719676807;10219;10222.948885255526;7537.9623101884135;7669.53982761025;7790.999999999886;7804.2585954334245;7806.765797026098;5676.631018983753;5684.573656975661;5742.339630942328;5819.912701309399;5924.733386997682;9387.061293870558;9438.59315874475;9464.95267523658;9465.952670236606;9517.238620910171;7642.4650274480255;7849.372050235853;8061.596920154087;9325.766855828659;9681.564329605326;7075.752348667772;7157.24848890859;7200.9639951802;7240.52936559122;7291.4166866649475;7831.334336581226;7912.643931023233;7926;7935.761927142195;7941.960290198512;6959.164900212007;7023.789286321418;7164.068671072727;7239.746608868875;7284.344409003215;6942.305769423017;7011.754588589375;7054.6472676367;7171.677274522554;7171.748988785367;6599.835004124743;6604.702788374439;6664.100346453167;6678.098456708282;6679.2318883329035;4832;4837.56461918415;4877.146499362527;4889.9022019560125;4896.951030489649;4766.1420944228985;4779.569838714532;4863.635227358033;4945.975270123627;4975.378077740283 20:09:32 INFO - PROCESS | 1653 | |4;prototype.html;280.81045294426264;304.87347750683466;311.2297064764707;316.1069977314087;318.0537899748248;4623.006053698454;5244.318238628977;5267.499587539187;5303.893922121555;5326.227696983945;4431.825566224953;4810.855674329767;4927.462356886197;5075.340205773245;5097.018244327073;3826.8810919100983;3908.550516690574;3925.9214815703813;3932.4494570759975;3944.4674968879262;3021.8942337018098;3838.4434257032713;3941.881743547698;3989.7207195496326;3994.8202330895033;3489.5812502499734;3854.537455505343;3962.809937999909;3964.9603503965022;4048.7165898387125;3262.755293353008;3923.2545816295096;3973.423853541249;3999.5600483946887;4001.9599804002023;3287.783520097579;3422.6748458896363;3448.70685991691;3473.94789078164;3491.720662347006;3893.5133108361456;4942.283368911523;5085.389753229636;5091.1090559152;5116.539511443951;4002.9199416011807;4519.0961807638605;4551.7724113794175;4556.134334476439;4567.2692369221095;5449.3460784706085;5471.480209380103;5479.424660410639;5485.533729632946;5553.472794981372;3819.350710379242;3850.667998501686;3852.8651497197466;3853.402722578005;3865.207632435344;5447.428020057877;5713.14302854571;5762.366139724627;5775.62126156195;5836.970815145897;3202.4715921873094;3487.4071407860724;3676.687481564097;3872.9031774205305;3886.5336159661024;3293.390722716305;3351.7151042161204;3444.9827750861086;3570.3037907608286;3653.0867283179205;3886.0090676877217;4131.586841315904;4267.4238977737605;4426.092651006536;4499.797509112098;4838.564619120464;5501.4143352624205;5504.944950550524;5508.705454218256;5532.7786888524815;1150.3213104268523;1250.9804509324872;1260.0801414967125;1277.6869666931661;1283.9101262911506;846.2130218896363;858.9055203927563;882.2456799436518;900.0504467786417;911.1617312072909;4438.844640437569;4824.611817150533;4844.539768721966;4866.537678920497;4931.621839040197;5199.272101905736;5371.919421208685;5420.647657902224;5429.429909859408;5452.4286592282115;4271.227440612111;4801.1117943180625;4895.926561101586;5065.720425808401;5139.511746384088;2437.9634305485433;2537.340291524217;2805.9298517536818;2820.464111818748;2840.488712031854;5154.53609175176;5364.409914909357;5619.915701264485;5641.420402287366;5650.265465489542;5099.56653684441;5119.744012799345;5120.846374608767;5125.820596279113;5287.920681189786;4753.572178503882;4939.90120197594;5582.581306401955;5623.493885550321;5640.971795140998;4797.88137710491;5386.148903533992;5402.514843172185;5421.647592906448;5426.565874730091;4508.977455112704;4803.567678908915;5134.666246693953;5380.435054319397;5385.865353366041;5612.887742245214;5627.8874422512135;5628.915566266427;5639.830805075854;5643.604947653625;3042.8630711618475;3501.8774342897877;3570.7325848086134;3597.514335564665;3614.313280476701;5173.63784535079;5183.274341592256;5397.622166448311;5410.3507579090765;5440.483154100354;4482.977585112054;4489.91020179588;5047.41954675217;5219.791208351623;5244.987584497786;4648.581627653527;5042.00861615231;5046.318746969112;5155.32980712505;5240.371155461369;5128.794733237612;5131.358580177478;5143.459936706592;5170.896582068262;5298.337707786527;4800.1119792838435;4846.479830488197;5392.541633961;5420.777481232676;5536.944630553804;4702.741349225688;5343.423690011534;5377.838664840061;5382.892342153056;5393.6763794172475 20:09:32 INFO - PROCESS | 1653 | |5;yui.html;656.6486929492722;718.511838101939;753.630720946736;770.626246270559;778.2217782217781;13777.448902043907;14074.366653500592;14090.295485225735;14094.859051409483;14096.577102686922;12570.12009159357;14178.291085445711;14748;14795.112293262411;14801;666.6733300682679;832.063928080909;854.8717692346136;857.0529564830865;862.4264863865521;607.8905796956547;631.8388810853226;822.0751654388813;836.8912041434605;853.1468531468531;893.3612467086032;894.0344428017726;896.4934811831295;898.8966268879097;901.7520181949951;882.8455020371441;883.7702197428715;884.2572239318939;886.6586364249778;888.9466632002101;511.06220086141974;511.2357026245749;522.8091746512496;653.8528831013032;656.2387630348773;14204.85795142056;14252.358643861051;14254.073485223427;14271.072380295247;14308.57074287773;11500.25248358854;13192.406341714646;13254.150357673147;13437.992150588745;13446.260455674934;11813.940930295294;12000.759984800256;12126.818097728541;12249.265044097383;12273.631791046282;4473.530279320657;4600.9769951150365;4609.953900460986;4612.561806628365;4641.280601506772;79930.60034699789;85141;85626.71559926606;86585;87675.56162219212;9921;10325.586976521005;10385.792284154275;10748.94625526883;10760.730981725519;19923.701144482846;19924;19962.401127966183;20013;20037.699434508493;7359.889601655979;7560.508566943131;7570.470067095251;7571.810704732316;7662.885056724153;4084.0606660468256;4183.958160418418;4187.476565429321;4227.957720422818;4323.0705398339505;3140.6231252249877;3243.189202699325;3248.8538015789345;3251.772375933662;3260.722838558749;548.6461232505012;554.365251786702;559.1724248112815;619.2012304127715;637.6684124255454;523.3222976245771;554.967759966462;593.7713980117686;597.1132867691475;599.4455129005739;8369.163083691583;8497.61760720769;8615.439996400213;8722.781930451663;8729.694460693845;584.8859472402926;601;613.7575657615217;614.8985417406167;625.5277265664395;3925.2345792570745;3953.683705303569;4016.0562267867026;4296.978515107405;4391.363252328394;77488.86366931391;79959;99855;108932.45533772439;112560.31159532613;96010;99689.50155249177;100370.89614907837;102203.97796021945;102860;57312.42687573071;76044.61977690076;85274.72087918562;86412;89637.99999999869;7951.960240198762;8044.396670249872;8081.191880811872;8151.918480815116;8309.626066826886;53648.73175634097;54234.72882635562;55440.44559554514;56320.15519767289;57990.4200957985;49414.764630884514;76666;80972.19027809646;81466.18533814586;84155;11534.307941523535;11914.16600837933;11954.222975506564;12108.273503589813;12828.55100071493;13707.588772336845;13806.93096534511;14044.78932815988;14148.22184779847;14683.19242441676;10716.678499645022;13119.016073794313;13151.34243287851;13192.868071319164;13477.528286509925;11019.669409917715;12430.189168049414;12741.10812243134;12878.613641590393;12937.417816198482;12196.268223906594;12738.471383434047;12921.547745828851;13081.149725267827;13519;10325.690229293134;10711.464426778786;10867.945660271647;11074.335539867634;11364.715882103013;10440.58237670515;11118.869690531945;11265.26775759573;11365.48855301497;11474.024707899922 20:09:32 INFO - PROCESS | 1653 | __end_tp_report 20:09:32 INFO - PROCESS | 1653 | __start_cc_report 20:09:32 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1744 20:09:32 INFO - PROCESS | 1653 | __end_cc_report 20:09:32 INFO - PROCESS | 1653 | __startTimestamp1483589372166__endTimestamp 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | ------- Summary: start ------- 20:09:32 INFO - PROCESS | 1653 | Number of tests: 6 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5723.02 Median:5918.41 stddev:666.85 (11.3%) stddev-sans-first:628.56 20:09:32 INFO - PROCESS | 1653 | Values: 2811.2 2859.4 3369.9 3384.1 3392.6 5671.0 5714.5 5743.8 5758.0 5791.8 5646.5 5733.8 5753.1 5760.7 5800.5 5205.4 5215.2 5247.0 5269.5 5294.6 5190.1 5220.5 5222.3 5228.3 5327.8 5261.9 5311.1 5316.7 5333.4 5371.3 5320.1 5326.7 5334.0 5357.5 5376.3 5060.6 5160.0 5242.3 5260.7 5287.2 5659.4 5680.7 5690.1 5702.7 5704.8 6112.3 6123.3 6124.6 6166.6 6237.4 6285.1 6295.7 6310.4 6322.7 6332.6 5370.5 5815.1 5835.3 5837.4 5883.5 5613.6 6241.8 6250.8 6311.2 6327.5 6166.0 6184.7 6186.5 6190.4 6198.7 6289.5 6291.5 6294.2 6306.4 6321.8 6044.5 6119.9 6130.7 6136.4 6223.3 6195.8 6269.0 6276.5 6279.5 6294.0 4878.9 4890.7 4928.9 4930.3 4953.1 4314.7 4364.7 4379.9 4448.4 4456.0 5635.8 5646.5 5647.7 5653.1 5660.9 6206.2 6218.3 6263.3 6301.4 6330.3 5696.5 5706.3 5709.5 5709.9 5715.8 4670.5 5276.9 5367.4 5412.4 5427.6 6255.6 6275.0 6281.6 6290.9 6293.2 6221.3 6236.2 6240.4 6241.5 6246.8 4973.3 5259.0 6196.5 6234.3 6277.2 6200.4 6225.6 6249.1 6252.2 6256.0 6273.2 6327.7 6332.6 6336.9 6345.3 5970.2 6009.9 6034.9 6118.1 6158.8 6025.8 6099.5 6107.5 6109.9 6138.5 5279.7 5614.8 5945.3 5958.7 5960.5 5918.4 6003.8 6109.8 6122.7 6145.3 5894.1 5916.9 5939.4 5992.9 6033.8 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:64094.16 Median:39204.90 stddev:138397.44 (353.0%) stddev-sans-first:138703.27 20:09:32 INFO - PROCESS | 1653 | Values: 482.2 490.8 492.4 497.7 523.0 31124.5 38893.0 40147.0 40354.8 41981.0 41515.2 41930.0 41954.6 42057.9 42113.9 9222.8 9363.3 9401.2 9405.3 9415.2 7935.6 9423.3 9429.5 9431.1 9451.6 8063.9 9410.2 9421.7 9440.6 9470.4 8146.8 9341.9 9348.7 9364.6 9431.9 9282.3 9350.6 9363.5 9367.9 9373.9 39083.0 39326.8 39340.8 39471.8 39537.6 3180.9 3306.2 3459.7 3576.5 3627.6 28645.9 29161.7 29453.0 29746.4 30132.7 3131.2 3136.5 3206.8 3213.6 3237.5 481767.0 484874.0 485203.6 486373.6 486869.0 31363.8 60511.4 60760.0 60865.7 60904.1 31467.5 33310.3 34353.0 34592.8 34597.5 14332.9 14552.9 14569.7 14572.9 14579.6 12208.8 12251.9 12271.1 12273.3 12318.8 7775.0 7776.8 7786.0 7842.2 7880.2 3214.3 3233.5 3238.7 3248.2 3279.3 894.4 896.2 906.4 933.3 1024.4 28647.6 29665.0 29671.7 30413.5 30509.8 873.8 913.8 958.0 958.4 1037.8 12368.6 15092.8 15119.8 15410.8 15415.6 569013.0 570137.0 571132.0 573191.0 574292.0 57501.7 57933.7 57951.4 57969.1 57990.0 1213.0 1258.4 1316.1 1342.5 1375.9 26392.6 29857.7 30297.5 30301.1 30457.8 49593.0 50151.2 50173.0 50231.5 50238.7 491571.0 492568.0 494816.0 494907.0 497486.0 26299.1 26980.3 27162.3 27242.0 27467.0 45286.5 45694.5 45752.8 45856.5 46201.8 34028.1 34823.0 36889.6 37615.1 37971.6 33190.0 35639.6 37552.6 37762.9 38328.2 36039.8 36722.3 37437.3 37583.6 37872.1 31209.4 34800.5 35385.8 35446.5 35635.3 25111.6 25416.4 25536.0 25588.7 25837.6 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:31879.37 Median:17887.24 stddev:89523.75 (500.5%) stddev-sans-first:89743.58 20:09:32 INFO - PROCESS | 1653 | Values: 343.3 348.6 371.5 379.3 396.9 11970.7 15262.8 15786.1 18645.5 20297.8 19758.4 19923.9 20452.2 20452.7 20823.3 5652.5 5970.8 5981.9 5982.4 5992.0 5546.5 5806.0 5834.6 5857.6 5865.9 4108.2 4349.6 5810.6 5825.4 6216.9 6469.0 6561.8 6578.4 6597.9 6614.6 4528.4 5698.1 5745.4 5752.9 5760.7 24516.0 25230.0 25348.1 25470.4 25579.2 2299.5 2359.7 2425.2 2534.8 2914.0 14758.8 14868.9 15259.0 15656.7 15870.6 1818.4 1838.6 1866.6 1878.8 1885.5 68638.9 78540.7 118650.4 120124.4 123235.0 10612.6 10757.6 11120.7 11139.0 11270.0 13834.8 13845.0 13852.7 13855.2 13881.7 6772.9 6871.9 8461.4 8580.9 8606.2 8218.2 8226.2 8344.2 8366.5 8547.2 6598.9 6687.5 6756.2 6757.3 6861.3 1574.2 1802.7 1841.1 1843.4 1844.2 871.4 888.3 967.4 1009.4 1031.5 21134.3 21224.7 21936.0 21949.8 22055.6 863.0 949.1 949.7 989.6 1034.3 9338.3 11304.9 11339.8 11365.2 11469.4 528613.0 533680.0 539583.0 545285.0 548456.0 88116.1 97404.0 102972.0 104793.0 106240.0 891.3 929.7 961.0 963.2 988.9 19105.5 19306.5 19817.7 19855.4 19958.6 32860.5 50072.5 50509.2 51409.0 56151.0 73283.6 74557.6 74926.1 74994.0 75687.6 6220.9 7631.2 7642.8 7710.0 7834.0 9343.0 11892.8 11974.8 11980.3 12370.6 10182.0 10366.4 10556.5 10708.0 10804.1 10250.6 10411.6 10509.7 10601.4 10787.6 10023.7 10034.8 10339.5 10352.2 10620.6 7645.8 7752.2 7800.6 7929.0 7929.9 9045.3 10453.4 10568.9 10627.0 10721.7 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5428.61 Median:5204.43 stddev:2967.92 (57.0%) stddev-sans-first:2961.25 20:09:32 INFO - PROCESS | 1653 | Values: 1457.8 1469.9 1622.7 1795.3 1811.0 8046.5 8451.9 8462.2 8468.4 8478.6 7385.2 7606.8 7618.4 7711.2 8183.6 2080.1 2085.6 2159.6 2232.2 2233.4 2356.3 2360.4 2360.9 2361.7 2365.3 2281.7 2337.0 2343.8 2346.4 2348.1 2306.0 2306.7 2338.6 2344.5 2348.2 2332.9 2336.9 2355.0 2360.8 2363.0 8203.9 8403.5 8405.3 8407.1 8432.1 5060.0 5215.2 5217.9 5237.1 5237.9 3234.1 3447.4 3448.2 3459.9 3554.9 1021.5 1036.9 1063.3 1082.7 1085.6 10881.9 10897.3 10919.9 10924.1 11002.0 4401.9 4584.0 4675.4 4681.6 4793.4 9388.8 9398.0 9399.4 9411.4 9440.8 5190.9 5201.9 5206.9 5209.1 5222.8 3959.4 4486.0 4706.8 4774.9 4779.2 3509.6 3658.9 3658.9 3671.6 3685.0 1014.7 1075.7 1189.1 1279.3 1286.5 758.3 769.4 823.4 908.1 916.3 5484.3 5641.4 5756.8 5813.1 5970.7 901.5 919.8 945.5 948.4 985.8 3588.2 3688.9 3713.3 3788.9 3842.2 9729.9 10235.5 10256.6 10269.9 10271.8 9611.3 10199.2 10213.4 10219.0 10222.9 7538.0 7669.5 7791.0 7804.3 7806.8 5676.6 5684.6 5742.3 5819.9 5924.7 9387.1 9438.6 9465.0 9466.0 9517.2 7642.5 7849.4 8061.6 9325.8 9681.6 7075.8 7157.2 7201.0 7240.5 7291.4 7831.3 7912.6 7926.0 7935.8 7942.0 6959.2 7023.8 7164.1 7239.7 7284.3 6942.3 7011.8 7054.6 7171.7 7171.7 6599.8 6604.7 6664.1 6678.1 6679.2 4832.0 4837.6 4877.1 4889.9 4897.0 4766.1 4779.6 4863.6 4946.0 4975.4 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4337.85 Median:4881.23 stddev:1330.99 (27.3%) stddev-sans-first:1299.43 20:09:32 INFO - PROCESS | 1653 | Values: 280.8 304.9 311.2 316.1 318.1 4623.0 5244.3 5267.5 5303.9 5326.2 4431.8 4810.9 4927.5 5075.3 5097.0 3826.9 3908.6 3925.9 3932.4 3944.5 3021.9 3838.4 3941.9 3989.7 3994.8 3489.6 3854.5 3962.8 3965.0 4048.7 3262.8 3923.3 3973.4 3999.6 4002.0 3287.8 3422.7 3448.7 3473.9 3491.7 3893.5 4942.3 5085.4 5091.1 5116.5 4002.9 4519.1 4551.8 4556.1 4567.3 5449.3 5471.5 5479.4 5485.5 5553.5 3819.4 3850.7 3852.9 3853.4 3865.2 5447.4 5713.1 5762.4 5775.6 5837.0 3202.5 3487.4 3676.7 3872.9 3886.5 3293.4 3351.7 3445.0 3570.3 3653.1 3886.0 4131.6 4267.4 4426.1 4499.8 4838.6 5501.4 5504.9 5508.7 5532.8 1150.3 1251.0 1260.1 1277.7 1283.9 846.2 858.9 882.2 900.1 911.2 4438.8 4824.6 4844.5 4866.5 4931.6 5199.3 5371.9 5420.6 5429.4 5452.4 4271.2 4801.1 4895.9 5065.7 5139.5 2438.0 2537.3 2805.9 2820.5 2840.5 5154.5 5364.4 5619.9 5641.4 5650.3 5099.6 5119.7 5120.8 5125.8 5287.9 4753.6 4939.9 5582.6 5623.5 5641.0 4797.9 5386.1 5402.5 5421.6 5426.6 4509.0 4803.6 5134.7 5380.4 5385.9 5612.9 5627.9 5628.9 5639.8 5643.6 3042.9 3501.9 3570.7 3597.5 3614.3 5173.6 5183.3 5397.6 5410.4 5440.5 4483.0 4489.9 5047.4 5219.8 5245.0 4648.6 5042.0 5046.3 5155.3 5240.4 5128.8 5131.4 5143.5 5170.9 5298.3 4800.1 4846.5 5392.5 5420.8 5536.9 4702.7 5343.4 5377.8 5382.9 5393.7 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:20025.13 Median:4605.47 stddev:29026.48 (630.3%) stddev-sans-first:29071.47 20:09:32 INFO - PROCESS | 1653 | Values: 656.6 718.5 753.6 770.6 778.2 13777.4 14074.4 14090.3 14094.9 14096.6 12570.1 14178.3 14748.0 14795.1 14801.0 666.7 832.1 854.9 857.1 862.4 607.9 631.8 822.1 836.9 853.1 893.4 894.0 896.5 898.9 901.8 882.8 883.8 884.3 886.7 888.9 511.1 511.2 522.8 653.9 656.2 14204.9 14252.4 14254.1 14271.1 14308.6 11500.3 13192.4 13254.2 13438.0 13446.3 11813.9 12000.8 12126.8 12249.3 12273.6 4473.5 4601.0 4610.0 4612.6 4641.3 79930.6 85141.0 85626.7 86585.0 87675.6 9921.0 10325.6 10385.8 10748.9 10760.7 19923.7 19924.0 19962.4 20013.0 20037.7 7359.9 7560.5 7570.5 7571.8 7662.9 4084.1 4184.0 4187.5 4228.0 4323.1 3140.6 3243.2 3248.9 3251.8 3260.7 548.6 554.4 559.2 619.2 637.7 523.3 555.0 593.8 597.1 599.4 8369.2 8497.6 8615.4 8722.8 8729.7 584.9 601.0 613.8 614.9 625.5 3925.2 3953.7 4016.1 4297.0 4391.4 77488.9 79959.0 99855.0 108932.5 112560.3 96010.0 99689.5 100370.9 102204.0 102860.0 57312.4 76044.6 85274.7 86412.0 89638.0 7952.0 8044.4 8081.2 8151.9 8309.6 53648.7 54234.7 55440.4 56320.2 57990.4 49414.8 76666.0 80972.2 81466.2 84155.0 11534.3 11914.2 11954.2 12108.3 12828.6 13707.6 13806.9 14044.8 14148.2 14683.2 10716.7 13119.0 13151.3 13192.9 13477.5 11019.7 12430.2 12741.1 12878.6 12937.4 12196.3 12738.5 12921.5 13081.1 13519.0 10325.7 10711.5 10867.9 11074.3 11364.7 10440.6 11118.9 11265.3 11365.5 11474.0 20:09:32 INFO - PROCESS | 1653 | -------- Summary: end -------- 20:09:32 INFO - PROCESS | 1653 | 20:09:32 INFO - TEST-INFO | 1653: exit 0 20:09:32 INFO - TEST-OK | dromaeo_css | took 1119522ms 20:09:32 INFO - TEST-START | kraken 20:09:32 INFO - operating with platform_type : mac_ 20:09:32 INFO - Initialising browser for kraken test... 20:09:32 INFO - TEST-INFO | started process 1775 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpqYCYcb/profile http://localhost:49247/getInfo.html) 20:09:35 INFO - PROCESS | 1775 | __metrics Screen width/height:1600/1200 20:09:35 INFO - PROCESS | 1775 | colorDepth:24 20:09:35 INFO - PROCESS | 1775 | Browser inner width/height: 1024/689 20:09:35 INFO - PROCESS | 1775 | __metrics 20:09:35 INFO - PROCESS | 1775 | 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" 20:09:35 INFO - PROCESS | 1775 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:09:36 INFO - TEST-INFO | 1775: exit 0 20:09:36 INFO - Browser initialized. 20:09:36 INFO - Running cycle 1/1 for kraken test... 20:09:36 INFO - TEST-INFO | started process 1776 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmpqYCYcb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:48 INFO - PROCESS | 1776 | RSS: Main: 222695424 20:09:48 INFO - PROCESS | 1776 | 20:09:51 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 20:09:52 INFO - PROCESS | 1776 | RSS: Main: 273829888 20:09:52 INFO - PROCESS | 1776 | 20:09:55 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 20:09:55 INFO - PROCESS | 1776 | RSS: Main: 286035968 20:09:55 INFO - PROCESS | 1776 | 20:09:58 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 20:09:59 INFO - PROCESS | 1776 | RSS: Main: 381648896 20:09:59 INFO - PROCESS | 1776 | 20:10:01 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 20:10:02 INFO - PROCESS | 1776 | RSS: Main: 284282880 20:10:02 INFO - PROCESS | 1776 | 20:10:04 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:10:04 INFO - PROCESS | 1776 | RSS: Main: 285450240 20:10:04 INFO - PROCESS | 1776 | 20:10:07 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 20:10:08 INFO - PROCESS | 1776 | RSS: Main: 326537216 20:10:08 INFO - PROCESS | 1776 | 20:10:10 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 20:10:11 INFO - PROCESS | 1776 | RSS: Main: 326995968 20:10:11 INFO - PROCESS | 1776 | 20:10:14 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 20:10:14 INFO - PROCESS | 1776 | RSS: Main: 324780032 20:10:14 INFO - PROCESS | 1776 | 20:10:16 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:10:17 INFO - PROCESS | 1776 | RSS: Main: 300077056 20:10:17 INFO - PROCESS | 1776 | 20:10:19 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:10:19 INFO - PROCESS | 1776 | RSS: Main: 300851200 20:10:19 INFO - PROCESS | 1776 | 20:10:21 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:10:22 INFO - PROCESS | 1776 | RSS: Main: 303923200 20:10:22 INFO - PROCESS | 1776 | 20:10:25 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:10:26 INFO - PROCESS | 1776 | RSS: Main: 316694528 20:10:26 INFO - PROCESS | 1776 | 20:10:29 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:10:29 INFO - PROCESS | 1776 | RSS: Main: 316727296 20:10:29 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 20:10:31 INFO - PROCESS | 1776 | RSS: Main: 360529920 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | __start_tp_report 20:10:31 INFO - PROCESS | 1776 | _x_x_mozilla_page_load 20:10:31 INFO - PROCESS | 1776 | _x_x_mozilla_page_load_details 20:10:31 INFO - PROCESS | 1776 | |i|pagename|runs| 20:10:31 INFO - PROCESS | 1776 | |0;ai-astar;115;122;168;152;116;114;114;115;114;114 20:10:31 INFO - PROCESS | 1776 | |1;audio-beat-detection;154;139;110;146;111;124;139;114;140;113 20:10:31 INFO - PROCESS | 1776 | |2;audio-dft;180;180;179;180;181;184;181;184;181;183 20:10:31 INFO - PROCESS | 1776 | |3;audio-fft;80;59;57;87;62;59;60;60;112;57 20:10:31 INFO - PROCESS | 1776 | |4;audio-oscillator;85;71;95;70;69;68;88;68;69;92 20:10:31 INFO - PROCESS | 1776 | |5;imaging-gaussian-blur;88;85;87;85;88;85;88;84;88;85 20:10:31 INFO - PROCESS | 1776 | |6;imaging-darkroom;95;96;96;96;96;96;96;95;96;96 20:10:31 INFO - PROCESS | 1776 | |7;imaging-desaturate;81;82;80;81;83;81;85;82;80;81 20:10:31 INFO - PROCESS | 1776 | |8;json-parse-financial;56;56;52;53;57;57;58;56;55;79 20:10:31 INFO - PROCESS | 1776 | |9;json-stringify-tinderbox;73;68;72;74;71;73;71;70;72;71 20:10:31 INFO - PROCESS | 1776 | |10;stanford-crypto-aes;84;84;83;83;83;84;80;82;80;83 20:10:31 INFO - PROCESS | 1776 | |11;stanford-crypto-ccm;144;151;142;140;141;141;147;138;213;174 20:10:31 INFO - PROCESS | 1776 | |12;stanford-crypto-pbkdf2;162;158;165;162;165;165;164;224;230;170 20:10:31 INFO - PROCESS | 1776 | |13;stanford-crypto-sha256-iterative;59;84;89;87;66;67;67;67;66;67 20:10:31 INFO - PROCESS | 1776 | __end_tp_report 20:10:31 INFO - PROCESS | 1776 | __start_cc_report 20:10:31 INFO - PROCESS | 1776 | _x_x_mozilla_cycle_collect,2016 20:10:31 INFO - PROCESS | 1776 | __end_cc_report 20:10:31 INFO - PROCESS | 1776 | __startTimestamp1483589431860__endTimestamp 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | ------- Summary: start ------- 20:10:31 INFO - PROCESS | 1776 | Number of tests: 14 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#0] ai-astar Cycles:10 Average:124.40 Median:115.50 stddev:19.29 (16.7%) stddev-sans-first:20.16 20:10:31 INFO - PROCESS | 1776 | Values: 115.0 122.0 168.0 152.0 116.0 114.0 114.0 115.0 114.0 114.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:139.00 stddev:16.42 (11.8%) stddev-sans-first:14.71 20:10:31 INFO - PROCESS | 1776 | Values: 154.0 139.0 110.0 146.0 111.0 124.0 139.0 114.0 140.0 113.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#2] audio-dft Cycles:10 Average:181.30 Median:181.00 stddev:1.77 (1.0%) stddev-sans-first:1.81 20:10:31 INFO - PROCESS | 1776 | Values: 180.0 180.0 179.0 180.0 181.0 184.0 181.0 184.0 181.0 183.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#3] audio-fft Cycles:10 Average:69.30 Median:60.00 stddev:18.23 (30.4%) stddev-sans-first:18.92 20:10:31 INFO - PROCESS | 1776 | Values: 80.0 59.0 57.0 87.0 62.0 59.0 60.0 60.0 112.0 57.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:11.09 (14.2%) stddev-sans-first:11.42 20:10:31 INFO - PROCESS | 1776 | Values: 85.0 71.0 95.0 70.0 69.0 68.0 88.0 68.0 69.0 92.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:1.64 (1.9%) stddev-sans-first:1.62 20:10:31 INFO - PROCESS | 1776 | Values: 88.0 85.0 87.0 85.0 88.0 85.0 88.0 84.0 88.0 85.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 20:10:31 INFO - PROCESS | 1776 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:81.50 stddev:1.51 (1.8%) stddev-sans-first:1.58 20:10:31 INFO - PROCESS | 1776 | Values: 81.0 82.0 80.0 81.0 83.0 81.0 85.0 82.0 80.0 81.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:56.50 stddev:7.64 (13.5%) stddev-sans-first:8.07 20:10:31 INFO - PROCESS | 1776 | Values: 56.0 56.0 52.0 53.0 57.0 57.0 58.0 56.0 55.0 79.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:1.72 (2.4%) stddev-sans-first:1.73 20:10:31 INFO - PROCESS | 1776 | Values: 73.0 68.0 72.0 74.0 71.0 73.0 71.0 70.0 72.0 71.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:1.51 (1.8%) stddev-sans-first:1.51 20:10:31 INFO - PROCESS | 1776 | Values: 84.0 84.0 83.0 83.0 83.0 84.0 80.0 82.0 80.0 83.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:145.50 stddev:23.49 (16.1%) stddev-sans-first:24.68 20:10:31 INFO - PROCESS | 1776 | Values: 144.0 151.0 142.0 140.0 141.0 141.0 147.0 138.0 213.0 174.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:165.00 stddev:26.83 (16.3%) stddev-sans-first:27.93 20:10:31 INFO - PROCESS | 1776 | Values: 162.0 158.0 165.0 162.0 165.0 165.0 164.0 224.0 230.0 170.0 20:10:31 INFO - PROCESS | 1776 | 20:10:31 INFO - PROCESS | 1776 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:67.00 stddev:10.54 (15.7%) stddev-sans-first:10.09 20:10:31 INFO - PROCESS | 1776 | Values: 59.0 84.0 89.0 87.0 66.0 67.0 67.0 67.0 66.0 67.0 20:10:31 INFO - PROCESS | 1776 | -------- Summary: end -------- 20:10:31 INFO - PROCESS | 1776 | 20:10:33 INFO - TEST-INFO | 1776: exit 0 20:10:33 INFO - TEST-OK | kraken | took 60238ms 20:10:33 INFO - SUITE-END | took 1179s 20:10:33 INFO - Completed test suite (00:19:40) 20:10:33 INFO - javascript benchmark 20:10:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2811.212860399088, 2859.4424087302973, 3369.89890303291, 3384.052465309711, 3392.5759280089987, 5671.007573674606, 5714.51426628737, 5743.798967036155, 5758.0, 5791.7972870949525, 5646.491815736593, 5733.799317023905, 5753.079507278826, 5760.7407666655, 5800.53595712343, 5205.37535495741, 5215.243789650498, 5246.95060987802, 5269.525742683158, 5294.5764338853, 5190.091733946563, 5220.4779522047875, 5222.32109825724, 5228.2680424740565, 5327.840164795062, 5261.86845328866, 5311.097113490697, 5316.707581083039, 5333.386660534049, 5371.301730774994, 5320.14877619581, 5326.6537675050995, 5334.0, 5357.544608708284, 5376.301080859503, 5060.569851562622, 5160.045391602565, 5242.292290540767, 5260.684358938476, 5287.233351164098, 5659.405762394971, 5680.715964201815, 5690.06113991191, 5702.714864256771, 5704.828855134353, 6112.299199283841, 6123.282621474291, 6124.625058545644, 6166.568340216187, 6237.407446292595, 6285.0886621440095, 6295.716692748837, 6310.432061114476, 6322.652254126021, 6332.620042797447, 5370.516653501204, 5815.127730840365, 5835.255123943025, 5837.416258374171, 5883.529317654663, 5613.635113717595, 6241.843953901098, 6250.812475625738, 6311.211098612674, 6327.462165715873, 6165.969170154121, 6184.721687524072, 6186.4741496972365, 6190.380961903864, 6198.659073750987, 6289.528285378524, 6291.5281353898645, 6294.2446906370615, 6306.400891915261, 6321.810345689637, 6044.516438684895, 6119.938800611937, 6130.693465326716, 6136.417040381157, 6223.253209614875, 6195.81412557624, 6268.968655156787, 6276.529260305495, 6279.4662453691035, 6294.0, 4878.902421951541, 4890.657653964188, 4928.852134435972, 4930.2851086592245, 4953.083679519301, 4314.654827613784, 4364.716293440916, 4379.912401751947, 4448.377227188196, 4456.0, 5635.774569017275, 5646.491815736604, 5647.745851436695, 5653.123765816305, 5660.8867822643315, 6206.193194884636, 6218.31598524162, 6263.342349053284, 6301.40136687014, 6330.272018717815, 5696.458836410535, 5706.3152421709665, 5709.51469125129, 5709.885802284014, 5715.799947001835, 4670.462896766848, 5276.92084618731, 5367.35591728995, 5412.431694672082, 5427.6472029317965, 6255.56211065221, 6274.968625157027, 6281.623102613858, 6290.937090629127, 6293.181886354744, 6221.346758590283, 6236.18929539157, 6240.375962403905, 6241.469475094486, 6246.781362652286, 4973.254011898244, 5259.0, 6196.535259855619, 6234.251889773256, 6277.2153480814895, 6200.3799620037635, 6225.564210505221, 6249.062640603764, 6252.218472690914, 6256.0303153011355, 6273.247210334789, 6327.715252813542, 6332.556721029484, 6336.936630633819, 6345.270293916259, 5970.223870896755, 6009.909851352146, 6034.879302414015, 6118.051701986199, 6158.846028849314, 6025.78909738157, 6099.512039036955, 6107.54193435503, 6109.908351374822, 6138.478229350555, 5279.683219006872, 5614.803481878114, 5945.34601193877, 5958.672273024852, 5960.4635582797755, 5918.408159184047, 6003.7898673546215, 6109.75560977556, 6122.6938653068055, 6145.293291271561, 5894.086416605433, 5916.911246331395, 5939.406059394026, 5992.910106348321, 6033.78881739137], "value": 5677.791511425216, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.1514135122185, 490.78650786907696, 492.3722254125991, 497.7113274300906, 522.9869253268668, 31124.53313200301, 38893.0, 40147.0, 40354.79822600887, 41981.0, 41515.16969660605, 41929.99999999993, 41954.58045419545, 42057.94855128623, 42113.947151321154, 9222.769430764218, 9363.250939924823, 9401.24790016797, 9405.294602904809, 9415.152636262736, 7935.563544005106, 9423.340366174336, 9429.481378524179, 9431.056894310548, 9451.621935122586, 8063.919360806375, 9410.247180225566, 9421.670241541548, 9440.622375105022, 9470.384425012386, 8146.75559733209, 9341.906580934206, 9348.672796452092, 9364.578593963288, 9431.858522122173, 9282.303827212918, 9350.57922393494, 9363.53182340887, 9367.859482107773, 9373.906260937372, 39083.0, 39326.80336598299, 39340.81977540678, 39471.802640986614, 39537.60462395368, 3180.872765089394, 3306.206510437502, 3459.7405194610506, 3576.535050443452, 3627.6009638939695, 28645.856770716222, 29161.708382916113, 29453.0, 29746.40507189844, 30132.69867301321, 3131.170239886432, 3136.466800643896, 3206.7915585487103, 3213.5766379663055, 3237.4981877808978, 481767.0, 484874.0, 485203.5739821314, 486373.56813216064, 486869.0, 31363.84318078395, 60511.39488605146, 60760.0, 60865.69567152136, 60904.08643870345, 31467.52798708021, 33310.333793324484, 34353.0, 34592.82703586466, 34597.481037784455, 14332.928335358465, 14552.927235363755, 14569.708605827824, 14572.85427145715, 14579.635509112357, 12208.755824883452, 12251.938740306241, 12271.079669024859, 12273.263604183778, 12318.75362492763, 7775.0, 7776.766696999099, 7785.961070194613, 7842.215778422112, 7880.172581878935, 3214.2928555717704, 3233.4988076848126, 3238.70851623355, 3248.2204270974794, 3279.294951585373, 894.4499133033138, 896.2381975320925, 906.3519583497831, 933.286036182321, 1024.4314405504867, 28647.57028644572, 29665.0, 29671.664775085173, 30413.543796843063, 30509.847450762605, 873.7640836973982, 913.8446464101119, 958.0276019839868, 958.4153666263574, 1037.7820657661825, 12368.567100151631, 15092.849071509365, 15119.848801512064, 15410.768838467431, 15415.614609634624, 569013.0, 570137.0, 571132.0, 573191.0, 574292.0, 57501.71249143811, 57933.71033144807, 57951.4204857946, 57969.13046304309, 57990.0, 1212.993935030319, 1258.3959699344366, 1316.098472546328, 1342.536824795444, 1375.8968077394036, 26392.604110937966, 29857.70142298549, 30297.545536816524, 30301.09096727102, 30457.84771076219, 49593.0, 50151.24773128333, 50173.0, 50231.497685024144, 50238.74880625573, 491571.0, 492568.0, 494816.0, 494907.0, 497486.0, 26299.07953221628, 26980.32549186286, 27162.32094197661, 27242.0, 27467.0, 45286.547134528235, 45694.543054569025, 45752.771236143606, 45856.541434585226, 46201.76899115483, 34028.149296267795, 34823.0, 36889.63110368862, 37615.05962350854, 37971.62028379681, 33190.00429987104, 35639.64360356363, 37552.624473756005, 37762.86711398663, 38328.23343533058, 36039.81980090083, 36722.26555468822, 37437.2512549742, 37583.62416375801, 37872.053198670255, 31209.374396649888, 34800.47799282962, 35385.82307088448, 35446.46830297496, 35635.287294254485, 25111.618860962823, 25416.364590884637, 25536.0, 25588.744112558637, 25837.612435813855], "value": 18071.573848533117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.29624270245995, 348.6287104233989, 371.48363774353635, 379.2907263417409, 396.89482287193897, 11970.700732481682, 15262.847371526295, 15786.052836829784, 18645.533861653465, 20297.797022029812, 19758.407247782554, 19923.900380498115, 20452.18191272351, 20452.693209601868, 20823.27118550847, 5652.5195358394485, 5970.820875373745, 5981.940180598203, 5982.401759824004, 5992.0, 5546.500814926656, 5806.0, 5834.5915785895, 5857.648541087548, 5865.853353666171, 4108.15782764534, 4349.608535231829, 5810.622309549887, 5825.388334224909, 6216.875662486747, 6468.967655161718, 6561.835954101186, 6578.440832529241, 6597.93402065978, 6614.603123812539, 4528.411306530163, 5698.116791897262, 5745.368009518949, 5752.913706294409, 5760.654360738369, 24516.0, 25230.0, 25348.11281605135, 25470.36324091894, 25579.232623021155, 2299.5171014087064, 2359.7168339799164, 2425.223928342931, 2534.759197876199, 2913.965542232498, 14758.778618320734, 14868.851311486855, 15259.0, 15656.686866262611, 15870.603234919105, 1818.4181062060145, 1838.6046999894952, 1866.5893503429224, 1878.7839398469214, 1885.5191926058767, 68638.94083177463, 78540.71674266044, 118650.40674796657, 120124.39937800255, 123235.0, 10612.628558000588, 10757.623483178051, 11120.666380008613, 11139.0, 11270.0, 13834.792478112837, 13845.030847840755, 13852.653683657787, 13855.16868987864, 13881.652958675912, 6772.864542709217, 6871.896921546181, 8461.365397595082, 8580.871286930702, 8606.182412670787, 8218.21926916942, 8226.218509241613, 8344.249017588447, 8366.53984030872, 8547.230749232598, 6598.868022639616, 6687.465002799764, 6756.189257289058, 6757.256701762803, 6861.313868613199, 1574.2443627058847, 1802.7295905614262, 1841.1438681013392, 1843.4377514858097, 1844.1516902224857, 871.3551971541013, 888.2938064238946, 967.3905439573165, 1009.3590569988111, 1031.4533297352416, 21134.260300889702, 21224.681629775565, 21936.0, 21949.780502194775, 22055.558888822456, 862.9611667474978, 949.1315446366635, 949.7293271417751, 989.6288891665625, 1034.3276870034388, 9338.299627527962, 11304.943475282571, 11339.786951260625, 11365.204435689588, 11469.369184694922, 528613.0, 533680.0, 539583.0, 545285.0, 548456.0, 88116.1188388108, 97404.0, 102971.97028029774, 104793.0, 106240.0, 891.2647066170435, 929.6839074714629, 960.9903900961041, 963.2486660404895, 988.8516722491684, 19105.522361941064, 19306.517337066125, 19817.702734458708, 19855.404337869888, 19958.60082798365, 32860.50709239411, 50072.49927500824, 50509.242361365345, 51409.0, 56151.0, 73283.63358183175, 74557.6272118636, 74926.12684682927, 74994.0, 75687.62156189184, 6220.875582488235, 7631.198724133886, 7642.847143057219, 7710.036245469317, 7833.960830195812, 9342.95328523353, 11892.821607675718, 11974.826670932383, 11980.28118312904, 12370.567030153901, 10182.0, 10366.378017318984, 10556.472176391304, 10708.03627673513, 10804.081653059578, 10250.641227557, 10411.583536658449, 10509.737256568647, 10601.41692206936, 10787.6224332148, 10023.749406264902, 10034.849477257992, 10339.483025848827, 10352.223583231138, 10620.575176992834, 7645.770626881203, 7752.224777522202, 7800.570968596781, 7928.960355198187, 7929.92945952289, 9045.321600879828, 10453.408007626225, 10568.947155264175, 10627.0, 10721.731956700833], "value": 9396.002015361279, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.7813328000798, 1469.8603632654897, 1622.6511300070483, 1795.3177792438871, 1810.9909450452747, 8046.517208967459, 8451.873221901677, 8462.19609137131, 8468.40721149519, 8478.576071196447, 7385.224551422104, 7606.771796846089, 7618.4286178536695, 7711.228877112286, 8183.590820458983, 2080.0535756230956, 2085.6037352902977, 2159.6328624133857, 2232.2075663139594, 2233.4081468410886, 2356.257778799681, 2360.3509035015336, 2360.9173678921243, 2361.669366288721, 2365.349528879555, 2281.7147856517936, 2336.976630233693, 2343.8359314847967, 2346.3547524430746, 2348.0842471436154, 2306.042992158261, 2306.7231932168165, 2338.590746619335, 2344.5310937812505, 2348.178137651825, 2332.941676458085, 2336.941576460585, 2355.0, 2360.8229382796358, 2363.0429675981254, 8203.876941845878, 8403.453775504573, 8405.327573794082, 8407.075221725605, 8432.072472028072, 5060.0, 5215.191645294985, 5217.869553261161, 5237.13587258102, 5237.921431178497, 3234.110619579634, 3447.3967055765393, 3448.189675426273, 3459.8616055357757, 3554.893353199408, 1021.4854059549137, 1036.9481525923673, 1063.3407287481734, 1082.7130810335268, 1085.5603480590373, 10881.891181088167, 10897.291676041032, 10919.890801091968, 10924.071453926346, 11002.0, 4401.889952751143, 4583.977080114645, 4675.4155730533685, 4681.555252251031, 4793.352897358882, 9388.765280868032, 9398.0, 9399.389039712396, 9411.43531388119, 9440.811183776286, 5190.948090519047, 5201.947980520146, 5206.921896171561, 5209.140491818884, 5222.764975576109, 3959.366501359769, 4485.977570112129, 4706.764661766898, 4774.856754297377, 4779.187538118528, 3509.5613048368955, 3658.926821463556, 3658.9451158232646, 3671.596124426311, 3685.0, 1014.705735336744, 1075.6611667324767, 1189.0547015122825, 1279.3411393132544, 1286.5175559165314, 758.2834221660478, 769.445998880805, 823.3948048184579, 908.0692290402391, 916.3356566489375, 5484.341878974548, 5641.351244606841, 5756.798512052058, 5813.09896965971, 5970.731317090741, 901.5221932375852, 919.8436265834824, 945.4847108325979, 948.3551185193996, 985.7831277119022, 3588.1747198144053, 3688.889333320005, 3713.313037088147, 3788.8673896413493, 3842.193139440749, 9729.854052189223, 10235.539400726986, 10256.58973641061, 10269.897301026895, 10271.845922311171, 9611.279154063333, 10199.235057370726, 10213.38719676807, 10219.0, 10222.948885255526, 7537.9623101884135, 7669.53982761025, 7790.999999999886, 7804.2585954334245, 7806.765797026098, 5676.631018983753, 5684.573656975661, 5742.339630942328, 5819.912701309399, 5924.733386997682, 9387.061293870558, 9438.59315874475, 9464.95267523658, 9465.952670236606, 9517.238620910171, 7642.4650274480255, 7849.372050235853, 8061.596920154087, 9325.766855828659, 9681.564329605326, 7075.752348667772, 7157.24848890859, 7200.9639951802, 7240.52936559122, 7291.4166866649475, 7831.334336581226, 7912.643931023233, 7926.0, 7935.761927142195, 7941.960290198512, 6959.164900212007, 7023.789286321418, 7164.068671072727, 7239.746608868875, 7284.344409003215, 6942.305769423017, 7011.754588589375, 7054.6472676367, 7171.677274522554, 7171.748988785367, 6599.835004124743, 6604.702788374439, 6664.100346453167, 6678.098456708282, 6679.2318883329035, 4832.0, 4837.56461918415, 4877.146499362527, 4889.9022019560125, 4896.951030489649, 4766.1420944228985, 4779.569838714532, 4863.635227358033, 4945.975270123627, 4975.378077740283], "value": 4398.319493425929, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.81045294426264, 304.87347750683466, 311.2297064764707, 316.1069977314087, 318.0537899748248, 4623.006053698454, 5244.318238628977, 5267.499587539187, 5303.893922121555, 5326.227696983945, 4431.825566224953, 4810.855674329767, 4927.462356886197, 5075.340205773245, 5097.018244327073, 3826.8810919100983, 3908.550516690574, 3925.9214815703813, 3932.4494570759975, 3944.4674968879262, 3021.8942337018098, 3838.4434257032713, 3941.881743547698, 3989.7207195496326, 3994.8202330895033, 3489.5812502499734, 3854.537455505343, 3962.809937999909, 3964.9603503965022, 4048.7165898387125, 3262.755293353008, 3923.2545816295096, 3973.423853541249, 3999.5600483946887, 4001.9599804002023, 3287.783520097579, 3422.6748458896363, 3448.70685991691, 3473.94789078164, 3491.720662347006, 3893.5133108361456, 4942.283368911523, 5085.389753229636, 5091.1090559152, 5116.539511443951, 4002.9199416011807, 4519.0961807638605, 4551.7724113794175, 4556.134334476439, 4567.2692369221095, 5449.3460784706085, 5471.480209380103, 5479.424660410639, 5485.533729632946, 5553.472794981372, 3819.350710379242, 3850.667998501686, 3852.8651497197466, 3853.402722578005, 3865.207632435344, 5447.428020057877, 5713.14302854571, 5762.366139724627, 5775.62126156195, 5836.970815145897, 3202.4715921873094, 3487.4071407860724, 3676.687481564097, 3872.9031774205305, 3886.5336159661024, 3293.390722716305, 3351.7151042161204, 3444.9827750861086, 3570.3037907608286, 3653.0867283179205, 3886.0090676877217, 4131.586841315904, 4267.4238977737605, 4426.092651006536, 4499.797509112098, 4838.564619120464, 5501.4143352624205, 5504.944950550524, 5508.705454218256, 5532.7786888524815, 1150.3213104268523, 1250.9804509324872, 1260.0801414967125, 1277.6869666931661, 1283.9101262911506, 846.2130218896363, 858.9055203927563, 882.2456799436518, 900.0504467786417, 911.1617312072909, 4438.844640437569, 4824.611817150533, 4844.539768721966, 4866.537678920497, 4931.621839040197, 5199.272101905736, 5371.919421208685, 5420.647657902224, 5429.429909859408, 5452.4286592282115, 4271.227440612111, 4801.1117943180625, 4895.926561101586, 5065.720425808401, 5139.511746384088, 2437.9634305485433, 2537.340291524217, 2805.9298517536818, 2820.464111818748, 2840.488712031854, 5154.53609175176, 5364.409914909357, 5619.915701264485, 5641.420402287366, 5650.265465489542, 5099.56653684441, 5119.744012799345, 5120.846374608767, 5125.820596279113, 5287.920681189786, 4753.572178503882, 4939.90120197594, 5582.581306401955, 5623.493885550321, 5640.971795140998, 4797.88137710491, 5386.148903533992, 5402.514843172185, 5421.647592906448, 5426.565874730091, 4508.977455112704, 4803.567678908915, 5134.666246693953, 5380.435054319397, 5385.865353366041, 5612.887742245214, 5627.8874422512135, 5628.915566266427, 5639.830805075854, 5643.604947653625, 3042.8630711618475, 3501.8774342897877, 3570.7325848086134, 3597.514335564665, 3614.313280476701, 5173.63784535079, 5183.274341592256, 5397.622166448311, 5410.3507579090765, 5440.483154100354, 4482.977585112054, 4489.91020179588, 5047.41954675217, 5219.791208351623, 5244.987584497786, 4648.581627653527, 5042.00861615231, 5046.318746969112, 5155.32980712505, 5240.371155461369, 5128.794733237612, 5131.358580177478, 5143.459936706592, 5170.896582068262, 5298.337707786527, 4800.1119792838435, 4846.479830488197, 5392.541633961, 5420.777481232676, 5536.944630553804, 4702.741349225688, 5343.423690011534, 5377.838664840061, 5382.892342153056, 5393.6763794172475], "value": 3917.8016429604127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.6486929492722, 718.511838101939, 753.630720946736, 770.626246270559, 778.2217782217781, 13777.448902043907, 14074.366653500592, 14090.295485225735, 14094.859051409483, 14096.577102686922, 12570.12009159357, 14178.291085445711, 14748.0, 14795.112293262411, 14801.0, 666.6733300682679, 832.063928080909, 854.8717692346136, 857.0529564830865, 862.4264863865521, 607.8905796956547, 631.8388810853226, 822.0751654388813, 836.8912041434605, 853.1468531468531, 893.3612467086032, 894.0344428017726, 896.4934811831295, 898.8966268879097, 901.7520181949951, 882.8455020371441, 883.7702197428715, 884.2572239318939, 886.6586364249778, 888.9466632002101, 511.06220086141974, 511.2357026245749, 522.8091746512496, 653.8528831013032, 656.2387630348773, 14204.85795142056, 14252.358643861051, 14254.073485223427, 14271.072380295247, 14308.57074287773, 11500.25248358854, 13192.406341714646, 13254.150357673147, 13437.992150588745, 13446.260455674934, 11813.940930295294, 12000.759984800256, 12126.818097728541, 12249.265044097383, 12273.631791046282, 4473.530279320657, 4600.9769951150365, 4609.953900460986, 4612.561806628365, 4641.280601506772, 79930.60034699789, 85141.0, 85626.71559926606, 86585.0, 87675.56162219212, 9921.0, 10325.586976521005, 10385.792284154275, 10748.94625526883, 10760.730981725519, 19923.701144482846, 19924.0, 19962.401127966183, 20013.0, 20037.699434508493, 7359.889601655979, 7560.508566943131, 7570.470067095251, 7571.810704732316, 7662.885056724153, 4084.0606660468256, 4183.958160418418, 4187.476565429321, 4227.957720422818, 4323.0705398339505, 3140.6231252249877, 3243.189202699325, 3248.8538015789345, 3251.772375933662, 3260.722838558749, 548.6461232505012, 554.365251786702, 559.1724248112815, 619.2012304127715, 637.6684124255454, 523.3222976245771, 554.967759966462, 593.7713980117686, 597.1132867691475, 599.4455129005739, 8369.163083691583, 8497.61760720769, 8615.439996400213, 8722.781930451663, 8729.694460693845, 584.8859472402926, 601.0, 613.7575657615217, 614.8985417406167, 625.5277265664395, 3925.2345792570745, 3953.683705303569, 4016.0562267867026, 4296.978515107405, 4391.363252328394, 77488.86366931391, 79959.0, 99855.0, 108932.45533772439, 112560.31159532613, 96010.0, 99689.50155249177, 100370.89614907837, 102203.97796021945, 102860.0, 57312.42687573071, 76044.61977690076, 85274.72087918562, 86412.0, 89637.99999999869, 7951.960240198762, 8044.396670249872, 8081.191880811872, 8151.918480815116, 8309.626066826886, 53648.73175634097, 54234.72882635562, 55440.44559554514, 56320.15519767289, 57990.4200957985, 49414.764630884514, 76666.0, 80972.19027809646, 81466.18533814586, 84155.0, 11534.307941523535, 11914.16600837933, 11954.222975506564, 12108.273503589813, 12828.55100071493, 13707.588772336845, 13806.93096534511, 14044.78932815988, 14148.22184779847, 14683.19242441676, 10716.678499645022, 13119.016073794313, 13151.34243287851, 13192.868071319164, 13477.528286509925, 11019.669409917715, 12430.189168049414, 12741.10812243134, 12878.613641590393, 12937.417816198482, 12196.268223906594, 12738.471383434047, 12921.547745828851, 13081.149725267827, 13519.0, 10325.690229293134, 10711.464426778786, 10867.945660271647, 11074.335539867634, 11364.715882103013, 10440.58237670515, 11118.869690531945, 11265.26775759573, 11365.48855301497, 11474.024707899922], "value": 7155.4560623721645, "unit": "score"}], "name": "dromaeo_css", "value": 7012.212653612963, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 122.0, 168.0, 152.0, 116.0, 114.0, 114.0, 115.0, 114.0, 114.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 139.0, 110.0, 146.0, 111.0, 124.0, 139.0, 114.0, 140.0, 113.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 180.0, 179.0, 180.0, 181.0, 184.0, 181.0, 184.0, 181.0, 183.0], "value": 181.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 57.0, 87.0, 62.0, 59.0, 60.0, 60.0, 112.0, 57.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 95.0, 70.0, 69.0, 68.0, 88.0, 68.0, 69.0, 92.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 81.0, 83.0, 81.0, 85.0, 82.0, 80.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 52.0, 53.0, 57.0, 57.0, 58.0, 56.0, 55.0, 79.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 68.0, 72.0, 74.0, 71.0, 73.0, 71.0, 70.0, 72.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 83.0, 83.0, 84.0, 80.0, 82.0, 80.0, 83.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 151.0, 142.0, 140.0, 141.0, 141.0, 147.0, 138.0, 213.0, 174.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 158.0, 165.0, 162.0, 165.0, 165.0, 164.0, 224.0, 230.0, 170.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 84.0, 89.0, 87.0, 66.0, 67.0, 67.0, 67.0, 66.0, 67.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1458.6999999999998, "alertThreshold": 2.0}]} 20:10:33 INFO - Return code: 0 20:10:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:10:33 INFO - # TBPL SUCCESS # 20:10:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:10:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:10:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:10:33 INFO - 2017-01-04 20:10:33.664 screenresolution[1786:9868] starting screenresolution argv=screenresolution get 20:10:33 INFO - 2017-01-04 20:10:33.666 screenresolution[1786:9868] Display 0: 1600x1200x32@60 20:10:33 INFO - 2017-01-04 20:10:33.672 screenresolution[1787:9871] starting screenresolution argv=screenresolution list 20:10:33 INFO - Available Modes on Display 0 20:10:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:10:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:10:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:10:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:10:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:10:33 INFO - Intel Iris: 20:10:33 INFO - Chipset Model: Intel Iris 20:10:33 INFO - Type: GPU 20:10:33 INFO - Bus: Built-In 20:10:33 INFO - VRAM (Dynamic, Max): 1536 MB 20:10:33 INFO - Vendor: Intel (0x8086) 20:10:33 INFO - Device ID: 0x0a2e 20:10:33 INFO - Revision ID: 0x0009 20:10:33 INFO - Displays: 20:10:33 INFO - Display: 20:10:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:10:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:10:33 INFO - Display Serial Number: 1600x1200 60 20:10:33 INFO - Main Display: Yes 20:10:33 INFO - Mirror: Off 20:10:33 INFO - Online: Yes 20:10:33 INFO - Rotation: Supported 20:10:33 INFO - Return code: 0 20:10:33 INFO - Running post-action listener: _resource_record_post_action 20:10:33 INFO - [mozharness: 2017-01-05 04:10:33.908118Z] Finished run-tests step (success) 20:10:33 INFO - Running post-run listener: _resource_record_post_run 20:10:33 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37954560; Write bytes: 662179840; Read time: 238; Write time: 1979 20:10:33 INFO - TinderboxPrint: CPU usage
53.7% 20:10:33 INFO - TinderboxPrint: I/O read bytes / time
37,954,560 / 238 20:10:33 INFO - TinderboxPrint: I/O write bytes / time
662,179,840 / 1,979 20:10:33 INFO - TinderboxPrint: CPU idle
2,243.2 (46.3%) 20:10:33 INFO - TinderboxPrint: CPU system
975.2 (20.1%) 20:10:33 INFO - TinderboxPrint: CPU user
1,631.1 (33.6%) 20:10:33 INFO - TinderboxPrint: Swap in / out
387,039,232 / 0 20:10:33 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348498432; Write bytes: 346490880; Read time: 26881; Write time: 737 20:10:33 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 34518016; Write bytes: 308414464; Read time: 212; Write time: 1213 20:10:34 INFO - Running post-run listener: _upload_blobber_files 20:10:34 INFO - Blob upload gear active. 20:10:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:10:34 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 20:10:34 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'] 20:10:34 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 20:10:34 INFO - (blobuploader) - INFO - Open directory for files ... 20:10:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:10:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:10:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:10:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:10:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:10:35 INFO - (blobuploader) - INFO - Done attempting. 20:10:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:10:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:10:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:10:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:10:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:10:36 INFO - (blobuploader) - INFO - Done attempting. 20:10:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:10:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:10:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:10:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:10:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:10:38 INFO - (blobuploader) - INFO - Done attempting. 20:10:38 INFO - (blobuploader) - INFO - Iteration through files over. 20:10:38 INFO - Return code: 0 20:10:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:10:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:10:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbc075f66bfb87bd88dc10fa8efdbb686dc3c64615d82236fbc7a9dc7f31ee0f5d538af85f6072254939f47f6e3605713279e83c3289b99916bc8b8d9a4bc519", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/814a4fb37c58c76c4bfda3a02f2e2cc299ae0d010a17f45cb3c9faad0049ca326cd0b6968c12486fafce3058ee8df601aecd8f90ece253cb9344c11bbc5036cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/601abe721ceb6864522806854ede5fedf0feedf883f5cd8b4bc14c763342fb9accd42e0df69420927ed1eb98fe1e186eeae9785c26c7e03134d1e52d57a7cf4f"} 20:10:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:10:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:10:38 INFO - Contents: 20:10:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbc075f66bfb87bd88dc10fa8efdbb686dc3c64615d82236fbc7a9dc7f31ee0f5d538af85f6072254939f47f6e3605713279e83c3289b99916bc8b8d9a4bc519", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/814a4fb37c58c76c4bfda3a02f2e2cc299ae0d010a17f45cb3c9faad0049ca326cd0b6968c12486fafce3058ee8df601aecd8f90ece253cb9344c11bbc5036cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/601abe721ceb6864522806854ede5fedf0feedf883f5cd8b4bc14c763342fb9accd42e0df69420927ed1eb98fe1e186eeae9785c26c7e03134d1e52d57a7cf4f"} 20:10:38 INFO - Running post-run listener: copy_logs_to_upload_dir 20:10:38 INFO - Copying logs to upload dir... 20:10:38 INFO - mkdir: /builds/slave/test/build/upload/logs 20:10:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.721829 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-04 20:10:38.897691) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 20:10:38.900847) ========= 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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/fbc075f66bfb87bd88dc10fa8efdbb686dc3c64615d82236fbc7a9dc7f31ee0f5d538af85f6072254939f47f6e3605713279e83c3289b99916bc8b8d9a4bc519", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/814a4fb37c58c76c4bfda3a02f2e2cc299ae0d010a17f45cb3c9faad0049ca326cd0b6968c12486fafce3058ee8df601aecd8f90ece253cb9344c11bbc5036cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/601abe721ceb6864522806854ede5fedf0feedf883f5cd8b4bc14c763342fb9accd42e0df69420927ed1eb98fe1e186eeae9785c26c7e03134d1e52d57a7cf4f"} build_url:https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010946 build_url: 'https://queue.taskcluster.net/v1/task/N8EhrvmFQBOICH0AjiZi5A/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/fbc075f66bfb87bd88dc10fa8efdbb686dc3c64615d82236fbc7a9dc7f31ee0f5d538af85f6072254939f47f6e3605713279e83c3289b99916bc8b8d9a4bc519", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/814a4fb37c58c76c4bfda3a02f2e2cc299ae0d010a17f45cb3c9faad0049ca326cd0b6968c12486fafce3058ee8df601aecd8f90ece253cb9344c11bbc5036cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/601abe721ceb6864522806854ede5fedf0feedf883f5cd8b4bc14c763342fb9accd42e0df69420927ed1eb98fe1e186eeae9785c26c7e03134d1e52d57a7cf4f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 20:10:38.934428) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:10:38.934769) ========= 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.NkCGNRwv5w/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.yCjnNcVJR7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005037 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:10:38.983395) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 20:10:38.983735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 20:10:38.984043) ========= ========= Total master_lag: 0.18 =========