builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0326 starttime: 1475115415.39 results: success (0) buildid: 20160928165820 builduid: 40097a8745e2425886e384f0d4f199de revision: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.385611) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.386066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.386348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.416742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.417144) ========= 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.jbfmmNAdb4/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.zHPNHdzngs/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-28 19:16:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-09-28 19:16:55 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098375 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.537873) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.541199) ========= 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.jbfmmNAdb4/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.zHPNHdzngs/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.026096 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:55.589797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 19:16:55.593172) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jbfmmNAdb4/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.zHPNHdzngs/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-28 19:16:55,677 truncating revision to first 12 chars 2016-09-28 19:16:55,677 Setting DEBUG logging. 2016-09-28 19:16:55,677 attempt 1/10 2016-09-28 19:16:55,677 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cde8b9afb577?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 19:16:56,732 unpacking tar archive at: autoland-cde8b9afb577/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337701 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 19:16:56.952710) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:56.956063) ========= script_repo_revision: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:56.956460) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:56.956719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 19:16:56.987954) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-28 19:16:56.988345) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jbfmmNAdb4/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.zHPNHdzngs/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:16:57 INFO - MultiFileLogger online at 20160928 19:16:57 in /builds/slave/test 19:16:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:16:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:16:57 INFO - {'append_to_log': False, 19:16:57 INFO - 'base_work_dir': '/builds/slave/test', 19:16:57 INFO - 'blob_upload_branch': 'Autoland', 19:16:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:16:57 INFO - 'branch': 'Autoland', 19:16:57 INFO - 'buildbot_json_path': 'buildprops.json', 19:16:57 INFO - 'config_files': ('talos/mac_config.py',), 19:16:57 INFO - 'default_actions': ('clobber', 19:16:57 INFO - 'read-buildbot-config', 19:16:57 INFO - 'download-and-extract', 19:16:57 INFO - 'populate-webroot', 19:16:57 INFO - 'create-virtualenv', 19:16:57 INFO - 'install', 19:16:57 INFO - 'run-tests'), 19:16:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:16:57 INFO - 'download_minidump_stackwalk': True, 19:16:57 INFO - 'download_symbols': 'ondemand', 19:16:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:16:57 INFO - 'tooltool.py': '/tools/tooltool.py', 19:16:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:16:57 INFO - '/tools/misc-python/virtualenv.py')}, 19:16:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:16:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:16:57 INFO - 'installer_path': 'installer.exe', 19:16:57 INFO - 'log_level': 'info', 19:16:57 INFO - 'log_name': 'talos', 19:16:57 INFO - 'log_to_console': True, 19:16:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:16:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:16:57 INFO - 'opt_config_files': (), 19:16:57 INFO - 'pip_index': False, 19:16:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:16:57 INFO - 'cmd': ('bash', 19:16:57 INFO - '-c', 19:16:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:16:57 INFO - 'enabled': True, 19:16:57 INFO - 'halt_on_failure': False, 19:16:57 INFO - 'name': 'check_screen_resolution'},), 19:16:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:16:57 INFO - 'cmd': ('bash', 19:16:57 INFO - '-c', 19:16:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:16:57 INFO - 'enabled': True, 19:16:57 INFO - 'halt_on_failure': False, 19:16:57 INFO - 'name': 'check_screen_resolution'},), 19:16:57 INFO - 'run_cmd_checks_enabled': True, 19:16:57 INFO - 'sps_profile': False, 19:16:57 INFO - 'sps_profile_interval': 0, 19:16:57 INFO - 'suite': 'dromaeojs', 19:16:57 INFO - 'system_bits': '32', 19:16:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:16:57 INFO - 'title': 't-yosemite-r7-0326', 19:16:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:16:57 INFO - 'use_talos_json': True, 19:16:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:16:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:16:57 INFO - 'work_dir': 'build'} 19:16:57 INFO - [mozharness: 2016-09-29 02:16:57.195379Z] Running clobber step. 19:16:57 INFO - Running pre-action listener: _resource_record_pre_action 19:16:57 INFO - Running main action method: clobber 19:16:57 INFO - rmtree: /builds/slave/test/build 19:16:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:16:59 INFO - Running post-action listener: _resource_record_post_action 19:16:59 INFO - [mozharness: 2016-09-29 02:16:59.403813Z] Finished clobber step (success) 19:16:59 INFO - [mozharness: 2016-09-29 02:16:59.404025Z] Running read-buildbot-config step. 19:16:59 INFO - Running pre-action listener: _resource_record_pre_action 19:16:59 INFO - Running main action method: read_buildbot_config 19:16:59 INFO - Using buildbot properties: 19:16:59 INFO - { 19:16:59 INFO - "project": "", 19:16:59 INFO - "product": "firefox", 19:16:59 INFO - "script_repo_revision": "production", 19:16:59 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:16:59 INFO - "repository": "", 19:16:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:16:59 INFO - "buildid": "20160928165820", 19:16:59 INFO - "pgo_build": "False", 19:16:59 INFO - "basedir": "/builds/slave/test", 19:16:59 INFO - "buildnumber": 310, 19:16:59 INFO - "slavename": "t-yosemite-r7-0326", 19:16:59 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:16:59 INFO - "platform": "yosemite_r7", 19:16:59 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:16:59 INFO - "branch": "autoland", 19:16:59 INFO - "repo_path": "integration/autoland", 19:16:59 INFO - "revision": "cde8b9afb57787e05bee1c891fa9d34bb5bf87a6", 19:16:59 INFO - "stage_platform": "macosx64", 19:16:59 INFO - "builduid": "40097a8745e2425886e384f0d4f199de", 19:16:59 INFO - "slavebuilddir": "test" 19:16:59 INFO - } 19:16:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:16:59 INFO - Running post-action listener: _resource_record_post_action 19:16:59 INFO - [mozharness: 2016-09-29 02:16:59.409517Z] Finished read-buildbot-config step (success) 19:16:59 INFO - [mozharness: 2016-09-29 02:16:59.409735Z] Running download-and-extract step. 19:16:59 INFO - Running pre-action listener: _resource_record_pre_action 19:16:59 INFO - Running main action method: download_and_extract 19:16:59 INFO - mkdir: /builds/slave/test/build/tests 19:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:59 INFO - trying https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:16:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 19:17:00 INFO - Downloaded 1199 bytes. 19:17:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:17:00 INFO - Using the following test package requirements: 19:17:00 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:17:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:17:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:17:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'jsshell-mac.zip'], 19:17:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:17:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:17:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:17:00 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:17:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:17:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:17:00 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:17:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:17:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:17:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:17:00 INFO - Fetch https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:17:01 INFO - Http code: 200 19:17:01 INFO - Content-Encoding: None 19:17:01 INFO - Content-Type: application/zip 19:17:01 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 19:17:01 INFO - x-amz-cf-id: 8eHtsDuUF9Zh3nNft2jejsINjdVJsBT6CtJ1izmj1wWIrYOLHiGegg== 19:17:01 INFO - x-amz-version-id: KMfTt2rPDY0AFZ15q7rhO14FhtJLvwqg 19:17:01 INFO - x-cache: Hit from cloudfront 19:17:01 INFO - Expected file size: 16472838 19:17:01 INFO - Obtained file size: 16472838 19:17:08 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:17:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:17:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:17:08 INFO - Fetch https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:17:08 INFO - Http code: 200 19:17:08 INFO - Content-Encoding: None 19:17:08 INFO - Content-Type: application/zip 19:17:08 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 19:17:08 INFO - x-amz-cf-id: H9VDPj-EtyVqaq-A_acQTw9gbA60SwUOj-7RRvtyL-qkl8SlaXuEyw== 19:17:08 INFO - x-amz-version-id: KMfTt2rPDY0AFZ15q7rhO14FhtJLvwqg 19:17:08 INFO - x-cache: Hit from cloudfront 19:17:09 INFO - Expected file size: 16472838 19:17:09 INFO - Obtained file size: 16472838 19:17:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:17:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:17:15 INFO - Fetch https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:17:16 INFO - Http code: 200 19:17:16 INFO - Content-Encoding: None 19:17:16 INFO - Content-Type: application/zip 19:17:16 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 19:17:16 INFO - x-amz-cf-id: lRoRhoYQVqm4RWhG_mSJrCEGH7Pn97m5fMQ_26th7qiOFMTsoN7Heg== 19:17:16 INFO - x-amz-version-id: O6xu5c9l9EHAofsZquaXjowvEw4LWPEk 19:17:16 INFO - x-cache: Hit from cloudfront 19:17:17 INFO - Expected file size: 14414067 19:17:17 INFO - Obtained file size: 14414067 19:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:17 INFO - trying https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 19:17:20 INFO - Downloaded 111795964 bytes. 19:17:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:17:20 INFO - mkdir: /builds/slave/test/properties 19:17:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:17:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:17:20 INFO - Contents: 19:17:20 INFO - build_url:https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:17:21 INFO - Running post-action listener: _resource_record_post_action 19:17:21 INFO - Running post-action listener: set_extra_try_arguments 19:17:21 INFO - [mozharness: 2016-09-29 02:17:21.239537Z] Finished download-and-extract step (success) 19:17:21 INFO - [mozharness: 2016-09-29 02:17:21.239682Z] Running populate-webroot step. 19:17:21 INFO - Running pre-action listener: _resource_record_pre_action 19:17:21 INFO - Running main action method: populate_webroot 19:17:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:17:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:17:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:17:21 INFO - '--tppagecycles', 19:17:21 INFO - '7'], 19:17:21 INFO - 'tests': ['tsvgm']}}, 19:17:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:17:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:17:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:17:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:17:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:17:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:17:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['damp', 'tps']}, 19:17:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'tests': ['damp', 'tps']}, 19:17:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['dromaeo_dom']}, 19:17:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:17:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['basic_compositor_video']}, 19:17:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:17:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['a11yr', 19:17:21 INFO - 'ts_paint', 19:17:21 INFO - 'tpaint', 19:17:21 INFO - 'sessionrestore', 19:17:21 INFO - 'sessionrestore_no_auto_restore', 19:17:21 INFO - 'tabpaint']}, 19:17:21 INFO - 'other-e10s': {'tests': ['a11yr', 19:17:21 INFO - 'ts_paint', 19:17:21 INFO - 'tpaint', 19:17:21 INFO - 'sessionrestore', 19:17:21 INFO - 'sessionrestore_no_auto_restore', 19:17:21 INFO - 'tabpaint']}, 19:17:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:17:21 INFO - 'ts_paint', 19:17:21 INFO - 'tpaint', 19:17:21 INFO - 'sessionrestore', 19:17:21 INFO - 'sessionrestore_no_auto_restore', 19:17:21 INFO - 'tabpaint']}, 19:17:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:17:21 INFO - 'ts_paint', 19:17:21 INFO - 'tpaint', 19:17:21 INFO - 'sessionrestore', 19:17:21 INFO - 'sessionrestore_no_auto_restore', 19:17:21 INFO - 'tabpaint']}, 19:17:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['a11yr', 19:17:21 INFO - 'ts_paint', 19:17:21 INFO - 'tpaint', 19:17:21 INFO - 'sessionrestore', 19:17:21 INFO - 'sessionrestore_no_auto_restore', 19:17:21 INFO - 'tabpaint']}, 19:17:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['a11yr', 19:17:21 INFO - 'ts_paint', 19:17:21 INFO - 'tpaint', 19:17:21 INFO - 'sessionrestore', 19:17:21 INFO - 'sessionrestore_no_auto_restore', 19:17:21 INFO - 'tabpaint']}, 19:17:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['tsvgx', 19:17:21 INFO - 'tsvgr_opacity', 19:17:21 INFO - 'tart', 19:17:21 INFO - 'tscrollx', 19:17:21 INFO - 'cart']}, 19:17:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:17:21 INFO - 'tsvgr_opacity', 19:17:21 INFO - 'tart', 19:17:21 INFO - 'tscrollx', 19:17:21 INFO - 'cart']}, 19:17:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'talos_options': ['--disable-e10s'], 19:17:21 INFO - 'tests': ['tp5o']}, 19:17:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'tests': ['tp5o']}, 19:17:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'talos_options': ['--disable-e10s', 19:17:21 INFO - '--xperf_path', 19:17:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:17:21 INFO - 'tests': ['tp5n']}, 19:17:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:17:21 INFO - 'talos_options': ['--xperf_path', 19:17:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:17:21 INFO - 'tests': ['tp5n']}}, 19:17:21 INFO - 'talos.zip': {'path': '', 19:17:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:17:21 INFO - Running post-action listener: _resource_record_post_action 19:17:21 INFO - [mozharness: 2016-09-29 02:17:21.258650Z] Finished populate-webroot step (success) 19:17:21 INFO - [mozharness: 2016-09-29 02:17:21.258789Z] Running create-virtualenv step. 19:17:21 INFO - Running pre-action listener: _resource_record_pre_action 19:17:21 INFO - Running main action method: create_virtualenv 19:17:21 INFO - Creating virtualenv /builds/slave/test/build/venv 19:17:21 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:17:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:17:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:17:21 INFO - Using real prefix '/tools/python27' 19:17:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:17:22 INFO - Installing distribute.............................................................................................................................................................................................done. 19:17:24 INFO - Installing pip.................done. 19:17:24 INFO - Return code: 0 19:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:17:25 INFO - Reading from file tmpfile_stdout 19:17:25 INFO - Output received: 19:17:25 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:17:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:17:25 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:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:25 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:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:25 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:25 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:17:25 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:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:25 INFO - 'HOME': '/Users/cltbld', 19:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:25 INFO - 'LOGNAME': 'cltbld', 19:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:25 INFO - 'MOZ_NO_REMOTE': '1', 19:17:25 INFO - 'NO_EM_RESTART': '1', 19:17:25 INFO - 'PAGER': '/bin/cat', 19:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:25 INFO - 'PWD': '/builds/slave/test', 19:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:25 INFO - 'SHELL': '/bin/bash', 19:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:25 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:25 INFO - 'USER': 'cltbld', 19:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:25 INFO - 'XPC_FLAGS': '0x0', 19:17:25 INFO - 'XPC_SERVICE_NAME': '0', 19:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:25 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:17:25 INFO - Cleaning up... 19:17:25 INFO - Return code: 0 19:17:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:17:25 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:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:25 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:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:25 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:25 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:17:25 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:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:25 INFO - 'HOME': '/Users/cltbld', 19:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:25 INFO - 'LOGNAME': 'cltbld', 19:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:25 INFO - 'MOZ_NO_REMOTE': '1', 19:17:25 INFO - 'NO_EM_RESTART': '1', 19:17:25 INFO - 'PAGER': '/bin/cat', 19:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:25 INFO - 'PWD': '/builds/slave/test', 19:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:25 INFO - 'SHELL': '/bin/bash', 19:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:25 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:25 INFO - 'USER': 'cltbld', 19:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:25 INFO - 'XPC_FLAGS': '0x0', 19:17:25 INFO - 'XPC_SERVICE_NAME': '0', 19:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:25 INFO - Downloading/unpacking psutil>=3.1.1 19:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:17:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:17:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:17:29 INFO - Installing collected packages: psutil 19:17:29 INFO - Running setup.py install for psutil 19:17:29 INFO - building 'psutil._psutil_osx' extension 19:17:29 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:17:29 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:17:29 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:17:29 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:17:30 INFO - building 'psutil._psutil_posix' extension 19:17:30 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:17:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:17:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:17:30 INFO - ^ 19:17:30 INFO - 1 warning generated. 19:17:30 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:17:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:17:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:17:30 INFO - Successfully installed psutil 19:17:30 INFO - Cleaning up... 19:17:30 INFO - Return code: 0 19:17:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:17:30 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:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:30 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:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:30 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:30 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:17:30 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:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:30 INFO - 'HOME': '/Users/cltbld', 19:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:30 INFO - 'LOGNAME': 'cltbld', 19:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:30 INFO - 'MOZ_NO_REMOTE': '1', 19:17:30 INFO - 'NO_EM_RESTART': '1', 19:17:30 INFO - 'PAGER': '/bin/cat', 19:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:30 INFO - 'PWD': '/builds/slave/test', 19:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:30 INFO - 'SHELL': '/bin/bash', 19:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:30 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:30 INFO - 'USER': 'cltbld', 19:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:30 INFO - 'XPC_FLAGS': '0x0', 19:17:30 INFO - 'XPC_SERVICE_NAME': '0', 19:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:17:30 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:17:30 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:17:30 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:17:30 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:17:30 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:17:30 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:17:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:17:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:17:33 INFO - Installing collected packages: mozsystemmonitor 19:17:33 INFO - Running setup.py install for mozsystemmonitor 19:17:33 INFO - Successfully installed mozsystemmonitor 19:17:33 INFO - Cleaning up... 19:17:33 INFO - Return code: 0 19:17:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:33 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:33 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:17:33 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:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:33 INFO - 'HOME': '/Users/cltbld', 19:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:33 INFO - 'LOGNAME': 'cltbld', 19:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:33 INFO - 'MOZ_NO_REMOTE': '1', 19:17:33 INFO - 'NO_EM_RESTART': '1', 19:17:33 INFO - 'PAGER': '/bin/cat', 19:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:33 INFO - 'PWD': '/builds/slave/test', 19:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:33 INFO - 'SHELL': '/bin/bash', 19:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:33 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:33 INFO - 'USER': 'cltbld', 19:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:33 INFO - 'XPC_FLAGS': '0x0', 19:17:33 INFO - 'XPC_SERVICE_NAME': '0', 19:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:33 INFO - Downloading/unpacking jsonschema==2.5.1 19:17:33 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:17:33 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:17:33 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:17:33 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:17:33 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:17:33 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:17:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:17:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:36 INFO - Downloading functools32-3.2.3-2.tar.gz 19:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:17:36 INFO - warning: no files found matching '*.txt' 19:17:36 INFO - no previously-included directories found matching 'build' 19:17:36 INFO - no previously-included directories found matching 'dist' 19:17:36 INFO - no previously-included directories found matching '.git*' 19:17:36 INFO - Installing collected packages: jsonschema, functools32 19:17:36 INFO - Running setup.py install for functools32 19:17:36 INFO - warning: no files found matching '*.txt' 19:17:36 INFO - no previously-included directories found matching 'build' 19:17:36 INFO - no previously-included directories found matching 'dist' 19:17:36 INFO - no previously-included directories found matching '.git*' 19:17:36 INFO - Successfully installed jsonschema functools32 19:17:36 INFO - Cleaning up... 19:17:36 INFO - Return code: 0 19:17:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:36 INFO - 'HOME': '/Users/cltbld', 19:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:36 INFO - 'LOGNAME': 'cltbld', 19:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:36 INFO - 'MOZ_NO_REMOTE': '1', 19:17:36 INFO - 'NO_EM_RESTART': '1', 19:17:36 INFO - 'PAGER': '/bin/cat', 19:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:36 INFO - 'PWD': '/builds/slave/test', 19:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:36 INFO - 'SHELL': '/bin/bash', 19:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:36 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:36 INFO - 'USER': 'cltbld', 19:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:36 INFO - 'XPC_FLAGS': '0x0', 19:17:36 INFO - 'XPC_SERVICE_NAME': '0', 19:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:17:37 INFO - Cleaning up... 19:17:37 INFO - Return code: 0 19:17:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:37 INFO - 'HOME': '/Users/cltbld', 19:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:37 INFO - 'LOGNAME': 'cltbld', 19:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:37 INFO - 'MOZ_NO_REMOTE': '1', 19:17:37 INFO - 'NO_EM_RESTART': '1', 19:17:37 INFO - 'PAGER': '/bin/cat', 19:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:37 INFO - 'PWD': '/builds/slave/test', 19:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:37 INFO - 'SHELL': '/bin/bash', 19:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:37 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:37 INFO - 'USER': 'cltbld', 19:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:37 INFO - 'XPC_FLAGS': '0x0', 19:17:37 INFO - 'XPC_SERVICE_NAME': '0', 19:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:37 INFO - Downloading/unpacking blobuploader==1.2.4 19:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:39 INFO - Downloading blobuploader-1.2.4.tar.gz 19:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:17:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:17:40 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:17:40 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:17:40 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:17:40 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:17:40 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:17:40 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:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:17:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:17:40 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:17:40 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:17:40 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:17:40 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:17:40 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:17:40 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:17:41 INFO - Downloading docopt-0.6.1.tar.gz 19:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:17:41 INFO - Installing collected packages: blobuploader, requests, docopt 19:17:41 INFO - Running setup.py install for blobuploader 19:17:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:17:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:17:41 INFO - Running setup.py install for requests 19:17:41 INFO - Running setup.py install for docopt 19:17:41 INFO - Successfully installed blobuploader requests docopt 19:17:41 INFO - Cleaning up... 19:17:42 INFO - Return code: 0 19:17:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:17:42 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:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:42 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:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:42 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:42 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:17:42 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:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:42 INFO - 'HOME': '/Users/cltbld', 19:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:42 INFO - 'LOGNAME': 'cltbld', 19:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:42 INFO - 'MOZ_NO_REMOTE': '1', 19:17:42 INFO - 'NO_EM_RESTART': '1', 19:17:42 INFO - 'PAGER': '/bin/cat', 19:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:42 INFO - 'PWD': '/builds/slave/test', 19:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:42 INFO - 'SHELL': '/bin/bash', 19:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:42 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:42 INFO - 'USER': 'cltbld', 19:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:42 INFO - 'XPC_FLAGS': '0x0', 19:17:42 INFO - 'XPC_SERVICE_NAME': '0', 19:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ZDPLtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-2UYvKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-xdrG9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-JBv0qA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-41vQVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-gqkql9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:17:42 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ka3teN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-eXS1pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-5PMJMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-mHEnTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-0CffYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-o6vboj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-MmC4p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-BIbPuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-CSHK4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:17:43 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-SLn_1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:17:44 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-3hleq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:17:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:17:44 INFO - Running setup.py install for manifestparser 19:17:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:17:44 INFO - Running setup.py install for mozcrash 19:17:44 INFO - Running setup.py install for mozdebug 19:17:44 INFO - Running setup.py install for mozdevice 19:17:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:17:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:17:44 INFO - Running setup.py install for mozfile 19:17:44 INFO - Running setup.py install for mozhttpd 19:17:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:17:44 INFO - Running setup.py install for mozinfo 19:17:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Running setup.py install for mozInstall 19:17:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Running setup.py install for mozleak 19:17:45 INFO - Running setup.py install for mozlog 19:17:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Running setup.py install for moznetwork 19:17:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:17:45 INFO - Running setup.py install for mozprocess 19:17:45 INFO - Running setup.py install for mozprofile 19:17:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:17:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:17:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:17:46 INFO - Running setup.py install for mozrunner 19:17:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:17:46 INFO - Running setup.py install for mozscreenshot 19:17:46 INFO - Running setup.py install for moztest 19:17:46 INFO - Running setup.py install for mozversion 19:17:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:17:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:17:46 INFO - Cleaning up... 19:17:46 INFO - Return code: 0 19:17:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:17:46 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:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:46 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:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:46 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:46 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:17:46 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:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:46 INFO - 'HOME': '/Users/cltbld', 19:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:46 INFO - 'LOGNAME': 'cltbld', 19:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:46 INFO - 'MOZ_NO_REMOTE': '1', 19:17:46 INFO - 'NO_EM_RESTART': '1', 19:17:46 INFO - 'PAGER': '/bin/cat', 19:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:46 INFO - 'PWD': '/builds/slave/test', 19:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:46 INFO - 'SHELL': '/bin/bash', 19:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:46 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:46 INFO - 'USER': 'cltbld', 19:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:46 INFO - 'XPC_FLAGS': '0x0', 19:17:46 INFO - 'XPC_SERVICE_NAME': '0', 19:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:17:46 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ZjCQUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Ml80l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-sWgaST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-FpNNdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-FSHbon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-2RdxjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-stlUxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-36TuMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-caWqdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:17:47 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:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:17:47 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-lW4w20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:17:48 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:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ckbbUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:17:48 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:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-8l1Ohy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:17:48 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:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-WmGtNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:17:48 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:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-6Dll9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-7HcEA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:48 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:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-7emr7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:17:48 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-EeG81A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:17:48 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:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:17:48 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:17:48 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:17:48 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:17:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:48 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:17:51 INFO - Downloading blessings-1.6.tar.gz 19:17:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:17:51 INFO - Installing collected packages: blessings 19:17:51 INFO - Running setup.py install for blessings 19:17:51 INFO - Successfully installed blessings 19:17:51 INFO - Cleaning up... 19:17:51 INFO - Return code: 0 19:17:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:17:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:17:51 INFO - Reading from file tmpfile_stdout 19:17:51 INFO - Current package versions: 19:17:51 INFO - blessings == 1.6 19:17:51 INFO - blobuploader == 1.2.4 19:17:51 INFO - docopt == 0.6.1 19:17:51 INFO - functools32 == 3.2.3-2 19:17:51 INFO - jsonschema == 2.5.1 19:17:51 INFO - manifestparser == 1.1 19:17:51 INFO - mozInstall == 1.12 19:17:51 INFO - mozcrash == 0.17 19:17:51 INFO - mozdebug == 0.1 19:17:51 INFO - mozdevice == 0.48 19:17:51 INFO - mozfile == 1.2 19:17:51 INFO - mozhttpd == 0.7 19:17:51 INFO - mozinfo == 0.9 19:17:51 INFO - mozleak == 0.1 19:17:51 INFO - mozlog == 3.4 19:17:51 INFO - moznetwork == 0.27 19:17:51 INFO - mozprocess == 0.23 19:17:51 INFO - mozprofile == 0.28 19:17:51 INFO - mozrunner == 6.12 19:17:51 INFO - mozscreenshot == 0.1 19:17:51 INFO - mozsystemmonitor == 0.3 19:17:51 INFO - moztest == 0.7 19:17:51 INFO - mozversion == 1.4 19:17:51 INFO - psutil == 3.1.1 19:17:51 INFO - requests == 1.2.3 19:17:51 INFO - wsgiref == 0.1.2 19:17:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:17:51 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:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17: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:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:52 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:52 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:17:52 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:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:52 INFO - 'HOME': '/Users/cltbld', 19:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:52 INFO - 'LOGNAME': 'cltbld', 19:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:52 INFO - 'MOZ_NO_REMOTE': '1', 19:17:52 INFO - 'NO_EM_RESTART': '1', 19:17:52 INFO - 'PAGER': '/bin/cat', 19:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:52 INFO - 'PWD': '/builds/slave/test', 19:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:52 INFO - 'SHELL': '/bin/bash', 19:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:52 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:52 INFO - 'USER': 'cltbld', 19:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:52 INFO - 'XPC_FLAGS': '0x0', 19:17:52 INFO - 'XPC_SERVICE_NAME': '0', 19:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 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:17:52 INFO - Cleaning up... 19:17:52 INFO - Return code: 0 19:17:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:17: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:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17: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:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:17:53 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 0x10d506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d47c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbca58dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, '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.jbfmmNAdb4/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.zHPNHdzngs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:17:53 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:17:53 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:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:53 INFO - 'HOME': '/Users/cltbld', 19:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:53 INFO - 'LOGNAME': 'cltbld', 19:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:53 INFO - 'MOZ_NO_REMOTE': '1', 19:17:53 INFO - 'NO_EM_RESTART': '1', 19:17:53 INFO - 'PAGER': '/bin/cat', 19:17:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:53 INFO - 'PWD': '/builds/slave/test', 19:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:53 INFO - 'SHELL': '/bin/bash', 19:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:17:53 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:53 INFO - 'USER': 'cltbld', 19:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:53 INFO - 'XPC_FLAGS': '0x0', 19:17:53 INFO - 'XPC_SERVICE_NAME': '0', 19:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:17:53 INFO - Cleaning up... 19:17:53 INFO - Return code: 0 19:17:53 INFO - Running post-action listener: _resource_record_post_action 19:17:53 INFO - Running post-action listener: _start_resource_monitoring 19:17:53 INFO - Starting resource monitoring. 19:17:53 INFO - [mozharness: 2016-09-29 02:17:53.431414Z] Finished create-virtualenv step (success) 19:17:53 INFO - [mozharness: 2016-09-29 02:17:53.432437Z] Running install step. 19:17:53 INFO - Running pre-action listener: _resource_record_pre_action 19:17:53 INFO - Running main action method: install 19:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:17:53 INFO - Reading from file tmpfile_stdout 19:17:53 INFO - Detecting whether we're running mozinstall >=1.0... 19:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:17:53 INFO - Reading from file tmpfile_stdout 19:17:53 INFO - Output received: 19:17:53 INFO - Usage: mozinstall [options] installer 19:17:53 INFO - Options: 19:17:53 INFO - -h, --help show this help message and exit 19:17:53 INFO - -d DEST, --destination=DEST 19:17:53 INFO - Directory to install application into. [default: 19:17:53 INFO - "/builds/slave/test"] 19:17:53 INFO - --app=APP Application being installed. [default: firefox] 19:17:53 INFO - mkdir: /builds/slave/test/build/application 19:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:18:27 INFO - Reading from file tmpfile_stdout 19:18:27 INFO - Output received: 19:18:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:18:27 INFO - Running post-action listener: _resource_record_post_action 19:18:27 INFO - [mozharness: 2016-09-29 02:18:27.830392Z] Finished install step (success) 19:18:27 INFO - [mozharness: 2016-09-29 02:18:27.830529Z] Running run-tests step. 19:18:27 INFO - Running pre-action listener: _resource_record_pre_action 19:18:27 INFO - Running main action method: run_tests 19:18:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:18:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:18:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:18:27 INFO - 2016-09-28 19:18:27.844 screenresolution[1641:7505] starting screenresolution argv=screenresolution get 19:18:27 INFO - 2016-09-28 19:18:27.847 screenresolution[1641:7505] Display 0: 1600x1200x32@60 19:18:27 INFO - 2016-09-28 19:18:27.853 screenresolution[1642:7507] starting screenresolution argv=screenresolution list 19:18:27 INFO - Available Modes on Display 0 19:18:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:18:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:18:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:18:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:18:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:18:28 INFO - Intel Iris: 19:18:28 INFO - Chipset Model: Intel Iris 19:18:28 INFO - Type: GPU 19:18:28 INFO - Bus: Built-In 19:18:28 INFO - VRAM (Dynamic, Max): 1536 MB 19:18:28 INFO - Vendor: Intel (0x8086) 19:18:28 INFO - Device ID: 0x0a2e 19:18:28 INFO - Revision ID: 0x0009 19:18:28 INFO - Displays: 19:18:28 INFO - Display: 19:18:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:18:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:18:28 INFO - Display Serial Number: 1600x1200 60 19:18:28 INFO - Main Display: Yes 19:18:28 INFO - Mirror: Off 19:18:28 INFO - Online: Yes 19:18:28 INFO - Rotation: Supported 19:18:28 INFO - Return code: 0 19:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:18:28 INFO - Python 2.7.3 19:18:28 INFO - Return code: 0 19:18:28 INFO - grabbing minidump binary from tooltool 19:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:28 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 0x10d31ad30>, '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 0x10d140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d312b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:18:28 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:18:28 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:18:28 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:18:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:18:28 INFO - Return code: 0 19:18:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:18:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:18:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:18:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:18:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0326 --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:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jbfmmNAdb4/Render', 19:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:28 INFO - 'HOME': '/Users/cltbld', 19:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:28 INFO - 'LOGNAME': 'cltbld', 19:18:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:18:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:28 INFO - 'MOZ_NO_REMOTE': '1', 19:18:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:18:28 INFO - 'NO_EM_RESTART': '1', 19:18:28 INFO - 'PAGER': '/bin/cat', 19:18:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:28 INFO - 'PWD': '/builds/slave/test', 19:18:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:28 INFO - 'SHELL': '/bin/bash', 19:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zHPNHdzngs/Listeners', 19:18:28 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 19:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:28 INFO - 'USER': 'cltbld', 19:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:28 INFO - 'XPC_FLAGS': '0x0', 19:18:28 INFO - 'XPC_SERVICE_NAME': '0', 19:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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:18:28 INFO - mozversion application_buildid: 20160928165820 19:18:28 INFO - mozversion application_changeset: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 19:18:28 INFO - mozversion application_display_name: Nightly 19:18:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:18:28 INFO - mozversion application_name: Firefox 19:18:28 INFO - mozversion application_remotingname: firefox 19:18:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:18:28 INFO - mozversion application_vendor: Mozilla 19:18:28 INFO - mozversion application_version: 52.0a1 19:18:28 INFO - mozversion platform_buildid: 20160928165820 19:18:28 INFO - mozversion platform_changeset: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 19:18:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:18:28 INFO - mozversion platform_version: 52.0a1 19:18:28 INFO - using testdate: 1475115508 19:18:28 INFO - actual date: 1475115508 19:18:28 INFO - starting webserver on 'localhost:49267' 19:18:28 INFO - SUITE-START | Running 2 tests 19:18:28 INFO - TEST-START | dromaeo_css 19:18:28 INFO - operating with platform_type : mac_ 19:18:28 INFO - Initialising browser for dromaeo_css test... 19:18:28 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpze1VMy/profile http://localhost:49267/getInfo.html) 19:18:30 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 19:18:30 INFO - PROCESS | 1652 | colorDepth:24 19:18:30 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 19:18:30 INFO - PROCESS | 1652 | __metrics 19:18:30 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:18:30 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:18:31 INFO - TEST-INFO | 1652: exit 0 19:18:31 INFO - Browser initialized. 19:18:31 INFO - Running cycle 1/1 for dromaeo_css test... 19:18:31 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpze1VMy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:18:43 INFO - PROCESS | 1654 | RSS: Main: 236023808 19:18:43 INFO - PROCESS | 1654 | 19:19:34 INFO - PROCESS | 1654 | 1475115574891 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:21:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 19:21:47 INFO - PROCESS | 1654 | RSS: Main: 372609024 19:21:47 INFO - PROCESS | 1654 | 19:24:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 19:24:51 INFO - PROCESS | 1654 | RSS: Main: 369508352 19:24:51 INFO - PROCESS | 1654 | 19:27:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 19:27:54 INFO - PROCESS | 1654 | RSS: Main: 337694720 19:27:54 INFO - PROCESS | 1654 | 19:30:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 19:30:57 INFO - PROCESS | 1654 | RSS: Main: 346521600 19:30:57 INFO - PROCESS | 1654 | 19:34:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 19:34:01 INFO - PROCESS | 1654 | RSS: Main: 333606912 19:34:01 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 19:37:04 INFO - PROCESS | 1654 | RSS: Main: 452837376 19:37:04 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | __start_tp_report 19:37:04 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 19:37:04 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 19:37:04 INFO - PROCESS | 1654 | |i|pagename|runs| 19:37:04 INFO - PROCESS | 1654 | |0;dojo.html;3283.8686452541892;3292.670732926706;3294.736421086313;3345.5483509726178;3346.7322614190866;5557.249771280876;5586.050371436855;5618.606697531174;5629.211910332551;5661.320641523023;4675.555822196894;5604.887902241952;5636.323641163056;5642.57680673949;5661.66030038198;5110;5140.33175687162;5158.174692049273;5181.455947125554;5221.817236396727;5172.19830926207;5254.81608143715;5257.00116977773;5260.736963151846;5274.9472505275035;5172.069027575035;5196.68819870809;5224.582033437335;5238.423773384925;5268.446813084628;5225.346831646025;5228.816991405321;5241.056609810233;5245.606579506552;5277.498637629419;5044.949550504484;5071.41678706946;5125.692458452505;5178.948210517884;5187.04039752647;4826.396700412449;5411.134218525017;5569.888602227973;5629.437056294379;5640.210370548086;6150.938490615082;6151.138840562325;6157.846053848645;6161.260648722137;6174.981128113855;5996.820095397145;6097;6183.752649893999;6240.531960102965;6269.341719119472;5639.323281206241;5691.146328050742;5693.0891057431445;5711.343195532567;5751;6275.560710750204;6277.403646653652;6278.780242691484;6297.6536290503545;6311.96844015777;5867.471927526543;5968.432998865101;6020.518358531307;6042.637441753509;6045.818625441244;6093.413838573086;6110.755569777248;6127.250613123855;6157.353477884758;6234.251889773256;6144.446999770043;6151.477124444383;6152.323244443108;6161.476274516626;6163.7842675506145;6168.228971378578;6169.475594574422;6183.319834818167;6187.381261873777;6200.56596038273;4730.337752714623;4823.831165909176;4846.36997190363;4852.951470485321;4880.365552478155;4339.717918335298;4346.21768081742;4370.2570563004365;4371.650267978554;4405.60349568534;5612.35457922336;5663.745131469093;5675.602707810413;5676.659400435987;5686.289213848269;6064.090386441981;6081.148639190517;6136.509079273647;6233.532485063638;6238.313785483593;5596.916046259309;5636.408177141423;5646.2942132233475;5653.123765816305;5671.461211184931;5308.389535203424;5309.177077552986;5316.202569614511;5319.2287118368395;5324.9733751331;6212.161358216583;6237.064440333896;6273.62358258506;6282.120503129566;6282.591631543935;6210.813675589739;6226.034964580497;6228.22147231596;6234.096056071935;6243.469305108935;6251.6561589113035;6255.749770009331;6259.3427690091885;6278.372162783868;6282.4974002080635;5916.940830591639;5975.55183361241;5978.282606087298;6087.421694938974;6143.416375444325;4776.617870570277;5583.8883222336135;6218.098375735583;6234.6570938597;6237.812865614039;5356.7589458473685;5730.512906402836;6039.245094363205;6049.274087109575;6053;5854.531637469077;6033.849153771191;6048.4858787003595;6051.939480605138;6053.939460605514;5765.538756899522;5796.304443466811;5809.2738407699035;5887.352391236875;5891.440313170242;6045.244344456943;6048.37109638519;6061.848453788691;6094.421030002142;6109.786157484645;5834.912476312944;5841.649501029951;5885.352611212677;5885.61743486672;5900.527957763455 19:37:04 INFO - PROCESS | 1654 | |1;ext.html;515.3635260453341;532.5766016017267;535.4297672978275;560.5263552298306;567.1860879637723;38792.61207387925;41213.58786412135;41362.793186034105;41377.17245655085;41430.58569414305;42271.57728422715;42689;42723;42802;42865;8728.51993140377;8950.641974321019;9582.329236953416;9775.120239178472;9781.266405019616;11907.047436205126;12198.024158067377;12217.938910305436;12341.5063397464;12345.13584049117;12049.397530123459;12135.878641213607;12136.45385957634;12152.878471215263;12174.878251217464;8786.64853405863;8980.820383592358;11861.406929653569;12022.69943251417;12176.26942383451;11920.642380728592;11982.041436685131;11982.880171198263;11988.940055299754;11999.820002699878;32628;39684.801575991936;39998.80003599897;40043.199136017116;40104.59895401038;3291.637919828841;3335.182880194344;3406.080358303269;3575.3022538434852;3930.8993481825137;30879.91920282802;31269.84365078183;31599.052028439182;31927.84036079828;32151.678483215102;3180.4116238495953;3197.5843140391835;3217.9034628961167;3273.49260864564;3317.270200555874;493379.53310233576;494481.52759235975;495016;495107;495303;33243.3351332972;34121.82939085289;59104;59316.703416483506;59434.40565594375;35126.64873351285;35213.59145634146;35475.290494190485;35502.93491195268;35507;14536.563903082924;14649;14664.633384165481;14669.413223471154;14680.41278348854;11313.547458101748;11469.942650286695;12163.02695784335;12374.566890158801;12491.937540312421;7848.940393046865;7860;7890.132085470594;7947.5231486111015;7948.801279968047;3221.7261532769508;3260.8206548639578;3270.444024515838;3283.6223834259367;3311.867525298961;799.7360870912702;837.4431003382682;881.1144799476613;996.7408473796866;1051.9474026298653;25832.48335033289;27381.72618273792;28362.85818570894;29765.809367625487;31174;1014.5079636376352;1077.498962982217;1096.9780604387868;1165.4056431220138;1217.72601164739;13293;15036.548903532912;15324.693506129815;15366.615834604001;15596.440355964312;666495;671236;671937;673395;674073;57289.14066289009;57619;57659.13511297334;57677.711611442515;57718.7114064427;1229.287013532149;1257.7107265329025;1334.7463981843418;1354.512375544803;1359.7484465373936;29177.562336565392;29879.701202987693;30987.380252394374;31238.531422028234;31709.36581268408;36814;48915;48936.510634893195;49014.01971960612;49043.75478122586;480637;483170;483686;486397;487313;26448.074317398798;27203.455930880875;27317.863410682818;28121.43757124805;28272.434551308448;44418.33607471169;45176.87057823581;45207.773961129984;45417.86455338643;45754.771226144985;34806;39101.41347879727;39255.60744392634;39324;40291.597084029956;37582;38335;38341.08294585315;38762.37945497943;39601.80199098986;38097.61902381052;38526.80736596299;38827;38850.4172437408;38857.80571097126;27823.443531128858;30435.39129217359;30481;31456.89900853459;35458;23590.764092358855;24986.250412487654;25207.74792252054;25796;26043.869780650977 19:37:04 INFO - PROCESS | 1654 | |2;jquery.html;305.8852930151193;354.54618088846286;371.14080902710225;379.16205186537735;408.41596516980707;15244.695106097872;15352;16161.353545858181;20312.390628281137;20714;19921.203151873906;20029.699554506693;20704;20758.792412075876;21150;5693.089105743082;5959.970200148993;6212.565120441571;6227.501799856001;6229.065640153968;5748.223989761392;6120.755169793203;6175.04286835539;6188.443040126388;6204.224471941007;5852.180694702745;6069.78755743549;6161.1066395372645;6163.8150855474405;6175.66033868137;6090.299615544202;6135.815925522264;6140.8771824563255;6142.508599312045;6157.6305421674615;5068.771905264272;5224.52979231871;5289.86775330616;5291.947080529184;5331.253624492574;25431.364215894566;26024.479510409874;26123.346916327053;26397.868010660015;26669.333266668295;2236.7651396603446;2311.167979527369;2413.4328432818274;2414.0746789399345;2583.702874169457;12401.75196496074;15540.766888496568;15774.526764197093;16258;16309.59226019347;1668.6746084513525;1749.3877142999975;1838.5403649087727;1917.04147926037;1936.4965109071602;115359.42320288345;116749;117444;121421;122174.85178038059;9290.581923813445;9746.31775775689;9824.754381140387;9876.40741555509;9946.651867184613;12208.877911220952;12295.93852030752;12299.262044277371;12300.446479908425;12300.876991230152;7859.331956783737;8002.919970800334;8008.83982320362;8089.797755056053;8114.675412983414;8361.958190209132;8362.289205417486;8373.37199710012;8545.31637469001;8598.355123365773;3717.423799311111;3835.520559930009;3841.327767640652;3895.162540053902;3924.2151569686175;1613.9757903631455;1623.2776414495672;1876.8592355573387;1915.396650055228;1918.7697476302933;822.6462621072876;855.3755758296478;883.4699180491782;908.7410088124985;974.8099120671401;19168.329108481135;20097.497562561053;20627.79372206289;21581.78418215829;21796.801175935158;947.3842002698163;949.2880339745191;968.8837339519303;970.9417434953925;978.7553111722069;10246.077852993118;11244.04425623831;11337.659870203906;11366.09071274296;11379.431028448544;495879;498201;499031;532398;559529.2023539856;114972;115678;117672.23491647631;121020;125962.37018814847;928.1136514628514;963.3738070254391;978.6379039755336;980.1472718734603;980.26969907418;18196.363127290482;18936.810631893506;18994.52513687169;18999.905000474908;19026.52433689169;39184.412233817086;51062.74468627633;51729.96540069102;51887.91961406663;52611.21083183679;69605.65197173982;71289.28710712827;71922.28077719365;73201.9019714694;73577;6948.826279342855;7972.720954766555;8012.3590112792;8038.959805200937;8305.294050005818;10579.365238085431;12105;12870.613881583567;13170.47318107265;13414.865851341752;10884.455777211266;11016.779664406828;11037.503312350804;11423.314601123959;11432.14258930567;10245.538950747085;10765.838512422164;10789.892101079202;11018.614348497764;11470.827937580776;11324.32054076758;11371;11742.530298788295;11778.057755379721;11801.35092569917;7154.2130365658695;7345;7347.448941329315;7537.434692398202;7582.507137036075;10449.477526123816;11074.11407087415;11081.390523521284;11443.141764367872;11597.942010289895 19:37:04 INFO - PROCESS | 1654 | |3;mootools.html;1378.1111183286787;1529.8776097912169;1547.8142622885255;1752.526817759205;1908.8854668719878;7020.438364930806;8355.331573474123;8365.707200247993;8524.403291769562;8926;8716.477011379306;8721.34589905758;8745.08176641453;8781.165789250026;8865.113488651132;2364.420716924356;2393.7127544694663;2402.6876506054186;2402.8318017738766;2403.807695384374;2346.3078391874437;2358.304300231427;2391.4021494626345;2392.043182726906;2392.7487613800563;2354.21133920137;2374.6675465434855;2380.0955636858057;2388.7133543974746;2389.904403823845;2332.0671731307443;2349.447879748258;2357.5638506876285;2361.645753137026;2368.928932132039;1971.8126777956072;2341.8243631727687;2348.283773449097;2351.8000969917575;2355.4229213842546;7568.205338439439;8656.78358041054;8880.777980550538;8883.822323553557;8898.644054237822;5126.307948426952;5157.71632560209;5324.627276090675;5334.199870019489;5343.438938911398;2946.6611339695996;3465.8960231193105;3639.672429481333;3641.9817900910325;3647.8905632831056;1079.3362082319318;1080.206048554312;1084.0243780597445;1108.384846410242;1109.91120710343;11744.941275293655;11811.704707382385;11911.583094591648;11947.103967202407;11976.880231197663;4412.801423935931;4424.80088396023;4565.977170114128;4570.9943812361225;4599.6090332321455;9229.815403691888;9247.907520924839;9261.120193581599;9267.490288034089;9293.860592090989;4474.753888536162;4476.932846007313;5247.08176069194;5252.422233554306;5258.316418865523;4405.29515277554;4411.316245981878;4551.089782043605;4553.043860789205;4932.334134891816;3703.8333275002688;3711.814409279579;3791.109089363997;3800.6959443244473;3801.2777572261184;1134.6028889888473;1134.971625709364;1135.1543100390263;1146.5929594993825;1147.8794726553758;789.4316092413453;862.2584577263548;890.247740659142;898.4115404410122;900.1718419054486;5518.91721624176;5657.632253903483;5728.911506813692;5835.241418615553;5895.705214739246;876.369014309696;883.9481017589048;921.5392303848076;929.8977112517617;936.7939053408239;3588.3540962626976;3668.4313931340685;3875.050612599931;3912.4522566840096;3961.7622942623534;10648.89351106479;10882;10981.231313807957;10993.395363255098;10997.61508347204;10904.890951090547;10944.17918656104;10954.342739435659;10970.286931349438;10981.121510279318;7730.420218483524;8005.039395272488;8202.917970820336;8240.711575094843;8271.255586997198;4946.653734238569;5847.853803654943;5884.823455296348;5988.101784732325;6004.489618382487;9862.55618497155;10145;10181.33821301613;10204.13264872494;10208.2343824212;9493.810123797348;9686.225101991964;10182.949085254526;10459.738506537398;10473.9476302618;6758.898616520658;6940.514164008471;7198.71205151788;7317.853642927218;7612.80967975805;8276.0896301408;8282.296004839656;8283.25450709439;8370.414071014971;8375.790605234917;6476.773312934025;7497.850042999;7502.249775022454;7556.811079723051;7560.886586701314;7313.158986716596;7334.376577990931;7396.55620662762;7437.367823735043;7483.289087536676;6824.727010919508;6850.691718872563;6898.517103802887;6901.344372284625;6916.585004899722;4857.222844344888;4973.950260497349;4974.32846565709;4997.675151115166;5165.199394093921;4952.47998960104;4957.529034741694;5042.672226305279;5064.468230835857;5125.1799712045895 19:37:04 INFO - PROCESS | 1654 | |4;prototype.html;303.2539951718772;304.3608422313143;310.33278451329636;318.3172095854394;318.55402436588787;4762.3330035872405;5103.2855400244;5414.2420061191415;5493.505584497394;5520.779168833242;4644.280136578827;5056.898862022757;5113.6931784092885;5293.591415905763;5449;3438.707709844654;3562.9643703562956;4074.7351422157467;4142.440770495989;4142.751434913915;4102.487189101363;4108.116754897696;4114.506259248894;4121.361189015707;4124.505059392877;3070.585470961414;3584.7132229421713;3900.9077458311717;4086.2236175126777;4092.2224777292367;3584.73114516411;4075.714699971003;4088.3049881520215;4098.7950602469755;4111.280525907954;3507.2468112531797;3507.2985402919517;3515.859365625372;3523.013556204267;3528.5709287738505;4642.35007099003;5123.2315152727015;5310.787568497255;5314.441983591706;5333.35999680037;4608.953910460886;4653.139169253699;4692.6245900327895;4715.373196247289;4772.117158325721;4491.640668746525;4984.6261530385;5657.20799088128;5662.21640183341;5737.340205876336;3292.8024318540965;3579.94630080549;3823.8661646842506;3840.8463661453507;3871.690264778811;5007.4491805901325;5265.9736701317015;5658.547316214735;5967.880642387128;6040.546958978094;3711.7958512282084;3749.7000239980734;3911.6088391160656;3911.9608803911597;3924.2151569686175;3921.7254792164845;3969.8090572828037;3976.4631774710624;3981.8606348777653;3983;3619;4244.3209086546;4520.589238151233;4540.8204062049745;4664.953350466452;5746.098220398755;5747.563109222694;5762.971185144047;5768.625039372428;5775.768969241268;1253.7993920972601;1279.7120647854158;1327.9310155324863;1352.3508715816267;1357.850636429992;842.995785021071;865.4893612768496;907.2197909797569;908.2779190543533;911.3620465674055;5333.733313334318;5335.893282134336;5365.490278423544;5378.623496355296;5379.596530260168;5191.636585439059;5788.4820103255215;5800.506956908625;5833.7374818133285;5849.181114643953;5073.630119867689;5385.6230063895155;5432.972835135799;5433.293671822637;5456.890862182813;2505.5865782145743;3048.969510304913;3068.938621227563;3076.7538596912195;3081.291303000278;5435.728213589305;5846.766129354864;6041.426064523863;6066.636001839904;6099.96950015256;6035.094735789667;6041.516678665696;6051.0923361496125;6085.482733967574;6137.60105593135;5527;5769.423057694197;5971.566823962218;5991.610545314454;6108.75564977396;5165.004297507512;5601.403599973988;5922.822315330547;5925.555583331181;6128.264608247039;4906.607471402351;5373.7850485980125;5865.94134058671;5892.823215303548;5963.373845746308;5414.565140237919;5618;5853.2976042875125;6177.62934223948;6194.491231051431;3428.6057103432427;3854.0344424343225;3865.8646947356706;3868.5357757068205;3880.9805950971195;5944.286685597756;5954.350314110179;6000.8799824004145;6000.939990600213;6016.548758843017;5566.248556444828;5716.714164291852;5737;5741.74162162712;5745.195672605755;5281.075811732854;5358.651687640291;5700.344460386943;5758.827235182951;5761.913571296518;4506.7070640408265;5418.751218244384;5590.440955904377;5669.943300566942;5686.658800471985;5434.10337294342;5634.971825140848;5689.34572524165;5709.771609135588;5762.164486149569;5310.6813591184655;5597.468240517144;5603.775848966159;5632.1551767233605;5634.535020894515 19:37:04 INFO - PROCESS | 1654 | |5;yui.html;739.9223081576434;783.5142211828667;791.3629528229775;811.2225717734112;833.474910806192;14872.47946321879;15646.84353156468;15676.216189190553;15708.136052517108;15709.214539273047;14109.365078571464;14137.646558836033;14147;14154.71690566188;14169.71660566788;676.1446769836161;698.5843423163235;706.4948561778327;828.9544075075869;857.1428571428571;847.4068152293388;851.4167795060373;852.9445586036887;855.2003876375578;857.8027053777635;910.1626503616656;910.5856835140029;910.8178364327161;911.1935936696005;911.5806728904712;724.9166345870187;743.4684200796403;882.5278476015366;907;907.2968449451695;672.3310306245318;672.7779832655261;673.1652750589283;675.1425689374466;677;14788.926055369655;14877.702445951021;14891.180985045818;14897.329620167118;14924.62688432787;13688.65778355539;13778.65553361164;13851.722965540734;13853.16880987144;14197.645058873508;12707.682307942283;12770.297633630147;12835.679108022281;12951.935240323832;13041.673958151026;4490.528494508062;4495.57292057254;4534.637099584755;4602.677812553123;4615.423072115986;109740;110549;120911.79088209094;122922;124300;10792.352458852494;10810.567577296977;10928.36767668713;11054.628338356717;11135.33188008722;21433.78566214318;21500.032498537603;21503;21576.244831430824;21579.892100539397;7870.291673749275;7915.802104947423;8024;8096.8197458183195;8111.310538604167;4391.3412988051405;4436.6007059364165;4479.865604031885;4582.266837306015;4610.976945115253;3173;3259.6903294187014;3259.9185020374207;3263.9881636692226;3264.5103234514536;608.285264813842;610.6763415389851;624.8631868022502;633.3729607688356;635.4566845347254;593.2080672302525;597.4324391827694;607.6566739792004;616.9604216894547;622.9781957631461;8758.467268228034;8854.114588541095;9109.954450227839;9338.571971287514;10080.991900810008;621.2762132116125;627.8399008252958;631.6810010944444;671.2951401028901;679.8606285711418;4615.215413379734;4622.537746225351;4641.280601506772;4655.790489427984;4699.318598803223;116010.83989160169;131769;136887;146358.26820866042;147576.26211868873;91895;109760.41437513774;131410;132301.33849330692;135337;104390;105448.47275763573;108296.45851770848;109964;109994.45002774935;7665.233476652514;8012.559309238048;9660.516974151406;10011.599536018477;10019.849702254618;55435.722821385636;58266.7086664564;58292.4170758287;61312;61465.770684586954;75053.24946750682;82071.5896420514;84131;88908.5554572223;95172;13082.607521774362;13311.66720831987;13402;13782.93108534451;13870;14290.64273393132;14420.567382978528;14952.551423457313;15129.924350378178;15548.067115973077;13625.523106691218;13962.650933726738;14087.929560352133;14385.424583016562;14469.421223151377;11457.369844658624;11861.466234019317;14524.27378631043;14793.408263669333;14818.206704327959;13252.668683282609;13642.658933526742;13892.444302228201;13996;14103.929480352532;9314.394564353295;10392.804827444941;11021.559137634405;11222;11676.299422034705;10916.454177290945;11136.828853655004;11341.773164536828;11592.246503977214;11641 19:37:04 INFO - PROCESS | 1654 | __end_tp_report 19:37:04 INFO - PROCESS | 1654 | __start_cc_report 19:37:04 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1578 19:37:04 INFO - PROCESS | 1654 | __end_cc_report 19:37:04 INFO - PROCESS | 1654 | __startTimestamp1475116624979__endTimestamp 19:37:04 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | ------- Summary: start ------- 19:37:04 INFO - PROCESS | 1654 | Number of tests: 6 19:37:04 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5658.13 Median:5834.91 stddev:640.96 (11.0%) stddev-sans-first:615.27 19:37:04 INFO - PROCESS | 1654 | Values: 3283.9 3292.7 3294.7 3345.5 3346.7 5557.2 5586.1 5618.6 5629.2 5661.3 4675.6 5604.9 5636.3 5642.6 5661.7 5110.0 5140.3 5158.2 5181.5 5221.8 5172.2 5254.8 5257.0 5260.7 5274.9 5172.1 5196.7 5224.6 5238.4 5268.4 5225.3 5228.8 5241.1 5245.6 5277.5 5044.9 5071.4 5125.7 5178.9 5187.0 4826.4 5411.1 5569.9 5629.4 5640.2 6150.9 6151.1 6157.8 6161.3 6175.0 5996.8 6097.0 6183.8 6240.5 6269.3 5639.3 5691.1 5693.1 5711.3 5751.0 6275.6 6277.4 6278.8 6297.7 6312.0 5867.5 5968.4 6020.5 6042.6 6045.8 6093.4 6110.8 6127.3 6157.4 6234.3 6144.4 6151.5 6152.3 6161.5 6163.8 6168.2 6169.5 6183.3 6187.4 6200.6 4730.3 4823.8 4846.4 4853.0 4880.4 4339.7 4346.2 4370.3 4371.7 4405.6 5612.4 5663.7 5675.6 5676.7 5686.3 6064.1 6081.1 6136.5 6233.5 6238.3 5596.9 5636.4 5646.3 5653.1 5671.5 5308.4 5309.2 5316.2 5319.2 5325.0 6212.2 6237.1 6273.6 6282.1 6282.6 6210.8 6226.0 6228.2 6234.1 6243.5 6251.7 6255.7 6259.3 6278.4 6282.5 5916.9 5975.6 5978.3 6087.4 6143.4 4776.6 5583.9 6218.1 6234.7 6237.8 5356.8 5730.5 6039.2 6049.3 6053.0 5854.5 6033.8 6048.5 6051.9 6053.9 5765.5 5796.3 5809.3 5887.4 5891.4 6045.2 6048.4 6061.8 6094.4 6109.8 5834.9 5841.6 5885.4 5885.6 5900.5 19:37:04 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:67109.04 Median:35170.12 stddev:149030.49 (423.7%) stddev-sans-first:149364.69 19:37:04 INFO - PROCESS | 1654 | Values: 515.4 532.6 535.4 560.5 567.2 38792.6 41213.6 41362.8 41377.2 41430.6 42271.6 42689.0 42723.0 42802.0 42865.0 8728.5 8950.6 9582.3 9775.1 9781.3 11907.0 12198.0 12217.9 12341.5 12345.1 12049.4 12135.9 12136.5 12152.9 12174.9 8786.6 8980.8 11861.4 12022.7 12176.3 11920.6 11982.0 11982.9 11988.9 11999.8 32628.0 39684.8 39998.8 40043.2 40104.6 3291.6 3335.2 3406.1 3575.3 3930.9 30879.9 31269.8 31599.1 31927.8 32151.7 3180.4 3197.6 3217.9 3273.5 3317.3 493379.5 494481.5 495016.0 495107.0 495303.0 33243.3 34121.8 59104.0 59316.7 59434.4 35126.6 35213.6 35475.3 35502.9 35507.0 14536.6 14649.0 14664.6 14669.4 14680.4 11313.5 11469.9 12163.0 12374.6 12491.9 7848.9 7860.0 7890.1 7947.5 7948.8 3221.7 3260.8 3270.4 3283.6 3311.9 799.7 837.4 881.1 996.7 1051.9 25832.5 27381.7 28362.9 29765.8 31174.0 1014.5 1077.5 1097.0 1165.4 1217.7 13293.0 15036.5 15324.7 15366.6 15596.4 666495.0 671236.0 671937.0 673395.0 674073.0 57289.1 57619.0 57659.1 57677.7 57718.7 1229.3 1257.7 1334.7 1354.5 1359.7 29177.6 29879.7 30987.4 31238.5 31709.4 36814.0 48915.0 48936.5 49014.0 49043.8 480637.0 483170.0 483686.0 486397.0 487313.0 26448.1 27203.5 27317.9 28121.4 28272.4 44418.3 45176.9 45207.8 45417.9 45754.8 34806.0 39101.4 39255.6 39324.0 40291.6 37582.0 38335.0 38341.1 38762.4 39601.8 38097.6 38526.8 38827.0 38850.4 38857.8 27823.4 30435.4 30481.0 31456.9 35458.0 23590.8 24986.3 25207.7 25796.0 26043.9 19:37:04 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:32208.77 Median:330.22 stddev:86909.35 (26319.0%) stddev-sans-first:87120.14 19:37:04 INFO - PROCESS | 1654 | Values: 305.9 354.5 371.1 379.2 408.4 15244.7 15352.0 16161.4 20312.4 20714.0 19921.2 20029.7 20704.0 20758.8 21150.0 5693.1 5960.0 6212.6 6227.5 6229.1 5748.2 6120.8 6175.0 6188.4 6204.2 5852.2 6069.8 6161.1 6163.8 6175.7 6090.3 6135.8 6140.9 6142.5 6157.6 5068.8 5224.5 5289.9 5291.9 5331.3 25431.4 26024.5 26123.3 26397.9 26669.3 2236.8 2311.2 2413.4 2414.1 2583.7 12401.8 15540.8 15774.5 16258.0 16309.6 1668.7 1749.4 1838.5 1917.0 1936.5 115359.4 116749.0 117444.0 121421.0 122174.9 9290.6 9746.3 9824.8 9876.4 9946.7 12208.9 12295.9 12299.3 12300.4 12300.9 7859.3 8002.9 8008.8 8089.8 8114.7 8362.0 8362.3 8373.4 8545.3 8598.4 3717.4 3835.5 3841.3 3895.2 3924.2 1614.0 1623.3 1876.9 1915.4 1918.8 822.6 855.4 883.5 908.7 974.8 19168.3 20097.5 20627.8 21581.8 21796.8 947.4 949.3 968.9 970.9 978.8 10246.1 11244.0 11337.7 11366.1 11379.4 495879.0 498201.0 499031.0 532398.0 559529.2 114972.0 115678.0 117672.2 121020.0 125962.4 928.1 963.4 978.6 980.1 980.3 18196.4 18936.8 18994.5 18999.9 19026.5 39184.4 51062.7 51730.0 51887.9 52611.2 69605.7 71289.3 71922.3 73201.9 73577.0 6948.8 7972.7 8012.4 8039.0 8305.3 10579.4 12105.0 12870.6 13170.5 13414.9 10884.5 11016.8 11037.5 11423.3 11432.1 10245.5 10765.8 10789.9 11018.6 11470.8 11324.3 11371.0 11742.5 11778.1 11801.4 7154.2 7345.0 7347.4 7537.4 7582.5 10449.5 11074.1 11081.4 11443.1 11597.9 19:37:04 INFO - PROCESS | 1654 | 19:37:04 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5642.32 Median:4949.57 stddev:3210.41 (64.9%) stddev-sans-first:3203.42 19:37:04 INFO - PROCESS | 1654 | Values: 1378.1 1529.9 1547.8 1752.5 1908.9 7020.4 8355.3 8365.7 8524.4 8926.0 8716.5 8721.3 8745.1 8781.2 8865.1 2364.4 2393.7 2402.7 2402.8 2403.8 2346.3 2358.3 2391.4 2392.0 2392.7 2354.2 2374.7 2380.1 2388.7 2389.9 2332.1 2349.4 2357.6 2361.6 2368.9 1971.8 2341.8 2348.3 2351.8 2355.4 7568.2 8656.8 8880.8 8883.8 8898.6 5126.3 5157.7 5324.6 5334.2 5343.4 2946.7 3465.9 3639.7 3642.0 3647.9 1079.3 1080.2 1084.0 1108.4 1109.9 11744.9 11811.7 11911.6 11947.1 11976.9 4412.8 4424.8 4566.0 4571.0 4599.6 9229.8 9247.9 9261.1 9267.5 9293.9 4474.8 4476.9 5247.1 5252.4 5258.3 4405.3 4411.3 4551.1 4553.0 4932.3 3703.8 3711.8 3791.1 3800.7 3801.3 1134.6 1135.0 1135.2 1146.6 1147.9 789.4 862.3 890.2 898.4 900.2 5518.9 5657.6 5728.9 5835.2 5895.7 876.4 883.9 921.5 929.9 936.8 3588.4 3668.4 3875.1 3912.5 3961.8 10648.9 10882.0 10981.2 10993.4 10997.6 10904.9 10944.2 10954.3 10970.3 10981.1 7730.4 8005.0 8202.9 8240.7 8271.3 4946.7 5847.9 5884.8 5988.1 6004.5 9862.6 10145.0 10181.3 10204.1 10208.2 9493.8 9686.2 10182.9 10459.7 10473.9 6758.9 6940.5 7198.7 7317.9 7612.8 8276.1 8282.3 8283.3 8370.4 8375.8 6476.8 7497.9 7502.2 7556.8 7560.9 7313.2 7334.4 7396.6 7437.4 7483.3 6824.7 6850.7 6898.5 6901.3 6916.6 4857.2 4974.0 4974.3 4997.7 5165.2 4952.5 4957.5 5042.7 5064.5 5125.2 19:37:05 INFO - PROCESS | 1654 | 19:37:05 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4623.34 Median:5323.90 stddev:1452.20 (27.3%) stddev-sans-first:1419.61 19:37:05 INFO - PROCESS | 1654 | Values: 303.3 304.4 310.3 318.3 318.6 4762.3 5103.3 5414.2 5493.5 5520.8 4644.3 5056.9 5113.7 5293.6 5449.0 3438.7 3563.0 4074.7 4142.4 4142.8 4102.5 4108.1 4114.5 4121.4 4124.5 3070.6 3584.7 3900.9 4086.2 4092.2 3584.7 4075.7 4088.3 4098.8 4111.3 3507.2 3507.3 3515.9 3523.0 3528.6 4642.4 5123.2 5310.8 5314.4 5333.4 4609.0 4653.1 4692.6 4715.4 4772.1 4491.6 4984.6 5657.2 5662.2 5737.3 3292.8 3579.9 3823.9 3840.8 3871.7 5007.4 5266.0 5658.5 5967.9 6040.5 3711.8 3749.7 3911.6 3912.0 3924.2 3921.7 3969.8 3976.5 3981.9 3983.0 3619.0 4244.3 4520.6 4540.8 4665.0 5746.1 5747.6 5763.0 5768.6 5775.8 1253.8 1279.7 1327.9 1352.4 1357.9 843.0 865.5 907.2 908.3 911.4 5333.7 5335.9 5365.5 5378.6 5379.6 5191.6 5788.5 5800.5 5833.7 5849.2 5073.6 5385.6 5433.0 5433.3 5456.9 2505.6 3049.0 3068.9 3076.8 3081.3 5435.7 5846.8 6041.4 6066.6 6100.0 6035.1 6041.5 6051.1 6085.5 6137.6 5527.0 5769.4 5971.6 5991.6 6108.8 5165.0 5601.4 5922.8 5925.6 6128.3 4906.6 5373.8 5865.9 5892.8 5963.4 5414.6 5618.0 5853.3 6177.6 6194.5 3428.6 3854.0 3865.9 3868.5 3881.0 5944.3 5954.4 6000.9 6000.9 6016.5 5566.2 5716.7 5737.0 5741.7 5745.2 5281.1 5358.7 5700.3 5758.8 5761.9 4506.7 5418.8 5590.4 5669.9 5686.7 5434.1 5635.0 5689.3 5709.8 5762.2 5310.7 5597.5 5603.8 5632.2 5634.5 19:37:05 INFO - PROCESS | 1654 | 19:37:05 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24184.68 Median:12376.45 stddev:37959.40 (306.7%) stddev-sans-first:38025.07 19:37:05 INFO - PROCESS | 1654 | Values: 739.9 783.5 791.4 811.2 833.5 14872.5 15646.8 15676.2 15708.1 15709.2 14109.4 14137.6 14147.0 14154.7 14169.7 676.1 698.6 706.5 829.0 857.1 847.4 851.4 852.9 855.2 857.8 910.2 910.6 910.8 911.2 911.6 724.9 743.5 882.5 907.0 907.3 672.3 672.8 673.2 675.1 677.0 14788.9 14877.7 14891.2 14897.3 14924.6 13688.7 13778.7 13851.7 13853.2 14197.6 12707.7 12770.3 12835.7 12951.9 13041.7 4490.5 4495.6 4534.6 4602.7 4615.4 109740.0 110549.0 120911.8 122922.0 124300.0 10792.4 10810.6 10928.4 11054.6 11135.3 21433.8 21500.0 21503.0 21576.2 21579.9 7870.3 7915.8 8024.0 8096.8 8111.3 4391.3 4436.6 4479.9 4582.3 4611.0 3173.0 3259.7 3259.9 3264.0 3264.5 608.3 610.7 624.9 633.4 635.5 593.2 597.4 607.7 617.0 623.0 8758.5 8854.1 9110.0 9338.6 10081.0 621.3 627.8 631.7 671.3 679.9 4615.2 4622.5 4641.3 4655.8 4699.3 116010.8 131769.0 136887.0 146358.3 147576.3 91895.0 109760.4 131410.0 132301.3 135337.0 104390.0 105448.5 108296.5 109964.0 109994.5 7665.2 8012.6 9660.5 10011.6 10019.8 55435.7 58266.7 58292.4 61312.0 61465.8 75053.2 82071.6 84131.0 88908.6 95172.0 13082.6 13311.7 13402.0 13782.9 13870.0 14290.6 14420.6 14952.6 15129.9 15548.1 13625.5 13962.7 14087.9 14385.4 14469.4 11457.4 11861.5 14524.3 14793.4 14818.2 13252.7 13642.7 13892.4 13996.0 14103.9 9314.4 10392.8 11021.6 11222.0 11676.3 10916.5 11136.8 11341.8 11592.2 11641.0 19:37:05 INFO - PROCESS | 1654 | -------- Summary: end -------- 19:37:05 INFO - PROCESS | 1654 | 19:37:05 INFO - TEST-INFO | 1654: exit 0 19:37:05 INFO - TEST-OK | dromaeo_css | took 1117207ms 19:37:05 INFO - TEST-START | kraken 19:37:05 INFO - operating with platform_type : mac_ 19:37:05 INFO - Initialising browser for kraken test... 19:37:05 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp5EDO8n/profile http://localhost:49267/getInfo.html) 19:37:07 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 19:37:07 INFO - PROCESS | 1661 | colorDepth:24 19:37:07 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 19:37:07 INFO - PROCESS | 1661 | __metrics 19:37:07 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:37:07 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:37:08 INFO - TEST-INFO | 1661: exit 0 19:37:08 INFO - Browser initialized. 19:37:08 INFO - Running cycle 1/1 for kraken test... 19:37:08 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp5EDO8n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:37:20 INFO - PROCESS | 1662 | RSS: Main: 236179456 19:37:20 INFO - PROCESS | 1662 | 19:37:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 19:37:24 INFO - PROCESS | 1662 | RSS: Main: 294907904 19:37:24 INFO - PROCESS | 1662 | 19:37:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 19:37:27 INFO - PROCESS | 1662 | RSS: Main: 293769216 19:37:27 INFO - PROCESS | 1662 | 19:37:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 19:37:31 INFO - PROCESS | 1662 | RSS: Main: 385433600 19:37:31 INFO - PROCESS | 1662 | 19:37:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 19:37:34 INFO - PROCESS | 1662 | RSS: Main: 285114368 19:37:34 INFO - PROCESS | 1662 | 19:37:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:37:36 INFO - PROCESS | 1662 | RSS: Main: 289099776 19:37:36 INFO - PROCESS | 1662 | 19:37:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 19:37:40 INFO - PROCESS | 1662 | RSS: Main: 327270400 19:37:40 INFO - PROCESS | 1662 | 19:37:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 19:37:43 INFO - PROCESS | 1662 | RSS: Main: 328204288 19:37:43 INFO - PROCESS | 1662 | 19:37:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 19:37:47 INFO - PROCESS | 1662 | RSS: Main: 326778880 19:37:47 INFO - PROCESS | 1662 | 19:37:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:37:49 INFO - PROCESS | 1662 | RSS: Main: 300834816 19:37:49 INFO - PROCESS | 1662 | 19:37:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:37:51 INFO - PROCESS | 1662 | RSS: Main: 304373760 19:37:51 INFO - PROCESS | 1662 | 19:37:54 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:37:54 INFO - PROCESS | 1662 | RSS: Main: 305242112 19:37:54 INFO - PROCESS | 1662 | 19:37:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:37:58 INFO - PROCESS | 1662 | RSS: Main: 315359232 19:37:58 INFO - PROCESS | 1662 | 19:38:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:38:01 INFO - PROCESS | 1662 | RSS: Main: 316751872 19:38:01 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 19:38:03 INFO - PROCESS | 1662 | RSS: Main: 360341504 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | __start_tp_report 19:38:03 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 19:38:03 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 19:38:03 INFO - PROCESS | 1662 | |i|pagename|runs| 19:38:03 INFO - PROCESS | 1662 | |0;ai-astar;114;113;113;113;114;113;113;114;113;114 19:38:03 INFO - PROCESS | 1662 | |1;audio-beat-detection;146;111;110;159;110;110;113;108;139;108 19:38:03 INFO - PROCESS | 1662 | |2;audio-dft;185;181;181;185;185;183;201;267;186;182 19:38:03 INFO - PROCESS | 1662 | |3;audio-fft;78;58;58;87;61;58;58;59;82;58 19:38:03 INFO - PROCESS | 1662 | |4;audio-oscillator;89;71;91;71;67;69;92;67;68;90 19:38:03 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;123;86;85;87;84;87;84;87;84 19:38:03 INFO - PROCESS | 1662 | |6;imaging-darkroom;122;164;169;96;97;96;96;97;95;97 19:38:03 INFO - PROCESS | 1662 | |7;imaging-desaturate;85;83;86;84;83;85;84;85;84;84 19:38:03 INFO - PROCESS | 1662 | |8;json-parse-financial;59;56;55;56;53;55;54;54;55;57 19:38:03 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;71;73;75;73;71;70;71;71;73 19:38:03 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;87;85;104;106;107;86;86;88;87;89 19:38:03 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;140;139;139;141;137;167;157;150;190;138 19:38:03 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;170;166;171;169;168;169;168;171;174;172 19:38:03 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;66;67;68;68;67;66;70;67;65 19:38:03 INFO - PROCESS | 1662 | __end_tp_report 19:38:03 INFO - PROCESS | 1662 | __start_cc_report 19:38:03 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1919 19:38:03 INFO - PROCESS | 1662 | __end_cc_report 19:38:03 INFO - PROCESS | 1662 | __startTimestamp1475116683894__endTimestamp 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | ------- Summary: start ------- 19:38:03 INFO - PROCESS | 1662 | Number of tests: 14 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 19:38:03 INFO - PROCESS | 1662 | Values: 114.0 113.0 113.0 113.0 114.0 113.0 113.0 114.0 113.0 114.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:112.00 stddev:19.02 (17.0%) stddev-sans-first:17.97 19:38:03 INFO - PROCESS | 1662 | Values: 146.0 111.0 110.0 159.0 110.0 110.0 113.0 108.0 139.0 108.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:193.60 Median:185.00 stddev:26.43 (14.3%) stddev-sans-first:27.85 19:38:03 INFO - PROCESS | 1662 | Values: 185.0 181.0 181.0 185.0 185.0 183.0 201.0 267.0 186.0 182.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:65.70 Median:60.00 stddev:11.71 (19.5%) stddev-sans-first:11.54 19:38:03 INFO - PROCESS | 1662 | Values: 78.0 58.0 58.0 87.0 61.0 58.0 58.0 59.0 82.0 58.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:80.00 stddev:11.30 (14.1%) stddev-sans-first:11.19 19:38:03 INFO - PROCESS | 1662 | Values: 89.0 71.0 91.0 71.0 67.0 69.0 92.0 67.0 68.0 90.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:86.50 stddev:11.85 (13.7%) stddev-sans-first:12.57 19:38:03 INFO - PROCESS | 1662 | Values: 89.0 123.0 86.0 85.0 87.0 84.0 87.0 84.0 87.0 84.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:96.00 stddev:29.40 (30.6%) stddev-sans-first:30.99 19:38:03 INFO - PROCESS | 1662 | Values: 122.0 164.0 169.0 96.0 97.0 96.0 96.0 97.0 95.0 97.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:0.95 (1.1%) stddev-sans-first:0.97 19:38:03 INFO - PROCESS | 1662 | Values: 85.0 83.0 86.0 84.0 83.0 85.0 84.0 85.0 84.0 84.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:1.71 (3.1%) stddev-sans-first:1.22 19:38:03 INFO - PROCESS | 1662 | Values: 59.0 56.0 55.0 56.0 53.0 55.0 54.0 54.0 55.0 57.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:2.67 (3.7%) stddev-sans-first:1.58 19:38:03 INFO - PROCESS | 1662 | Values: 65.0 71.0 73.0 75.0 73.0 71.0 70.0 71.0 71.0 73.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:86.50 stddev:9.18 (10.6%) stddev-sans-first:9.52 19:38:03 INFO - PROCESS | 1662 | Values: 87.0 85.0 104.0 106.0 107.0 86.0 86.0 88.0 87.0 89.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:145.50 stddev:17.24 (11.8%) stddev-sans-first:17.91 19:38:03 INFO - PROCESS | 1662 | Values: 140.0 139.0 139.0 141.0 137.0 167.0 157.0 150.0 190.0 138.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.50 stddev:2.30 (1.3%) stddev-sans-first:2.44 19:38:03 INFO - PROCESS | 1662 | Values: 170.0 166.0 171.0 169.0 168.0 169.0 168.0 171.0 174.0 172.0 19:38:03 INFO - PROCESS | 1662 | 19:38:03 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.37 (2.0%) stddev-sans-first:1.45 19:38:03 INFO - PROCESS | 1662 | Values: 67.0 66.0 67.0 68.0 68.0 67.0 66.0 70.0 67.0 65.0 19:38:03 INFO - PROCESS | 1662 | -------- Summary: end -------- 19:38:03 INFO - PROCESS | 1662 | 19:38:04 INFO - TEST-INFO | 1662: exit 0 19:38:04 INFO - TEST-OK | kraken | took 59146ms 19:38:04 INFO - SUITE-END | took 1176s 19:38:05 INFO - Completed test suite (00:19:36) 19:38:05 INFO - javascript benchmark 19:38:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.8686452541892, 3292.670732926706, 3294.736421086313, 3345.5483509726178, 3346.7322614190866, 5557.249771280876, 5586.050371436855, 5618.606697531174, 5629.211910332551, 5661.320641523023, 4675.555822196894, 5604.887902241952, 5636.323641163056, 5642.57680673949, 5661.66030038198, 5110.0, 5140.33175687162, 5158.174692049273, 5181.455947125554, 5221.817236396727, 5172.19830926207, 5254.81608143715, 5257.00116977773, 5260.736963151846, 5274.9472505275035, 5172.069027575035, 5196.68819870809, 5224.582033437335, 5238.423773384925, 5268.446813084628, 5225.346831646025, 5228.816991405321, 5241.056609810233, 5245.606579506552, 5277.498637629419, 5044.949550504484, 5071.41678706946, 5125.692458452505, 5178.948210517884, 5187.04039752647, 4826.396700412449, 5411.134218525017, 5569.888602227973, 5629.437056294379, 5640.210370548086, 6150.938490615082, 6151.138840562325, 6157.846053848645, 6161.260648722137, 6174.981128113855, 5996.820095397145, 6097.0, 6183.752649893999, 6240.531960102965, 6269.341719119472, 5639.323281206241, 5691.146328050742, 5693.0891057431445, 5711.343195532567, 5751.0, 6275.560710750204, 6277.403646653652, 6278.780242691484, 6297.6536290503545, 6311.96844015777, 5867.471927526543, 5968.432998865101, 6020.518358531307, 6042.637441753509, 6045.818625441244, 6093.413838573086, 6110.755569777248, 6127.250613123855, 6157.353477884758, 6234.251889773256, 6144.446999770043, 6151.477124444383, 6152.323244443108, 6161.476274516626, 6163.7842675506145, 6168.228971378578, 6169.475594574422, 6183.319834818167, 6187.381261873777, 6200.56596038273, 4730.337752714623, 4823.831165909176, 4846.36997190363, 4852.951470485321, 4880.365552478155, 4339.717918335298, 4346.21768081742, 4370.2570563004365, 4371.650267978554, 4405.60349568534, 5612.35457922336, 5663.745131469093, 5675.602707810413, 5676.659400435987, 5686.289213848269, 6064.090386441981, 6081.148639190517, 6136.509079273647, 6233.532485063638, 6238.313785483593, 5596.916046259309, 5636.408177141423, 5646.2942132233475, 5653.123765816305, 5671.461211184931, 5308.389535203424, 5309.177077552986, 5316.202569614511, 5319.2287118368395, 5324.9733751331, 6212.161358216583, 6237.064440333896, 6273.62358258506, 6282.120503129566, 6282.591631543935, 6210.813675589739, 6226.034964580497, 6228.22147231596, 6234.096056071935, 6243.469305108935, 6251.6561589113035, 6255.749770009331, 6259.3427690091885, 6278.372162783868, 6282.4974002080635, 5916.940830591639, 5975.55183361241, 5978.282606087298, 6087.421694938974, 6143.416375444325, 4776.617870570277, 5583.8883222336135, 6218.098375735583, 6234.6570938597, 6237.812865614039, 5356.7589458473685, 5730.512906402836, 6039.245094363205, 6049.274087109575, 6053.0, 5854.531637469077, 6033.849153771191, 6048.4858787003595, 6051.939480605138, 6053.939460605514, 5765.538756899522, 5796.304443466811, 5809.2738407699035, 5887.352391236875, 5891.440313170242, 6045.244344456943, 6048.37109638519, 6061.848453788691, 6094.421030002142, 6109.786157484645, 5834.912476312944, 5841.649501029951, 5885.352611212677, 5885.61743486672, 5900.527957763455], "value": 5617.423184006694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.3635260453341, 532.5766016017267, 535.4297672978275, 560.5263552298306, 567.1860879637723, 38792.61207387925, 41213.58786412135, 41362.793186034105, 41377.17245655085, 41430.58569414305, 42271.57728422715, 42689.0, 42723.0, 42802.0, 42865.0, 8728.51993140377, 8950.641974321019, 9582.329236953416, 9775.120239178472, 9781.266405019616, 11907.047436205126, 12198.024158067377, 12217.938910305436, 12341.5063397464, 12345.13584049117, 12049.397530123459, 12135.878641213607, 12136.45385957634, 12152.878471215263, 12174.878251217464, 8786.64853405863, 8980.820383592358, 11861.406929653569, 12022.69943251417, 12176.26942383451, 11920.642380728592, 11982.041436685131, 11982.880171198263, 11988.940055299754, 11999.820002699878, 32628.0, 39684.801575991936, 39998.80003599897, 40043.199136017116, 40104.59895401038, 3291.637919828841, 3335.182880194344, 3406.080358303269, 3575.3022538434852, 3930.8993481825137, 30879.91920282802, 31269.84365078183, 31599.052028439182, 31927.84036079828, 32151.678483215102, 3180.4116238495953, 3197.5843140391835, 3217.9034628961167, 3273.49260864564, 3317.270200555874, 493379.53310233576, 494481.52759235975, 495016.0, 495107.0, 495303.0, 33243.3351332972, 34121.82939085289, 59104.0, 59316.703416483506, 59434.40565594375, 35126.64873351285, 35213.59145634146, 35475.290494190485, 35502.93491195268, 35507.0, 14536.563903082924, 14649.0, 14664.633384165481, 14669.413223471154, 14680.41278348854, 11313.547458101748, 11469.942650286695, 12163.02695784335, 12374.566890158801, 12491.937540312421, 7848.940393046865, 7860.0, 7890.132085470594, 7947.5231486111015, 7948.801279968047, 3221.7261532769508, 3260.8206548639578, 3270.444024515838, 3283.6223834259367, 3311.867525298961, 799.7360870912702, 837.4431003382682, 881.1144799476613, 996.7408473796866, 1051.9474026298653, 25832.48335033289, 27381.72618273792, 28362.85818570894, 29765.809367625487, 31174.0, 1014.5079636376352, 1077.498962982217, 1096.9780604387868, 1165.4056431220138, 1217.72601164739, 13293.0, 15036.548903532912, 15324.693506129815, 15366.615834604001, 15596.440355964312, 666495.0, 671236.0, 671937.0, 673395.0, 674073.0, 57289.14066289009, 57619.0, 57659.13511297334, 57677.711611442515, 57718.7114064427, 1229.287013532149, 1257.7107265329025, 1334.7463981843418, 1354.512375544803, 1359.7484465373936, 29177.562336565392, 29879.701202987693, 30987.380252394374, 31238.531422028234, 31709.36581268408, 36814.0, 48915.0, 48936.510634893195, 49014.01971960612, 49043.75478122586, 480637.0, 483170.0, 483686.0, 486397.0, 487313.0, 26448.074317398798, 27203.455930880875, 27317.863410682818, 28121.43757124805, 28272.434551308448, 44418.33607471169, 45176.87057823581, 45207.773961129984, 45417.86455338643, 45754.771226144985, 34806.0, 39101.41347879727, 39255.60744392634, 39324.0, 40291.597084029956, 37582.0, 38335.0, 38341.08294585315, 38762.37945497943, 39601.80199098986, 38097.61902381052, 38526.80736596299, 38827.0, 38850.4172437408, 38857.80571097126, 27823.443531128858, 30435.39129217359, 30481.0, 31456.89900853459, 35458.0, 23590.764092358855, 24986.250412487654, 25207.74792252054, 25796.0, 26043.869780650977], "value": 18816.113437366035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.8852930151193, 354.54618088846286, 371.14080902710225, 379.16205186537735, 408.41596516980707, 15244.695106097872, 15352.0, 16161.353545858181, 20312.390628281137, 20714.0, 19921.203151873906, 20029.699554506693, 20704.0, 20758.792412075876, 21150.0, 5693.089105743082, 5959.970200148993, 6212.565120441571, 6227.501799856001, 6229.065640153968, 5748.223989761392, 6120.755169793203, 6175.04286835539, 6188.443040126388, 6204.224471941007, 5852.180694702745, 6069.78755743549, 6161.1066395372645, 6163.8150855474405, 6175.66033868137, 6090.299615544202, 6135.815925522264, 6140.8771824563255, 6142.508599312045, 6157.6305421674615, 5068.771905264272, 5224.52979231871, 5289.86775330616, 5291.947080529184, 5331.253624492574, 25431.364215894566, 26024.479510409874, 26123.346916327053, 26397.868010660015, 26669.333266668295, 2236.7651396603446, 2311.167979527369, 2413.4328432818274, 2414.0746789399345, 2583.702874169457, 12401.75196496074, 15540.766888496568, 15774.526764197093, 16258.0, 16309.59226019347, 1668.6746084513525, 1749.3877142999975, 1838.5403649087727, 1917.04147926037, 1936.4965109071602, 115359.42320288345, 116749.0, 117444.0, 121421.0, 122174.85178038059, 9290.581923813445, 9746.31775775689, 9824.754381140387, 9876.40741555509, 9946.651867184613, 12208.877911220952, 12295.93852030752, 12299.262044277371, 12300.446479908425, 12300.876991230152, 7859.331956783737, 8002.919970800334, 8008.83982320362, 8089.797755056053, 8114.675412983414, 8361.958190209132, 8362.289205417486, 8373.37199710012, 8545.31637469001, 8598.355123365773, 3717.423799311111, 3835.520559930009, 3841.327767640652, 3895.162540053902, 3924.2151569686175, 1613.9757903631455, 1623.2776414495672, 1876.8592355573387, 1915.396650055228, 1918.7697476302933, 822.6462621072876, 855.3755758296478, 883.4699180491782, 908.7410088124985, 974.8099120671401, 19168.329108481135, 20097.497562561053, 20627.79372206289, 21581.78418215829, 21796.801175935158, 947.3842002698163, 949.2880339745191, 968.8837339519303, 970.9417434953925, 978.7553111722069, 10246.077852993118, 11244.04425623831, 11337.659870203906, 11366.09071274296, 11379.431028448544, 495879.0, 498201.0, 499031.0, 532398.0, 559529.2023539856, 114972.0, 115678.0, 117672.23491647631, 121020.0, 125962.37018814847, 928.1136514628514, 963.3738070254391, 978.6379039755336, 980.1472718734603, 980.26969907418, 18196.363127290482, 18936.810631893506, 18994.52513687169, 18999.905000474908, 19026.52433689169, 39184.412233817086, 51062.74468627633, 51729.96540069102, 51887.91961406663, 52611.21083183679, 69605.65197173982, 71289.28710712827, 71922.28077719365, 73201.9019714694, 73577.0, 6948.826279342855, 7972.720954766555, 8012.3590112792, 8038.959805200937, 8305.294050005818, 10579.365238085431, 12105.0, 12870.613881583567, 13170.47318107265, 13414.865851341752, 10884.455777211266, 11016.779664406828, 11037.503312350804, 11423.314601123959, 11432.14258930567, 10245.538950747085, 10765.838512422164, 10789.892101079202, 11018.614348497764, 11470.827937580776, 11324.32054076758, 11371.0, 11742.530298788295, 11778.057755379721, 11801.35092569917, 7154.2130365658695, 7345.0, 7347.448941329315, 7537.434692398202, 7582.507137036075, 10449.477526123816, 11074.11407087415, 11081.390523521284, 11443.141764367872, 11597.942010289895], "value": 9370.608543618238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.1111183286787, 1529.8776097912169, 1547.8142622885255, 1752.526817759205, 1908.8854668719878, 7020.438364930806, 8355.331573474123, 8365.707200247993, 8524.403291769562, 8926.0, 8716.477011379306, 8721.34589905758, 8745.08176641453, 8781.165789250026, 8865.113488651132, 2364.420716924356, 2393.7127544694663, 2402.6876506054186, 2402.8318017738766, 2403.807695384374, 2346.3078391874437, 2358.304300231427, 2391.4021494626345, 2392.043182726906, 2392.7487613800563, 2354.21133920137, 2374.6675465434855, 2380.0955636858057, 2388.7133543974746, 2389.904403823845, 2332.0671731307443, 2349.447879748258, 2357.5638506876285, 2361.645753137026, 2368.928932132039, 1971.8126777956072, 2341.8243631727687, 2348.283773449097, 2351.8000969917575, 2355.4229213842546, 7568.205338439439, 8656.78358041054, 8880.777980550538, 8883.822323553557, 8898.644054237822, 5126.307948426952, 5157.71632560209, 5324.627276090675, 5334.199870019489, 5343.438938911398, 2946.6611339695996, 3465.8960231193105, 3639.672429481333, 3641.9817900910325, 3647.8905632831056, 1079.3362082319318, 1080.206048554312, 1084.0243780597445, 1108.384846410242, 1109.91120710343, 11744.941275293655, 11811.704707382385, 11911.583094591648, 11947.103967202407, 11976.880231197663, 4412.801423935931, 4424.80088396023, 4565.977170114128, 4570.9943812361225, 4599.6090332321455, 9229.815403691888, 9247.907520924839, 9261.120193581599, 9267.490288034089, 9293.860592090989, 4474.753888536162, 4476.932846007313, 5247.08176069194, 5252.422233554306, 5258.316418865523, 4405.29515277554, 4411.316245981878, 4551.089782043605, 4553.043860789205, 4932.334134891816, 3703.8333275002688, 3711.814409279579, 3791.109089363997, 3800.6959443244473, 3801.2777572261184, 1134.6028889888473, 1134.971625709364, 1135.1543100390263, 1146.5929594993825, 1147.8794726553758, 789.4316092413453, 862.2584577263548, 890.247740659142, 898.4115404410122, 900.1718419054486, 5518.91721624176, 5657.632253903483, 5728.911506813692, 5835.241418615553, 5895.705214739246, 876.369014309696, 883.9481017589048, 921.5392303848076, 929.8977112517617, 936.7939053408239, 3588.3540962626976, 3668.4313931340685, 3875.050612599931, 3912.4522566840096, 3961.7622942623534, 10648.89351106479, 10882.0, 10981.231313807957, 10993.395363255098, 10997.61508347204, 10904.890951090547, 10944.17918656104, 10954.342739435659, 10970.286931349438, 10981.121510279318, 7730.420218483524, 8005.039395272488, 8202.917970820336, 8240.711575094843, 8271.255586997198, 4946.653734238569, 5847.853803654943, 5884.823455296348, 5988.101784732325, 6004.489618382487, 9862.55618497155, 10145.0, 10181.33821301613, 10204.13264872494, 10208.2343824212, 9493.810123797348, 9686.225101991964, 10182.949085254526, 10459.738506537398, 10473.9476302618, 6758.898616520658, 6940.514164008471, 7198.71205151788, 7317.853642927218, 7612.80967975805, 8276.0896301408, 8282.296004839656, 8283.25450709439, 8370.414071014971, 8375.790605234917, 6476.773312934025, 7497.850042999, 7502.249775022454, 7556.811079723051, 7560.886586701314, 7313.158986716596, 7334.376577990931, 7396.55620662762, 7437.367823735043, 7483.289087536676, 6824.727010919508, 6850.691718872563, 6898.517103802887, 6901.344372284625, 6916.585004899722, 4857.222844344888, 4973.950260497349, 4974.32846565709, 4997.675151115166, 5165.199394093921, 4952.47998960104, 4957.529034741694, 5042.672226305279, 5064.468230835857, 5125.1799712045895], "value": 4517.689738353675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.2539951718772, 304.3608422313143, 310.33278451329636, 318.3172095854394, 318.55402436588787, 4762.3330035872405, 5103.2855400244, 5414.2420061191415, 5493.505584497394, 5520.779168833242, 4644.280136578827, 5056.898862022757, 5113.6931784092885, 5293.591415905763, 5449.0, 3438.707709844654, 3562.9643703562956, 4074.7351422157467, 4142.440770495989, 4142.751434913915, 4102.487189101363, 4108.116754897696, 4114.506259248894, 4121.361189015707, 4124.505059392877, 3070.585470961414, 3584.7132229421713, 3900.9077458311717, 4086.2236175126777, 4092.2224777292367, 3584.73114516411, 4075.714699971003, 4088.3049881520215, 4098.7950602469755, 4111.280525907954, 3507.2468112531797, 3507.2985402919517, 3515.859365625372, 3523.013556204267, 3528.5709287738505, 4642.35007099003, 5123.2315152727015, 5310.787568497255, 5314.441983591706, 5333.35999680037, 4608.953910460886, 4653.139169253699, 4692.6245900327895, 4715.373196247289, 4772.117158325721, 4491.640668746525, 4984.6261530385, 5657.20799088128, 5662.21640183341, 5737.340205876336, 3292.8024318540965, 3579.94630080549, 3823.8661646842506, 3840.8463661453507, 3871.690264778811, 5007.4491805901325, 5265.9736701317015, 5658.547316214735, 5967.880642387128, 6040.546958978094, 3711.7958512282084, 3749.7000239980734, 3911.6088391160656, 3911.9608803911597, 3924.2151569686175, 3921.7254792164845, 3969.8090572828037, 3976.4631774710624, 3981.8606348777653, 3983.0, 3619.0, 4244.3209086546, 4520.589238151233, 4540.8204062049745, 4664.953350466452, 5746.098220398755, 5747.563109222694, 5762.971185144047, 5768.625039372428, 5775.768969241268, 1253.7993920972601, 1279.7120647854158, 1327.9310155324863, 1352.3508715816267, 1357.850636429992, 842.995785021071, 865.4893612768496, 907.2197909797569, 908.2779190543533, 911.3620465674055, 5333.733313334318, 5335.893282134336, 5365.490278423544, 5378.623496355296, 5379.596530260168, 5191.636585439059, 5788.4820103255215, 5800.506956908625, 5833.7374818133285, 5849.181114643953, 5073.630119867689, 5385.6230063895155, 5432.972835135799, 5433.293671822637, 5456.890862182813, 2505.5865782145743, 3048.969510304913, 3068.938621227563, 3076.7538596912195, 3081.291303000278, 5435.728213589305, 5846.766129354864, 6041.426064523863, 6066.636001839904, 6099.96950015256, 6035.094735789667, 6041.516678665696, 6051.0923361496125, 6085.482733967574, 6137.60105593135, 5527.0, 5769.423057694197, 5971.566823962218, 5991.610545314454, 6108.75564977396, 5165.004297507512, 5601.403599973988, 5922.822315330547, 5925.555583331181, 6128.264608247039, 4906.607471402351, 5373.7850485980125, 5865.94134058671, 5892.823215303548, 5963.373845746308, 5414.565140237919, 5618.0, 5853.2976042875125, 6177.62934223948, 6194.491231051431, 3428.6057103432427, 3854.0344424343225, 3865.8646947356706, 3868.5357757068205, 3880.9805950971195, 5944.286685597756, 5954.350314110179, 6000.8799824004145, 6000.939990600213, 6016.548758843017, 5566.248556444828, 5716.714164291852, 5737.0, 5741.74162162712, 5745.195672605755, 5281.075811732854, 5358.651687640291, 5700.344460386943, 5758.827235182951, 5761.913571296518, 4506.7070640408265, 5418.751218244384, 5590.440955904377, 5669.943300566942, 5686.658800471985, 5434.10337294342, 5634.971825140848, 5689.34572524165, 5709.771609135588, 5762.164486149569, 5310.6813591184655, 5597.468240517144, 5603.775848966159, 5632.1551767233605, 5634.535020894515], "value": 4157.452005986291, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.9223081576434, 783.5142211828667, 791.3629528229775, 811.2225717734112, 833.474910806192, 14872.47946321879, 15646.84353156468, 15676.216189190553, 15708.136052517108, 15709.214539273047, 14109.365078571464, 14137.646558836033, 14147.0, 14154.71690566188, 14169.71660566788, 676.1446769836161, 698.5843423163235, 706.4948561778327, 828.9544075075869, 857.1428571428571, 847.4068152293388, 851.4167795060373, 852.9445586036887, 855.2003876375578, 857.8027053777635, 910.1626503616656, 910.5856835140029, 910.8178364327161, 911.1935936696005, 911.5806728904712, 724.9166345870187, 743.4684200796403, 882.5278476015366, 907.0, 907.2968449451695, 672.3310306245318, 672.7779832655261, 673.1652750589283, 675.1425689374466, 677.0, 14788.926055369655, 14877.702445951021, 14891.180985045818, 14897.329620167118, 14924.62688432787, 13688.65778355539, 13778.65553361164, 13851.722965540734, 13853.16880987144, 14197.645058873508, 12707.682307942283, 12770.297633630147, 12835.679108022281, 12951.935240323832, 13041.673958151026, 4490.528494508062, 4495.57292057254, 4534.637099584755, 4602.677812553123, 4615.423072115986, 109740.0, 110549.0, 120911.79088209094, 122922.0, 124300.0, 10792.352458852494, 10810.567577296977, 10928.36767668713, 11054.628338356717, 11135.33188008722, 21433.78566214318, 21500.032498537603, 21503.0, 21576.244831430824, 21579.892100539397, 7870.291673749275, 7915.802104947423, 8024.0, 8096.8197458183195, 8111.310538604167, 4391.3412988051405, 4436.6007059364165, 4479.865604031885, 4582.266837306015, 4610.976945115253, 3173.0, 3259.6903294187014, 3259.9185020374207, 3263.9881636692226, 3264.5103234514536, 608.285264813842, 610.6763415389851, 624.8631868022502, 633.3729607688356, 635.4566845347254, 593.2080672302525, 597.4324391827694, 607.6566739792004, 616.9604216894547, 622.9781957631461, 8758.467268228034, 8854.114588541095, 9109.954450227839, 9338.571971287514, 10080.991900810008, 621.2762132116125, 627.8399008252958, 631.6810010944444, 671.2951401028901, 679.8606285711418, 4615.215413379734, 4622.537746225351, 4641.280601506772, 4655.790489427984, 4699.318598803223, 116010.83989160169, 131769.0, 136887.0, 146358.26820866042, 147576.26211868873, 91895.0, 109760.41437513774, 131410.0, 132301.33849330692, 135337.0, 104390.0, 105448.47275763573, 108296.45851770848, 109964.0, 109994.45002774935, 7665.233476652514, 8012.559309238048, 9660.516974151406, 10011.599536018477, 10019.849702254618, 55435.722821385636, 58266.7086664564, 58292.4170758287, 61312.0, 61465.770684586954, 75053.24946750682, 82071.5896420514, 84131.0, 88908.5554572223, 95172.0, 13082.607521774362, 13311.66720831987, 13402.0, 13782.93108534451, 13870.0, 14290.64273393132, 14420.567382978528, 14952.551423457313, 15129.924350378178, 15548.067115973077, 13625.523106691218, 13962.650933726738, 14087.929560352133, 14385.424583016562, 14469.421223151377, 11457.369844658624, 11861.466234019317, 14524.27378631043, 14793.408263669333, 14818.206704327959, 13252.668683282609, 13642.658933526742, 13892.444302228201, 13996.0, 14103.929480352532, 9314.394564353295, 10392.804827444941, 11021.559137634405, 11222.0, 11676.299422034705, 10916.454177290945, 11136.828853655004, 11341.773164536828, 11592.246503977214, 11641.0], "value": 7783.251169380362, "unit": "score"}], "name": "dromaeo_css", "value": 7246.53700006402, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 110.0, 159.0, 110.0, 110.0, 113.0, 108.0, 139.0, 108.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 181.0, 185.0, 185.0, 183.0, 201.0, 267.0, 186.0, 182.0], "value": 193.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 58.0, 87.0, 61.0, 58.0, 58.0, 59.0, 82.0, 58.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 91.0, 71.0, 67.0, 69.0, 92.0, 67.0, 68.0, 90.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 123.0, 86.0, 85.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 164.0, 169.0, 96.0, 97.0, 96.0, 96.0, 97.0, 95.0, 97.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 84.0, 83.0, 85.0, 84.0, 85.0, 84.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 55.0, 56.0, 53.0, 55.0, 54.0, 54.0, 55.0, 57.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 73.0, 75.0, 73.0, 71.0, 70.0, 71.0, 71.0, 73.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 104.0, 106.0, 107.0, 86.0, 86.0, 88.0, 87.0, 89.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 141.0, 137.0, 167.0, 157.0, 150.0, 190.0, 138.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 171.0, 169.0, 168.0, 169.0, 168.0, 171.0, 174.0, 172.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 68.0, 68.0, 67.0, 66.0, 70.0, 67.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1464.2999999999997, "alertThreshold": 2.0}]} 19:38:05 INFO - Return code: 0 19:38:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:38:05 INFO - # TBPL SUCCESS # 19:38:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:38:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:38:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:38:05 INFO - 2016-09-28 19:38:05.219 screenresolution[1666:9752] starting screenresolution argv=screenresolution get 19:38:05 INFO - 2016-09-28 19:38:05.221 screenresolution[1666:9752] Display 0: 1600x1200x32@60 19:38:05 INFO - 2016-09-28 19:38:05.227 screenresolution[1667:9755] starting screenresolution argv=screenresolution list 19:38:05 INFO - Available Modes on Display 0 19:38:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:38:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:38:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:38:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:38:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:38:05 INFO - Intel Iris: 19:38:05 INFO - Chipset Model: Intel Iris 19:38:05 INFO - Type: GPU 19:38:05 INFO - Bus: Built-In 19:38:05 INFO - VRAM (Dynamic, Max): 1536 MB 19:38:05 INFO - Vendor: Intel (0x8086) 19:38:05 INFO - Device ID: 0x0a2e 19:38:05 INFO - Revision ID: 0x0009 19:38:05 INFO - Displays: 19:38:05 INFO - Display: 19:38:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:38:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:38:05 INFO - Display Serial Number: 1600x1200 60 19:38:05 INFO - Main Display: Yes 19:38:05 INFO - Mirror: Off 19:38:05 INFO - Online: Yes 19:38:05 INFO - Rotation: Supported 19:38:05 INFO - Return code: 0 19:38:05 INFO - Running post-action listener: _resource_record_post_action 19:38:05 INFO - [mozharness: 2016-09-29 02:38:05.464059Z] Finished run-tests step (success) 19:38:05 INFO - Running post-run listener: _resource_record_post_run 19:38:05 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37463040; Write bytes: 706331648; Read time: 242; Write time: 1936 19:38:05 INFO - TinderboxPrint: CPU usage
53.7% 19:38:05 INFO - TinderboxPrint: I/O read bytes / time
37,463,040 / 242 19:38:05 INFO - TinderboxPrint: I/O write bytes / time
706,331,648 / 1,936 19:38:05 INFO - TinderboxPrint: CPU idle
2,245.3 (46.3%) 19:38:05 INFO - TinderboxPrint: CPU system
971.1 (20.0%) 19:38:05 INFO - TinderboxPrint: CPU user
1,629.3 (33.6%) 19:38:05 INFO - TinderboxPrint: Swap in / out
444,796,928 / 0 19:38:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 407278080; Write bytes: 397572096; Read time: 29737; Write time: 785 19:38:05 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30067712; Write bytes: 293841920; Read time: 179; Write time: 1114 19:38:05 INFO - Running post-run listener: _upload_blobber_files 19:38:05 INFO - Blob upload gear active. 19:38:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:38:05 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 19:38:05 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:38:05 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:38:06 INFO - (blobuploader) - INFO - Open directory for files ... 19:38:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:38:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:08 INFO - (blobuploader) - INFO - Done attempting. 19:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:09 INFO - (blobuploader) - INFO - Done attempting. 19:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:38:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:12 INFO - (blobuploader) - INFO - Done attempting. 19:38:12 INFO - (blobuploader) - INFO - Iteration through files over. 19:38:12 INFO - Return code: 0 19:38:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:38:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:38:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/18daafe441ffd64e349dad03e78b65b073d106ca362a9db484bf432d39154427ef7123bab4b9572a803b8023a5d199bf49b97e47611f1f6e821cb71e3f7268ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78f38de5eb85e478db71e5e16a423520f76728bb12c96d9f8ff54bb4b94af61ab96a2979caf085366a7b124b519d0d3c39f609ed032c5441dfaea1f3f5326ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b13da8c2d702103cdccb2dc5d00647d4827b11d6e9b3e81873a780986d2fffc5985f0e8ebace47f420fa35a5b6e307ab4c04b6c31200f45db78be98494f11fa5"} 19:38:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:38:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:38:12 INFO - Contents: 19:38:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/18daafe441ffd64e349dad03e78b65b073d106ca362a9db484bf432d39154427ef7123bab4b9572a803b8023a5d199bf49b97e47611f1f6e821cb71e3f7268ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78f38de5eb85e478db71e5e16a423520f76728bb12c96d9f8ff54bb4b94af61ab96a2979caf085366a7b124b519d0d3c39f609ed032c5441dfaea1f3f5326ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b13da8c2d702103cdccb2dc5d00647d4827b11d6e9b3e81873a780986d2fffc5985f0e8ebace47f420fa35a5b6e307ab4c04b6c31200f45db78be98494f11fa5"} 19:38:12 INFO - Running post-run listener: copy_logs_to_upload_dir 19:38:12 INFO - Copying logs to upload dir... 19:38:12 INFO - mkdir: /builds/slave/test/build/upload/logs 19:38:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.154198 ========= master_lag: 0.42 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-28 19:38:12.567220) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 19:38:12.574356) ========= 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.jbfmmNAdb4/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.zHPNHdzngs/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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/Autoland/sha512/18daafe441ffd64e349dad03e78b65b073d106ca362a9db484bf432d39154427ef7123bab4b9572a803b8023a5d199bf49b97e47611f1f6e821cb71e3f7268ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78f38de5eb85e478db71e5e16a423520f76728bb12c96d9f8ff54bb4b94af61ab96a2979caf085366a7b124b519d0d3c39f609ed032c5441dfaea1f3f5326ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b13da8c2d702103cdccb2dc5d00647d4827b11d6e9b3e81873a780986d2fffc5985f0e8ebace47f420fa35a5b6e307ab4c04b6c31200f45db78be98494f11fa5"} build_url:https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/18daafe441ffd64e349dad03e78b65b073d106ca362a9db484bf432d39154427ef7123bab4b9572a803b8023a5d199bf49b97e47611f1f6e821cb71e3f7268ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f78f38de5eb85e478db71e5e16a423520f76728bb12c96d9f8ff54bb4b94af61ab96a2979caf085366a7b124b519d0d3c39f609ed032c5441dfaea1f3f5326ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b13da8c2d702103cdccb2dc5d00647d4827b11d6e9b3e81873a780986d2fffc5985f0e8ebace47f420fa35a5b6e307ab4c04b6c31200f45db78be98494f11fa5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 19:38:12.610174) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:38:12.610483) ========= 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.jbfmmNAdb4/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.zHPNHdzngs/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.005268 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:38:12.658681) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 19:38:12.662029) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 19:38:12.662345) ========= ========= Total master_lag: 0.56 =========