builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0175 starttime: 1476234462.82 results: success (0) buildid: 20161011160246 builduid: 588c79148a1d4087bb2f737b263a46b3 revision: daa573228c276fcabf806766e0ae4d736f86b089 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:42.822682) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:42.823128) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:42.824132) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:42.862881) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:42.863401) ========= 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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-10-11 18:07:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.5M=0.001s 2016-10-11 18:07:43 (16.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.452722 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:43.339114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:43.339470) ========= 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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.028158 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:43.395760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 18:07:43.396405) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev daa573228c276fcabf806766e0ae4d736f86b089 --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 daa573228c276fcabf806766e0ae4d736f86b089 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bUvxUxDdg4/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.5OAHeBq3j6/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-10-11 18:07:43,476 truncating revision to first 12 chars 2016-10-11 18:07:43,476 Setting DEBUG logging. 2016-10-11 18:07:43,476 attempt 1/10 2016-10-11 18:07:43,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/daa573228c27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 18:07:44,972 unpacking tar archive at: autoland-daa573228c27/testing/mozharness/ program finished with exit code 0 elapsedTime=1.766267 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 18:07:45.189353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:45.190143) ========= script_repo_revision: daa573228c276fcabf806766e0ae4d736f86b089 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:45.191143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:45.191788) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 18:07:45.275393) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-11 18:07:45.276063) ========= /tools/buildbot/bin/python -u 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', '-u', '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:07:45 INFO - MultiFileLogger online at 20161011 18:07:45 in /builds/slave/test 18:07:45 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 18:07:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:07:45 INFO - {'append_to_log': False, 18:07:45 INFO - 'base_work_dir': '/builds/slave/test', 18:07:45 INFO - 'blob_upload_branch': 'Autoland', 18:07:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:07:45 INFO - 'branch': 'Autoland', 18:07:45 INFO - 'buildbot_json_path': 'buildprops.json', 18:07:45 INFO - 'config_files': ('talos/mac_config.py',), 18:07:45 INFO - 'default_actions': ('clobber', 18:07:45 INFO - 'read-buildbot-config', 18:07:45 INFO - 'download-and-extract', 18:07:45 INFO - 'populate-webroot', 18:07:45 INFO - 'create-virtualenv', 18:07:45 INFO - 'install', 18:07:45 INFO - 'run-tests'), 18:07:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:07:45 INFO - 'download_minidump_stackwalk': True, 18:07:45 INFO - 'download_symbols': 'ondemand', 18:07:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:07:45 INFO - 'tooltool.py': '/tools/tooltool.py', 18:07:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:07:45 INFO - '/tools/misc-python/virtualenv.py')}, 18:07:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:07:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:07:45 INFO - 'installer_path': 'installer.exe', 18:07:45 INFO - 'log_level': 'info', 18:07:45 INFO - 'log_name': 'talos', 18:07:45 INFO - 'log_to_console': True, 18:07:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:07:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:07:45 INFO - 'opt_config_files': (), 18:07:45 INFO - 'pip_index': False, 18:07:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:07:45 INFO - 'cmd': ('bash', 18:07:45 INFO - '-c', 18:07:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:07:45 INFO - 'enabled': True, 18:07:45 INFO - 'halt_on_failure': False, 18:07:45 INFO - 'name': 'check_screen_resolution'},), 18:07:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:07:45 INFO - 'cmd': ('bash', 18:07:45 INFO - '-c', 18:07:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:07:45 INFO - 'enabled': True, 18:07:45 INFO - 'halt_on_failure': False, 18:07:45 INFO - 'name': 'check_screen_resolution'},), 18:07:45 INFO - 'run_cmd_checks_enabled': True, 18:07:45 INFO - 'sps_profile': False, 18:07:45 INFO - 'sps_profile_interval': 0, 18:07:45 INFO - 'suite': 'dromaeojs', 18:07:45 INFO - 'system_bits': '32', 18:07:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:07:45 INFO - 'title': 't-yosemite-r7-0175', 18:07:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:07:45 INFO - 'use_talos_json': True, 18:07:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:07:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:07:45 INFO - 'work_dir': 'build'} 18:07:45 INFO - [mozharness: 2016-10-12 01:07:45.514485Z] Running clobber step. 18:07:45 INFO - Running pre-action listener: _resource_record_pre_action 18:07:45 INFO - Running main action method: clobber 18:07:45 INFO - rmtree: /builds/slave/test/build 18:07:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:07:47 INFO - Running post-action listener: _resource_record_post_action 18:07:47 INFO - [mozharness: 2016-10-12 01:07:47.772478Z] Finished clobber step (success) 18:07:47 INFO - [mozharness: 2016-10-12 01:07:47.772666Z] Running read-buildbot-config step. 18:07:47 INFO - Running pre-action listener: _resource_record_pre_action 18:07:47 INFO - Running main action method: read_buildbot_config 18:07:47 INFO - Using buildbot properties: 18:07:47 INFO - { 18:07:47 INFO - "project": "", 18:07:47 INFO - "product": "firefox", 18:07:47 INFO - "script_repo_revision": "production", 18:07:47 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 18:07:47 INFO - "repository": "", 18:07:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 18:07:47 INFO - "buildid": "20161011160246", 18:07:47 INFO - "pgo_build": "False", 18:07:47 INFO - "basedir": "/builds/slave/test", 18:07:47 INFO - "buildnumber": 351, 18:07:47 INFO - "slavename": "t-yosemite-r7-0175", 18:07:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:07:47 INFO - "platform": "yosemite_r7", 18:07:47 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:07:47 INFO - "branch": "autoland", 18:07:47 INFO - "repo_path": "integration/autoland", 18:07:47 INFO - "revision": "daa573228c276fcabf806766e0ae4d736f86b089", 18:07:47 INFO - "stage_platform": "macosx64", 18:07:47 INFO - "builduid": "588c79148a1d4087bb2f737b263a46b3", 18:07:47 INFO - "slavebuilddir": "test" 18:07:47 INFO - } 18:07:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 18:07:47 INFO - Running post-action listener: _resource_record_post_action 18:07:47 INFO - [mozharness: 2016-10-12 01:07:47.775994Z] Finished read-buildbot-config step (success) 18:07:47 INFO - [mozharness: 2016-10-12 01:07:47.776123Z] Running download-and-extract step. 18:07:47 INFO - Running pre-action listener: _resource_record_pre_action 18:07:47 INFO - Running main action method: download_and_extract 18:07:47 INFO - mkdir: /builds/slave/test/build/tests 18:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:47 INFO - trying https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 18:07:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/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 18:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/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 18:07:49 INFO - Downloaded 1199 bytes. 18:07:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:07:49 INFO - Using the following test package requirements: 18:07:49 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:07:49 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 18:07:49 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 18:07:49 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'jsshell-mac.zip'], 18:07:49 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 18:07:49 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:07:49 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 18:07:49 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 18:07:49 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 18:07:49 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:07:49 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 18:07:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:07:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:07:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:07:49 INFO - Fetch https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:07:49 INFO - Http code: 200 18:07:49 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 18:07:49 INFO - x-amz-cf-id: t6YtQJHmjX1kDgqELi_n_aJdwuv247GxMMA3Vzna9aUtH9VOdjcNIQ== 18:07:49 INFO - x-amz-version-id: 9o81EW1rGXHhphxE0pbIy4wyMM4V_ylJ 18:07:49 INFO - Expected file size: 16508519 18:07:49 INFO - Obtained file size: 16508519 18:07:56 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 18:07:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:07:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:07:56 INFO - Fetch https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:07:57 INFO - Http code: 200 18:07:57 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 18:07:57 INFO - x-amz-cf-id: Uh6rmRtk0yZ9Mn9N5jBAdNUOOO7u4v9nkiNhjyW227gyFqCAFO083A== 18:07:57 INFO - x-amz-version-id: 9o81EW1rGXHhphxE0pbIy4wyMM4V_ylJ 18:07:57 INFO - Expected file size: 16508519 18:07:57 INFO - Obtained file size: 16508519 18:08:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 18:08:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:08:04 INFO - Fetch https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 18:08:04 INFO - Http code: 200 18:08:04 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 18:08:04 INFO - x-amz-cf-id: Lqblh8WVTfLd9yx9ViKQ3uGN3IlS8V4WeycEftpuUDGGh6XIutMWxQ== 18:08:04 INFO - x-amz-version-id: SVycnXhRH484Z2rewSHc8V9s_mYCKsxV 18:08:05 INFO - Expected file size: 14409006 18:08:05 INFO - Obtained file size: 14409006 18:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:05 INFO - trying https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:08:05 INFO - Downloading https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 18:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/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 18:08:09 INFO - Downloaded 111706362 bytes. 18:08:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:08:09 INFO - mkdir: /builds/slave/test/properties 18:08:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:08:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:08:09 INFO - Contents: 18:08:09 INFO - build_url:https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:08:10 INFO - Running post-action listener: _resource_record_post_action 18:08:10 INFO - Running post-action listener: set_extra_try_arguments 18:08:10 INFO - [mozharness: 2016-10-12 01:08:10.858572Z] Finished download-and-extract step (success) 18:08:10 INFO - [mozharness: 2016-10-12 01:08:10.858731Z] Running populate-webroot step. 18:08:10 INFO - Running pre-action listener: _resource_record_pre_action 18:08:10 INFO - Running main action method: populate_webroot 18:08:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:08:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:08:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:08:10 INFO - '--tppagecycles', 18:08:10 INFO - '7'], 18:08:10 INFO - 'tests': ['tsvgm']}}, 18:08:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:08:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:08:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:08:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:08:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['damp', 'tps']}, 18:08:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'tests': ['damp', 'tps']}, 18:08:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['dromaeo_dom']}, 18:08:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:08:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['basic_compositor_video']}, 18:08:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:08:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['a11yr', 18:08:10 INFO - 'ts_paint', 18:08:10 INFO - 'tpaint', 18:08:10 INFO - 'sessionrestore', 18:08:10 INFO - 'sessionrestore_no_auto_restore', 18:08:10 INFO - 'tabpaint']}, 18:08:10 INFO - 'other-e10s': {'tests': ['a11yr', 18:08:10 INFO - 'ts_paint', 18:08:10 INFO - 'tpaint', 18:08:10 INFO - 'sessionrestore', 18:08:10 INFO - 'sessionrestore_no_auto_restore', 18:08:10 INFO - 'tabpaint']}, 18:08:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:08:10 INFO - 'ts_paint', 18:08:10 INFO - 'tpaint', 18:08:10 INFO - 'sessionrestore', 18:08:10 INFO - 'sessionrestore_no_auto_restore', 18:08:10 INFO - 'tabpaint']}, 18:08:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:08:10 INFO - 'ts_paint', 18:08:10 INFO - 'tpaint', 18:08:10 INFO - 'sessionrestore', 18:08:10 INFO - 'sessionrestore_no_auto_restore', 18:08:10 INFO - 'tabpaint']}, 18:08:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['a11yr', 18:08:10 INFO - 'ts_paint', 18:08:10 INFO - 'tpaint', 18:08:10 INFO - 'sessionrestore', 18:08:10 INFO - 'sessionrestore_no_auto_restore', 18:08:10 INFO - 'tabpaint']}, 18:08:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['a11yr', 18:08:10 INFO - 'ts_paint', 18:08:10 INFO - 'tpaint', 18:08:10 INFO - 'sessionrestore', 18:08:10 INFO - 'sessionrestore_no_auto_restore', 18:08:10 INFO - 'tabpaint']}, 18:08:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['tsvgx', 18:08:10 INFO - 'tsvgr_opacity', 18:08:10 INFO - 'tart', 18:08:10 INFO - 'tscrollx', 18:08:10 INFO - 'cart']}, 18:08:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:08:10 INFO - 'tsvgr_opacity', 18:08:10 INFO - 'tart', 18:08:10 INFO - 'tscrollx', 18:08:10 INFO - 'cart']}, 18:08:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'talos_options': ['--disable-e10s'], 18:08:10 INFO - 'tests': ['tp5o']}, 18:08:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'tests': ['tp5o']}, 18:08:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'talos_options': ['--disable-e10s', 18:08:10 INFO - '--xperf_path', 18:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:10 INFO - 'tests': ['tp5n']}, 18:08:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:10 INFO - 'talos_options': ['--xperf_path', 18:08:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:10 INFO - 'tests': ['tp5n']}}, 18:08:10 INFO - 'talos.zip': {'path': '', 18:08:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:08:10 INFO - Running post-action listener: _resource_record_post_action 18:08:10 INFO - [mozharness: 2016-10-12 01:08:10.877809Z] Finished populate-webroot step (success) 18:08:10 INFO - [mozharness: 2016-10-12 01:08:10.877917Z] Running create-virtualenv step. 18:08:10 INFO - Running pre-action listener: _resource_record_pre_action 18:08:10 INFO - Running main action method: create_virtualenv 18:08:10 INFO - Creating virtualenv /builds/slave/test/build/venv 18:08:10 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 18:08:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:08:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:08:11 INFO - Using real prefix '/tools/python27' 18:08:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:08:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:08:14 INFO - Installing pip.................done. 18:08:14 INFO - Return code: 0 18:08:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:08:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:08:15 INFO - Reading from file tmpfile_stdout 18:08:15 INFO - Output received: 18:08:15 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) 18:08:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:08:15 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')]} 18:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:15 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')]} 18:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:15 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:15 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 18:08:15 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 18:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:15 INFO - 'HOME': '/Users/cltbld', 18:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:15 INFO - 'LOGNAME': 'cltbld', 18:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:15 INFO - 'MOZ_NO_REMOTE': '1', 18:08:15 INFO - 'NO_EM_RESTART': '1', 18:08:15 INFO - 'PAGER': '/bin/cat', 18:08:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:15 INFO - 'PWD': '/builds/slave/test', 18:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:15 INFO - 'SHELL': '/bin/bash', 18:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:15 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:15 INFO - 'USER': 'cltbld', 18:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:15 INFO - 'XPC_FLAGS': '0x0', 18:08:15 INFO - 'XPC_SERVICE_NAME': '0', 18:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:16 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 18:08:16 INFO - Cleaning up... 18:08:16 INFO - Return code: 0 18:08:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:16 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:16 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 18:08:16 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 18:08:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:16 INFO - 'HOME': '/Users/cltbld', 18:08:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:16 INFO - 'LOGNAME': 'cltbld', 18:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:16 INFO - 'MOZ_NO_REMOTE': '1', 18:08:16 INFO - 'NO_EM_RESTART': '1', 18:08:16 INFO - 'PAGER': '/bin/cat', 18:08:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:16 INFO - 'PWD': '/builds/slave/test', 18:08:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:16 INFO - 'SHELL': '/bin/bash', 18:08:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:16 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:16 INFO - 'USER': 'cltbld', 18:08:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:16 INFO - 'XPC_FLAGS': '0x0', 18:08:16 INFO - 'XPC_SERVICE_NAME': '0', 18:08:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:16 INFO - Downloading/unpacking psutil>=3.1.1 18:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:08:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:20 INFO - Installing collected packages: psutil 18:08:20 INFO - Running setup.py install for psutil 18:08:20 INFO - building 'psutil._psutil_osx' extension 18:08:20 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 18:08:21 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 18:08:21 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 18:08:21 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 18:08:21 INFO - building 'psutil._psutil_posix' extension 18:08:21 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 18:08:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:08:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:08:21 INFO - ^ 18:08:21 INFO - 1 warning generated. 18:08:21 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 18:08:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:21 INFO - Successfully installed psutil 18:08:21 INFO - Cleaning up... 18:08:21 INFO - Return code: 0 18:08:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:21 INFO - 'HOME': '/Users/cltbld', 18:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:21 INFO - 'LOGNAME': 'cltbld', 18:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:21 INFO - 'MOZ_NO_REMOTE': '1', 18:08:21 INFO - 'NO_EM_RESTART': '1', 18:08:21 INFO - 'PAGER': '/bin/cat', 18:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:21 INFO - 'PWD': '/builds/slave/test', 18:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:21 INFO - 'SHELL': '/bin/bash', 18:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:21 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:21 INFO - 'USER': 'cltbld', 18:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:21 INFO - 'XPC_FLAGS': '0x0', 18:08:21 INFO - 'XPC_SERVICE_NAME': '0', 18:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:08:22 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 18:08:22 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 18:08:22 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 18:08:22 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 18:08:22 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 18:08:22 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 18:08:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:08:24 INFO - Installing collected packages: mozsystemmonitor 18:08:24 INFO - Running setup.py install for mozsystemmonitor 18:08:24 INFO - Successfully installed mozsystemmonitor 18:08:24 INFO - Cleaning up... 18:08:24 INFO - Return code: 0 18:08:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:24 INFO - 'HOME': '/Users/cltbld', 18:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:24 INFO - 'LOGNAME': 'cltbld', 18:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:24 INFO - 'MOZ_NO_REMOTE': '1', 18:08:24 INFO - 'NO_EM_RESTART': '1', 18:08:24 INFO - 'PAGER': '/bin/cat', 18:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:24 INFO - 'PWD': '/builds/slave/test', 18:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:24 INFO - 'SHELL': '/bin/bash', 18:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:24 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:24 INFO - 'USER': 'cltbld', 18:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:24 INFO - 'XPC_FLAGS': '0x0', 18:08:24 INFO - 'XPC_SERVICE_NAME': '0', 18:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:25 INFO - Downloading/unpacking jsonschema==2.5.1 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:08:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:27 INFO - Downloading functools32-3.2.3-2.tar.gz 18:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:08:28 INFO - warning: no files found matching '*.txt' 18:08:28 INFO - no previously-included directories found matching 'build' 18:08:28 INFO - no previously-included directories found matching 'dist' 18:08:28 INFO - no previously-included directories found matching '.git*' 18:08:28 INFO - Installing collected packages: jsonschema, functools32 18:08:28 INFO - Running setup.py install for functools32 18:08:28 INFO - warning: no files found matching '*.txt' 18:08:28 INFO - no previously-included directories found matching 'build' 18:08:28 INFO - no previously-included directories found matching 'dist' 18:08:28 INFO - no previously-included directories found matching '.git*' 18:08:28 INFO - Successfully installed jsonschema functools32 18:08:28 INFO - Cleaning up... 18:08:28 INFO - Return code: 0 18:08:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:28 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:28 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 18:08:28 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 18:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:28 INFO - 'HOME': '/Users/cltbld', 18:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:28 INFO - 'LOGNAME': 'cltbld', 18:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:28 INFO - 'MOZ_NO_REMOTE': '1', 18:08:28 INFO - 'NO_EM_RESTART': '1', 18:08:28 INFO - 'PAGER': '/bin/cat', 18:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:28 INFO - 'PWD': '/builds/slave/test', 18:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:28 INFO - 'SHELL': '/bin/bash', 18:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:28 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:28 INFO - 'USER': 'cltbld', 18:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:28 INFO - 'XPC_FLAGS': '0x0', 18:08:28 INFO - 'XPC_SERVICE_NAME': '0', 18:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:08:28 INFO - Cleaning up... 18:08:28 INFO - Return code: 0 18:08:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:28 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:28 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 18:08:28 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 18:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:28 INFO - 'HOME': '/Users/cltbld', 18:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:28 INFO - 'LOGNAME': 'cltbld', 18:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:28 INFO - 'MOZ_NO_REMOTE': '1', 18:08:28 INFO - 'NO_EM_RESTART': '1', 18:08:28 INFO - 'PAGER': '/bin/cat', 18:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:28 INFO - 'PWD': '/builds/slave/test', 18:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:28 INFO - 'SHELL': '/bin/bash', 18:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:28 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:28 INFO - 'USER': 'cltbld', 18:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:28 INFO - 'XPC_FLAGS': '0x0', 18:08:28 INFO - 'XPC_SERVICE_NAME': '0', 18:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:28 INFO - Downloading/unpacking blobuploader==1.2.4 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:31 INFO - Downloading blobuploader-1.2.4.tar.gz 18:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:08:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:08:31 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 18:08:31 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 18:08:31 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 18:08:31 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 18:08:31 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 18:08:31 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 18:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:08:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 INFO - Downloading docopt-0.6.1.tar.gz 18:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:08:32 INFO - Installing collected packages: blobuploader, requests, docopt 18:08:32 INFO - Running setup.py install for blobuploader 18:08:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:08:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:08:32 INFO - Running setup.py install for requests 18:08:33 INFO - Running setup.py install for docopt 18:08:33 INFO - Successfully installed blobuploader requests docopt 18:08:33 INFO - Cleaning up... 18:08:33 INFO - Return code: 0 18:08:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:33 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:33 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 18:08:33 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 18:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:33 INFO - 'HOME': '/Users/cltbld', 18:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:33 INFO - 'LOGNAME': 'cltbld', 18:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:33 INFO - 'MOZ_NO_REMOTE': '1', 18:08:33 INFO - 'NO_EM_RESTART': '1', 18:08:33 INFO - 'PAGER': '/bin/cat', 18:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:33 INFO - 'PWD': '/builds/slave/test', 18:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:33 INFO - 'SHELL': '/bin/bash', 18:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:33 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:33 INFO - 'USER': 'cltbld', 18:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:33 INFO - 'XPC_FLAGS': '0x0', 18:08:33 INFO - 'XPC_SERVICE_NAME': '0', 18:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-U1Frnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-SmyUqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-6JytKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Wpd9ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-EzSDE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-L5ljOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-q_eOdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-YTL4gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-kc73I_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:34 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-cYZIpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-gtOZ_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-XTshPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-wljiwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-0UHzyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-HColOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ZtKT2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:35 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-4B6hFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:08:35 INFO - Running setup.py install for manifestparser 18:08:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:08:35 INFO - Running setup.py install for mozcrash 18:08:36 INFO - Running setup.py install for mozdebug 18:08:36 INFO - Running setup.py install for mozdevice 18:08:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozfile 18:08:36 INFO - Running setup.py install for mozhttpd 18:08:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozinfo 18:08:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozInstall 18:08:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozleak 18:08:37 INFO - Running setup.py install for mozlog 18:08:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for moznetwork 18:08:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozprocess 18:08:37 INFO - Running setup.py install for mozprofile 18:08:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozrunner 18:08:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozscreenshot 18:08:38 INFO - Running setup.py install for moztest 18:08:38 INFO - Running setup.py install for mozversion 18:08:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:08:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:08:38 INFO - Cleaning up... 18:08:38 INFO - Return code: 0 18:08:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:38 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')]} 18:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:38 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')]} 18:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:38 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:38 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 18:08:38 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 18:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:38 INFO - 'HOME': '/Users/cltbld', 18:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:38 INFO - 'LOGNAME': 'cltbld', 18:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:38 INFO - 'MOZ_NO_REMOTE': '1', 18:08:38 INFO - 'NO_EM_RESTART': '1', 18:08:38 INFO - 'PAGER': '/bin/cat', 18:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:38 INFO - 'PWD': '/builds/slave/test', 18:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:38 INFO - 'SHELL': '/bin/bash', 18:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:38 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:38 INFO - 'USER': 'cltbld', 18:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:38 INFO - 'XPC_FLAGS': '0x0', 18:08:38 INFO - 'XPC_SERVICE_NAME': '0', 18:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:38 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-5daohx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:38 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-7UJif_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:38 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-cJDs8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:38 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)) 18:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-FYniZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-S7pKPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AgRRSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-BK_Rm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KXEE_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Y0ePkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-2lgX25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-HYBj1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:39 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-tfdWsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-R6bDmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-fW_X7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-pfwdHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ymqo6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AoSV6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:40 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)) 18:08:40 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)) 18:08:40 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)) 18:08:40 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)) 18:08:40 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)) 18:08:40 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)) 18:08:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:43 INFO - Downloading blessings-1.6.tar.gz 18:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:08:43 INFO - Installing collected packages: blessings 18:08:43 INFO - Running setup.py install for blessings 18:08:43 INFO - Successfully installed blessings 18:08:43 INFO - Cleaning up... 18:08:43 INFO - Return code: 0 18:08:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:08:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:43 INFO - Reading from file tmpfile_stdout 18:08:43 INFO - Current package versions: 18:08:43 INFO - blessings == 1.6 18:08:43 INFO - blobuploader == 1.2.4 18:08:43 INFO - docopt == 0.6.1 18:08:43 INFO - functools32 == 3.2.3-2 18:08:43 INFO - jsonschema == 2.5.1 18:08:43 INFO - manifestparser == 1.1 18:08:43 INFO - mozInstall == 1.12 18:08:43 INFO - mozcrash == 0.17 18:08:43 INFO - mozdebug == 0.1 18:08:43 INFO - mozdevice == 0.48 18:08:43 INFO - mozfile == 1.2 18:08:43 INFO - mozhttpd == 0.7 18:08:43 INFO - mozinfo == 0.9 18:08:43 INFO - mozleak == 0.1 18:08:43 INFO - mozlog == 3.4 18:08:43 INFO - moznetwork == 0.27 18:08:43 INFO - mozprocess == 0.23 18:08:43 INFO - mozprofile == 0.28 18:08:43 INFO - mozrunner == 6.12 18:08:43 INFO - mozscreenshot == 0.1 18:08:43 INFO - mozsystemmonitor == 0.3 18:08:43 INFO - moztest == 0.7 18:08:43 INFO - mozversion == 1.4 18:08:43 INFO - psutil == 3.1.1 18:08:43 INFO - requests == 1.2.3 18:08:43 INFO - wsgiref == 0.1.2 18:08:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:43 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')]} 18:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:43 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')]} 18:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:43 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:43 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 18:08:43 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 18:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:43 INFO - 'HOME': '/Users/cltbld', 18:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:43 INFO - 'LOGNAME': 'cltbld', 18:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:43 INFO - 'MOZ_NO_REMOTE': '1', 18:08:43 INFO - 'NO_EM_RESTART': '1', 18:08:43 INFO - 'PAGER': '/bin/cat', 18:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:43 INFO - 'PWD': '/builds/slave/test', 18:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:43 INFO - 'SHELL': '/bin/bash', 18:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:43 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:43 INFO - 'USER': 'cltbld', 18:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:43 INFO - 'XPC_FLAGS': '0x0', 18:08:43 INFO - 'XPC_SERVICE_NAME': '0', 18:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:43 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 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)) 18:08:44 INFO - Cleaning up... 18:08:44 INFO - Return code: 0 18:08:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:08:44 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')]} 18:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:44 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')]} 18:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:44 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 0x1066fe490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106674570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc71c93be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, '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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 18:08:44 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 18:08:44 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 18:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:44 INFO - 'HOME': '/Users/cltbld', 18:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:44 INFO - 'LOGNAME': 'cltbld', 18:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:44 INFO - 'MOZ_NO_REMOTE': '1', 18:08:44 INFO - 'NO_EM_RESTART': '1', 18:08:44 INFO - 'PAGER': '/bin/cat', 18:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:44 INFO - 'PWD': '/builds/slave/test', 18:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:44 INFO - 'SHELL': '/bin/bash', 18:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:08:44 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:44 INFO - 'USER': 'cltbld', 18:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:44 INFO - 'XPC_FLAGS': '0x0', 18:08:44 INFO - 'XPC_SERVICE_NAME': '0', 18:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:08:44 INFO - Cleaning up... 18:08:44 INFO - Return code: 0 18:08:44 INFO - Running post-action listener: _resource_record_post_action 18:08:44 INFO - Running post-action listener: _start_resource_monitoring 18:08:44 INFO - Starting resource monitoring. 18:08:44 INFO - [mozharness: 2016-10-12 01:08:44.313508Z] Finished create-virtualenv step (success) 18:08:44 INFO - [mozharness: 2016-10-12 01:08:44.314562Z] Running install step. 18:08:44 INFO - Running pre-action listener: _resource_record_pre_action 18:08:44 INFO - Running main action method: install 18:08:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:44 INFO - Reading from file tmpfile_stdout 18:08:44 INFO - Detecting whether we're running mozinstall >=1.0... 18:08:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:08:44 INFO - Reading from file tmpfile_stdout 18:08:44 INFO - Output received: 18:08:44 INFO - Usage: mozinstall [options] installer 18:08:44 INFO - Options: 18:08:44 INFO - -h, --help show this help message and exit 18:08:44 INFO - -d DEST, --destination=DEST 18:08:44 INFO - Directory to install application into. [default: 18:08:44 INFO - "/builds/slave/test"] 18:08:44 INFO - --app=APP Application being installed. [default: firefox] 18:08:44 INFO - mkdir: /builds/slave/test/build/application 18:08:44 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'] 18:08:44 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 18:09:18 INFO - Reading from file tmpfile_stdout 18:09:18 INFO - Output received: 18:09:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:09:18 INFO - Running post-action listener: _resource_record_post_action 18:09:18 INFO - [mozharness: 2016-10-12 01:09:18.888958Z] Finished install step (success) 18:09:18 INFO - [mozharness: 2016-10-12 01:09:18.889058Z] Running run-tests step. 18:09:18 INFO - Running pre-action listener: _resource_record_pre_action 18:09:18 INFO - Running main action method: run_tests 18:09:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:09:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:09:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:09:18 INFO - 2016-10-11 18:09:18.903 screenresolution[1641:7570] starting screenresolution argv=screenresolution get 18:09:18 INFO - 2016-10-11 18:09:18.906 screenresolution[1641:7570] Display 0: 1600x1200x32@60 18:09:18 INFO - 2016-10-11 18:09:18.912 screenresolution[1642:7572] starting screenresolution argv=screenresolution list 18:09:18 INFO - Available Modes on Display 0 18:09:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:09:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:09:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:09:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:09:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:09:19 INFO - Intel Iris: 18:09:19 INFO - Chipset Model: Intel Iris 18:09:19 INFO - Type: GPU 18:09:19 INFO - Bus: Built-In 18:09:19 INFO - VRAM (Dynamic, Max): 1536 MB 18:09:19 INFO - Vendor: Intel (0x8086) 18:09:19 INFO - Device ID: 0x0a2e 18:09:19 INFO - Revision ID: 0x0009 18:09:19 INFO - Displays: 18:09:19 INFO - Display: 18:09:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:09:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:09:19 INFO - Display Serial Number: 1600x1200 60 18:09:19 INFO - Main Display: Yes 18:09:19 INFO - Mirror: Off 18:09:19 INFO - Online: Yes 18:09:19 INFO - Rotation: Supported 18:09:19 INFO - Return code: 0 18:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:09:19 INFO - Python 2.7.3 18:09:19 INFO - Return code: 0 18:09:19 INFO - grabbing minidump binary from tooltool 18:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:19 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 0x106512d30>, '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 0x106338db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10650ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:09:19 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 18:09:19 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 18:09:19 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 18:09:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:09:19 INFO - Return code: 0 18:09:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:09:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:09:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:09:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:09:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:09:19 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/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 18:09:19 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/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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 18:09:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUvxUxDdg4/Render', 18:09:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:19 INFO - 'HOME': '/Users/cltbld', 18:09:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:19 INFO - 'LOGNAME': 'cltbld', 18:09:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:09:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:19 INFO - 'MOZ_NO_REMOTE': '1', 18:09:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:09:19 INFO - 'NO_EM_RESTART': '1', 18:09:19 INFO - 'PAGER': '/bin/cat', 18:09:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:19 INFO - 'PWD': '/builds/slave/test', 18:09:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:09:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:19 INFO - 'SHELL': '/bin/bash', 18:09:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5OAHeBq3j6/Listeners', 18:09:19 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 18:09:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:19 INFO - 'USER': 'cltbld', 18:09:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:19 INFO - 'XPC_FLAGS': '0x0', 18:09:19 INFO - 'XPC_SERVICE_NAME': '0', 18:09:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:19 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/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 18:09:19 INFO - mozversion application_buildid: 20161011160246 18:09:19 INFO - mozversion application_changeset: daa573228c276fcabf806766e0ae4d736f86b089 18:09:19 INFO - mozversion application_display_name: Nightly 18:09:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:09:19 INFO - mozversion application_name: Firefox 18:09:19 INFO - mozversion application_remotingname: firefox 18:09:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:09:19 INFO - mozversion application_vendor: Mozilla 18:09:19 INFO - mozversion application_version: 52.0a1 18:09:19 INFO - mozversion platform_buildid: 20161011160246 18:09:19 INFO - mozversion platform_changeset: daa573228c276fcabf806766e0ae4d736f86b089 18:09:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:09:19 INFO - mozversion platform_version: 52.0a1 18:09:19 INFO - using testdate: 1476234559 18:09:19 INFO - actual date: 1476234559 18:09:19 INFO - starting webserver on 'localhost:49271' 18:09:19 INFO - SUITE-START | Running 2 tests 18:09:19 INFO - TEST-START | dromaeo_css 18:09:19 INFO - operating with platform_type : mac_ 18:09:19 INFO - Initialising browser for dromaeo_css test... 18:09:19 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp0e4IQg/profile http://localhost:49271/getInfo.html) 18:09:21 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 18:09:21 INFO - PROCESS | 1652 | colorDepth:24 18:09:21 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 18:09:21 INFO - PROCESS | 1652 | __metrics 18:09:22 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" 18:09:22 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:09:22 INFO - TEST-INFO | 1652: exit 0 18:09:22 INFO - Browser initialized. 18:09:22 INFO - Running cycle 1/1 for dromaeo_css test... 18:09:22 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp0e4IQg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:34 INFO - PROCESS | 1654 | RSS: Main: 235167744 18:09:34 INFO - PROCESS | 1654 | 18:10:26 INFO - PROCESS | 1654 | 1476234626596 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] 18:12:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 18:12:40 INFO - PROCESS | 1654 | RSS: Main: 416796672 18:12:40 INFO - PROCESS | 1654 | 18:15:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 18:15:44 INFO - PROCESS | 1654 | RSS: Main: 371965952 18:15:44 INFO - PROCESS | 1654 | 18:18:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 18:18:47 INFO - PROCESS | 1654 | RSS: Main: 363384832 18:18:47 INFO - PROCESS | 1654 | 18:21:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 18:21:50 INFO - PROCESS | 1654 | RSS: Main: 340221952 18:21:50 INFO - PROCESS | 1654 | 18:24:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 18:24:54 INFO - PROCESS | 1654 | RSS: Main: 333008896 18:24:54 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 18:27:57 INFO - PROCESS | 1654 | RSS: Main: 484802560 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | __start_tp_report 18:27:57 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 18:27:57 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 18:27:57 INFO - PROCESS | 1654 | |i|pagename|runs| 18:27:57 INFO - PROCESS | 1654 | |0;dojo.html;2867.1828528869264;3281.8677556243097;3313.801171929684;3316.253842885351;3321.4187517184487;5380.704061276629;5458.5087342139195;5488.231647569343;5557.555395568355;5564.387917329095;5474.178873169027;5480.972595137028;5552.7223638817995;5563.165525171226;5609.663420194781;5006.774695138727;5021.447640759532;5062.291279220912;5095.439501654815;5099.4900509949075;5089.770960306777;5146.3309769729885;5157.742112894359;5164.044651739421;5243.764030618612;5144.717040562768;5151.995360904625;5154.38147422308;5164.018836421087;5220.738963051851;4950.4801995790285;5016.573591244749;5031.924521132186;5101.872453188663;5105;5023.171176755831;5054.797808087672;5054.974725126351;5062.063518249136;5102.107131252016;5465.371482279549;5557.5831812613815;5561.440677966105;5586.24585680936;5621.381648018714;5645.943540564624;5675.829725108253;5699.74351154199;6027.78902738402;6075.513958883279;5107.489251074863;6044.788432404889;6075.605085669417;6103.055501741071;6163.2604087509335;5634.4365563443735;5635.943640563624;5637.267155269788;5650.406707295675;5656.575756818255;6041.335453100155;6149.2620885494025;6265.686715664198;6318.462930650854;6327.082573026977;5810.593258471867;5853.9414605854245;5874.412558744091;5898.4101589840675;5900.350961394243;6058.757649694051;6064.36324185963;6064.605800622945;6068.362821903725;6078.908816367758;5842.977478941169;5891.086881533369;5902.498287645598;5906.763729450774;5925.970370148121;5798.478136967693;5881.05903055509;5890.734916928748;5991.910121348184;5992.161097446361;4788.688735232268;4794.2808578712775;4797.808087676523;4810.7835147418455;4812.494688057774;4234.089670720809;4278.187144442546;4359.651227901761;4372.562743725602;4384.671149663788;5516.979358818631;5574.9442505574425;5586.050371436865;5589.888202235932;5605.32736071674;6040.365761595057;6047.0324748040985;6061.969690151609;6067.211262535842;6076;5595.4124816894455;5604.887902242014;5609.80365687199;5610.663360198401;5611.663300202001;5190.740462976836;5201.973990130025;5202.843914682566;5257.263983042377;5264.763085661154;6330.746770129143;6342.8731425370315;6352.174217351715;6375;6383.585066970725;5884.382139875252;5962.493188079062;5971.4327138921735;6132.601380910227;6150.7847225346895;6229.906551401823;6276.529260305404;6285.340039295991;6332.271788744353;6350.618962862059;6005.219321488178;6007.639541627516;6012.278526576839;6023.656870436949;6086.847828804315;5911.438413350725;5966.403359664172;6138.877222455437;6151.507879369729;6158;5782.566307527037;5807.041838096667;5813.3605303417435;5840.853978650568;5842.7662893485485;4767.856964291077;5853.414658534112;5854.414558544111;5860.853478663067;5892.7642894283745;5735.7418916148035;5736.483716465371;5740.942590574041;5753.993051215937;5770.13447982806;5757.942420575908;5830.737616807339;5835.591508594358;5840.8247752567495;5882.764689412375;5673.34756502991;5737.08206686928;5746.5402767779315;5748.885022299447;5755.079187330174 18:27:57 INFO - PROCESS | 1654 | |1;ext.html;475.35589276530294;488.6237597050271;521.2311840035948;524.035774175517;524.1848924921748;29997.400051998946;30673.77304907801;39402.605973940284;40312.596874031326;40568.391474127835;41379.96550086249;41752.58247417525;41806.95482612936;41820.79089604555;41865.37201941966;9407.294452916025;9584.952075239615;9614.278929080312;9616.134547890688;9630.614775408976;8559.657613695445;8801.735947921572;11441.427928603578;11645.184837061366;11691.766164676701;11582.652520424359;11650.184487085908;11672.949434550888;11681.59114430995;11716.238444501123;11578.305301681927;11627.651170464815;11650.359230242333;11651.708707282343;11654.883451165464;8428.115047919942;8630.482171069756;8640.22237998577;8714.825703485958;8742.737717868475;35202.647973520194;38023.80988095069;38982.02544936371;40734;40737;3303.752218583592;3312.337532493511;3318.0875259303884;3432.5366075579977;3756.6619004289473;30532.08403747891;30812.075637730904;31141.8442907784;31241.37517249665;31583.42082895846;3175.7300629446527;3178.3802158579088;3235.5793746813;3324.8670053197843;3327.1848397142853;457826;459712.9999999967;463808;465847;473004;50509;52162.47837521576;52507;54704;59166.40833591695;60577.39422605806;60649.09026364608;62164.68917655383;62997;63008.68495657492;15271.160086195143;15407.845921540866;15439.768403473958;15441.382344706311;15448.845511544965;10809.189310801565;11768.82346764782;11865.228760130563;11868.821967670492;11884.227525210834;14560.63598410027;14997.850021499864;16729.247183876756;16826.747598786027;17074.317027319015;6352.809415717536;6751.750926078691;6906.407239185885;7032.964835175791;7104.6447677615915;855.7176131876466;991.2466525440717;1008.8133695266399;1043.415687215162;1060.761328701036;27050.91796328164;28074.438511229662;29239.56140657892;29595.704042959725;30574.84712576423;1014.7919676466307;1052.2318707343682;1055.1664185293685;1069.7539565899888;1128.2440764687678;12756.971554659282;15113.47102851417;15332.846671533365;15407.306671199824;15933.920330398274;625266;630577;631012;632228;633341;56465.43534564684;56572.71713641405;56610.43389566051;56614;56626.43373566212;1211.3277131192096;1241.522014024607;1336.6457888659318;1349.197227649547;1367.0294091195362;29865.25336866596;30756;30963.380732385678;31587;31879.362412752078;48299.517004829504;48990.265146022124;49030.26454603255;49062.01875962532;49224.26163607477;516482;517460.6031517394;517567;517692.8230717795;691384;25830.483390332465;26025.21924342273;26084.73915260899;26087.95636676318;26184.73815261823;45868.77065614651;46424;47450.76274618605;47888;47910.52089479061;32596.185095372806;37282.627173729;39398.212035759694;39812;40137;38091.619083809914;38539.42190867083;39518.60481395149;39621.60378396179;39691.80154099211;36966.81516592507;37215.813920930224;37562.624373757004;37608.811955940044;38206.61793382031;33240.1689957753;33664.831675841466;34128.829355853064;34197.48703769396;34348.14129646779;25115.623265650665;25133.49733005293;25240.368990775376;25281.62077568801;25384.11155609545 18:27:57 INFO - PROCESS | 1654 | |2;jquery.html;351.7309258417313;373.88222709846394;373.98130093495325;380.8933498620386;404.54286656078625;14480;14849;15118.903827495391;20377.981100944966;20510.820822772494;18388.172532236047;19490.92799896005;19797.634402151634;20905;21136.365909022716;5861.648301101937;5925.940740592582;6168.259808822925;6172.5370597205165;6181.907271390933;5947.286325640929;6065.332813390523;6110.786122485714;6119.969400152993;6122.265328160627;5741.511971482429;5974.79088231912;6075.119107729377;6075.908861367083;6082.756689732405;5985.072313791369;6044.607100538451;6051.515878729691;6066.393360663942;6081.513478921698;5070.467600901889;5158.40678321994;5163.690178589297;5239.580833533309;5247.580193584504;25420.49159016828;25524.106656267126;25532.361690957874;25624.23127306184;25897.928165746696;2287.4853158039473;3003.5344521599186;3021.154076858461;3028.37918226763;3037.5747395364665;14927;15680.215989200495;15725.606859828482;15797.289121989537;15937.760933586005;1770.1768677564994;1886.6328678113098;1910.0927059646694;1967.3409407848387;1969.3993332033867;119214;119537.20825628415;121778;121900.32334038551;123999;10588.311759735592;10689.679309620724;10778.892211077946;11028.779424411627;11283.323000619988;13799.655008624664;13806.723865522834;13819.930900345435;13848.930755346159;13852.021815309758;8072.5963701815845;8323.16768323175;8351.498910065415;8439.789005274795;8474.74575762728;6606.333484915822;8329.66681332752;8357.247847693738;8382.622781974704;8546.700865469678;6383.744650214032;6549.541532092708;6566;6573.683534676087;6593.208814942238;1513.28119143406;1804.3684710351272;1815.3101821307819;1820.280989009334;1825.2060353746162;893.9329550283625;904.5341649050744;923.3121324613206;944.2587568758629;1007.0986467111894;15882.047077175406;20539.075741591365;21916.561668766535;21929.162396580854;22228.777712222993;912.1061359867367;927.9257659387233;944.6079876851168;946.3281070439928;963.643451922793;7926.920730792734;10661.466926653637;10815.729606759736;11608.30350178992;11648.242864213798;564968;582244;590094;600039;602525;112499;113972;115975.2603710945;124935;134840.32579837038;948.170350942925;978.1538968791943;991.7322322972901;992.2409356842022;1011.9595216191417;19186.904065479583;19513.51216219549;19699.704504432157;19881.403557893285;19932.90033549823;41757.999999998785;55073;56225.43774562202;56538.86922261614;57142.42857571371;80724.59637701773;80727.59636202017;81978.59010704908;83984.16015839763;84079;7665.578393188428;7872.291493765589;8098.919010809816;8132.308753755997;8323.83352332962;10935.781284374427;12610.810837837624;12848.164869283466;12876.549320773727;13015.739685206432;11145.88854111481;11177.385243811343;11286.266392684449;11410.258333208314;11480.425978700865;10785.298955568176;10973.451327433757;11099.278546894426;11379.260348077349;11551.88448115508;11122.61070862516;11233.606823761129;11339.773204536028;11470.598529051444;11624.825627615422;7812.88280675779;7944.761657150295;8050.5572193529915;8110.472819266729;8202.26179643835;8177.427580069338;10637.680869573926;10767.676969690921;10931.234813562975;11141.220114592224 18:27:57 INFO - PROCESS | 1654 | |3;mootools.html;1328.8272524571805;1471.8013068235791;1494.1259363272486;1657.8590819780325;1845.9446216613499;7624.23757624238;8130.756077317676;8344.2907352875;8685.86971195431;8758.649654013832;8580.785480362994;8648.264897483707;8652.826943461143;8655.307575393968;8661.870071948926;2309.145616122029;2333.7782910623464;2337.929862104131;2343.5078633486987;2352.2707960532202;2285.7828506291958;2316.328264803205;2334.7781960713705;2336.0538981712434;2336.19401306549;2297.4026753044163;2322.7096612923383;2332.47519308156;2344.812415006795;2345.026813872241;2272.5909336319455;2299.321700098475;2309.988450057739;2311.699479067727;2319.9999999999914;2283.4633861042644;2292.64464008079;2299.7815207555423;2302.5164715409783;2303.0442366417915;8524.701635442792;8568.357373197035;8622.784430389227;8625.525596092213;8627.007894092134;4695.5304469552775;5032.6477146599755;5106.285120083191;5226.477352264781;5228.4510126436935;3183.458812001942;3346.748993825473;3577.4454959481322;3649.489071529988;3649.835757390897;1037;1069.9679009629724;1070.4273213830563;1094.458243169636;1096.5065720425841;11430.542778288858;11433.828492572618;11529.192956493049;11554.53781848725;11561.190716649753;4028.7582745035393;4047.1096358801015;4266.0827921996915;4317.266064768997;4480.596746292849;8867.379283450226;8874.77813054666;8879.556022198993;8880;8889.422187557788;5115.104856650146;5119.923201151985;5122.513361230677;5125.794968201305;5132.1788513838355;4263.488381394252;4286.592773686557;4445.711028783118;4782.163121453732;4816.638752093607;3562.447820587813;3590.7791350940806;3702.7037836972977;3706.777593344408;3723.491985745777;1128.9830652540218;1137.113051819582;1146.18620779246;1152.4698638626157;1157.346099453806;732.5348403763542;743.2418932688628;801.9318357939641;874.1695389380114;909.9317551183688;5568.52667523257;5675.318961724537;5787.2543322456595;5918.674472904032;5923.940760592425;700.4676445901151;718.4611541343992;841.7600983719733;871.1636828644572;945.0879900895532;3730.2166545025307;3780.962190378061;3790.2608991246993;3905.2775236580756;3962.405639154092;10278.537465814057;10739.979701928305;10777.568897244179;10795.190360722978;10836.458177091114;10320.43237621938;10557.313774604627;10712.625058122929;10755.354678719303;10764.838527422095;8062.798430039179;8219.342452603896;8220;8221.424500285042;8227.8354432911;5590.580706446952;5641.548676105819;5726.65640061581;5742.454466825645;5830.271216097988;9657.565409556728;9834.459104749307;9883.159931405911;9931.155851752683;9954.352967057119;9506.47714375716;9949.502524873582;9955.950220248853;10073.043060909202;10117.898821011695;6859.657017149222;7052.435805135474;7382.114146302478;7539.962300188464;7547.7735667927855;7742;7992.480488768444;8111.594420278844;8263.297619702393;8382.036065852504;7320.487565870338;7382;7421.925780742124;7537.321641052332;7582.658780354787;6221.782237621662;7279.308465695751;7302.96348518254;7329.633518324169;7362.5214361066355;6691.230508491586;6841.760538381132;6905.896411553931;6911.861762764616;6937.861242775217;4683.555062269079;4714.66997310185;4737.502562231054;4774.594159496482;4887.951120488749;4719.764011799465;4768.237082066853;4846.660733748745;4882.6826256293225;4968.701877887338 18:27:57 INFO - PROCESS | 1654 | |4;prototype.html;297.85702862625925;299.53871038600556;308.60960884481136;312.7154289596468;315.67800843139986;4728.976355118229;4883.096627123985;5190.844274671761;5209.7134657593815;5226.8693282667955;4226.372846454115;4432.756198409094;4545.340925565799;4813.590844778198;5044.445111037783;3584.211473475831;3779.130799916021;4006.3389540725743;4018.176273863851;4020.8994775130564;3374.0721301641997;3786.148116673754;3808.504894363729;3924.2347742190286;3984.6812255019527;3439.6044454887633;3744.700423966076;3836.0601652595155;3941.9211615767667;3965.8611948581806;3454.6372630873775;3911.242573985348;3939.231849789292;3958.3270843956598;3976.324024915771;3010.3377257003426;3144.559761633373;3425.6060553036473;3433.0215888471917;3447.913802154941;4397.494288156871;4544.431946006749;4613.685099746556;5087.49125087492;5095.235714642796;3630.8366123524506;4495.730256184639;4520.547945205486;4527.4340707411575;4541.477730061019;4645.65157613177;5429;5501.559875209973;5505;5516.91724624131;3284.5730055092686;3424.6404127566498;3735.82321568705;3739.775613463201;3752.512173417438;5468.781248750045;5689.829305120853;5704.34400043992;5763.827085187451;5766.94233057664;3630.146915474861;3648.21563363878;3787.4697542344093;3810.2950954073585;3817.809109544567;3245.626752923444;3254.3491301739746;3891.63792672568;3893.377059670439;3900.1419687668667;3804.097951024488;4330.501992270866;4536.77316134192;4567.611753001032;4577.496475387705;4460.397846290774;5491.807786727525;5509.539971907448;5529.861753456115;5647.350554686263;1031.803108394274;1259.2696236182992;1289.607224197864;1312.2716892124963;1314.3165553912104;696.5437638346891;705.7071315404049;812.1959260332228;872.9607167677469;877.8902637170354;5122.513361230677;5154.252633368215;5157.767900444489;5159.658488792866;5187.662801917863;4742.264948932921;5266.4470230626;5514.6691198528215;5516.420775818561;5529.972350138223;4289.048482940317;4826.131296366688;5093.414257360451;5145.4597267287145;5177.689338639693;2795.329121010943;2858.7284208000206;2891.209982367026;2924.9707502925125;2949.8672559734864;4990.50094990498;5015.724135172601;5140.511651393112;5634.7182640867795;5745.68398738065;5000.724960127228;5404.919016196698;5663.716814159276;5685.857853553612;5686.914696279559;5257.369115706063;5704.372519022904;5749.683767392667;5751.814310562043;5765.769369225268;4413.58070983256;5025;5476.58925580591;5491.341039075336;5602.803901863415;4553.271476563734;4655.813767449264;5358.767483478744;5450.809221677381;5489.85895500747;4818.98801251727;5355.714628489212;5439.857629897766;5707.486326230659;5721.4812890073135;3143.9528407073453;3148.811575954781;3458.8616455341503;3464.5149679045458;3685.4840322355426;4563.498015218257;5132.461091585479;5514.889702206014;5529.700520377628;5544.916826247691;4378.715383500063;4676.92984605238;4786.641001924939;5109.74451277442;5118.539331460186;5078.898422031465;5347.893042139213;5371.167469042305;5402.135658294654;5443.591730620298;5095.235714642833;5271.472852714698;5277.8416647500635;5284.392294886137;5308.814191503279;4961.900761984813;5046.889684269455;5425.620206585659;5535.8892822144135;5547.472990065938;4995.525425084611;5141.305923700402;5381.408045114956;5440.483154100354;5445.727713614383 18:27:57 INFO - PROCESS | 1654 | |5;yui.html;712.6971037309143;752.243994785241;756.3684323589803;794.9244821741933;802.5866678660489;15469.84530154698;15830.92084539577;15838.76241856373;15869.444569440071;15870.682586348266;14150.221737804417;14234.71530569388;14246.216458094827;14250.144991300529;14552.272386380691;671.5097978475716;689.1764341611776;815.9143289954559;816.020775069917;842.6039761312173;827.9254867061964;829.4152622401193;831.2726364431123;835.9884539706962;836.2056046755606;883.7127933421664;887.6227603268617;887.8135591525787;888.2893685051965;890.0031964200105;875.5928493250669;878.3412440669498;881.4887365328126;881.6209030117047;884.8097659003281;516.7622893469008;530.7478947499945;641.7144370755016;655.2922843329193;656.3961155736686;14863.925680371636;14900.0314979526;14904.105753654816;14915.552533424014;14917;13500.864991350158;13940.651483712887;14087.225202613852;14087.71824563503;14180.22008789516;12127.471672130216;12535.435905384189;12585.74828503434;12586;12979.286139262336;4461.486929003141;4472.731636101845;4554.43069616298;4667.393238878958;4688.882777930579;95403.52298238465;96849.51575242079;116577;118999;123411;11407.48666310018;11631.53473861053;11714.180007399571;11722.648320550397;12189.390530473442;23902.282931512083;24136.87931560366;24186.758132418803;24248.51502969966;24296.392590185034;8012.1186669466315;8187.26314631686;8487.193716596914;8487.745367638981;8527.232549070613;3909.5113110861143;4177.415161877339;4300.3549467580115;4372.344148377705;4438.955610443919;3653.1780349421697;3776.376897811885;3790.1093243087853;3943.7830919299713;4159.896002599899;709.4005565297316;712.9928700713031;731.3673672273552;737.3474475089478;739.3752279323962;560.4479587606205;614.6527212125135;641.810665853565;643.3341491556217;655.2693746472648;9265.490398028172;9295.907040929504;9503.429794212369;9517.762055948657;9525.237980961507;551.3879593651044;609.8597322615823;629.3234772619452;634.9903653191448;636.5766765101156;4414.580614841585;4431.667624928144;4623.722576645345;4706.976465117721;4809.206480930678;159413;170743.29256707523;171756;172019.13990429966;178872.7465429486;120044;144946.5505344954;145653.27173364066;147190;149315.69798805375;122495;122853.38573307254;123655;124830.75169248373;125246.37376813058;8479.279261262862;8779.297656187362;8832.072632373509;8899.599518021834;9192.218661413588;64292.03561946481;67210.99183512153;67445.66277168778;68048.3195168042;68917.65541172262;90038.89649931538;101139;102625.78993680637;104028.47985760022;108788.4560577192;11845.170838041255;11898;12059.397030148633;12153.939230303791;12314.445849936596;11829.994450471806;13314.93342533281;14608.196549189897;14650.413983440969;14758.48345307909;13333.933330333286;13659.317034148451;13679.04246702761;14226.573202803933;14294.71410571762;13938.512152074629;14057.22685252321;14264.358103885143;14270.643233919234;14709.117452952856;10996.395198264172;13438.529651462151;13531.729365412833;13657.180569165881;13915.886729061853;10842.783144337227;11012.39431831257;11135.944320278346;11410.82883756761;11441.542338306375;10862.837057443987;11200.846024532482;11479.483423246142;11545.307281562797;11601.419929003347 18:27:57 INFO - PROCESS | 1654 | __end_tp_report 18:27:57 INFO - PROCESS | 1654 | __start_cc_report 18:27:57 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1890 18:27:57 INFO - PROCESS | 1654 | __end_cc_report 18:27:57 INFO - PROCESS | 1654 | __startTimestamp1476235677588__endTimestamp 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | ------- Summary: start ------- 18:27:57 INFO - PROCESS | 1654 | Number of tests: 6 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5567.96 Median:5736.48 stddev:624.78 (10.9%) stddev-sans-first:589.68 18:27:57 INFO - PROCESS | 1654 | Values: 2867.2 3281.9 3313.8 3316.3 3321.4 5380.7 5458.5 5488.2 5557.6 5564.4 5474.2 5481.0 5552.7 5563.2 5609.7 5006.8 5021.4 5062.3 5095.4 5099.5 5089.8 5146.3 5157.7 5164.0 5243.8 5144.7 5152.0 5154.4 5164.0 5220.7 4950.5 5016.6 5031.9 5101.9 5105.0 5023.2 5054.8 5055.0 5062.1 5102.1 5465.4 5557.6 5561.4 5586.2 5621.4 5645.9 5675.8 5699.7 6027.8 6075.5 5107.5 6044.8 6075.6 6103.1 6163.3 5634.4 5635.9 5637.3 5650.4 5656.6 6041.3 6149.3 6265.7 6318.5 6327.1 5810.6 5853.9 5874.4 5898.4 5900.4 6058.8 6064.4 6064.6 6068.4 6078.9 5843.0 5891.1 5902.5 5906.8 5926.0 5798.5 5881.1 5890.7 5991.9 5992.2 4788.7 4794.3 4797.8 4810.8 4812.5 4234.1 4278.2 4359.7 4372.6 4384.7 5517.0 5574.9 5586.1 5589.9 5605.3 6040.4 6047.0 6062.0 6067.2 6076.0 5595.4 5604.9 5609.8 5610.7 5611.7 5190.7 5202.0 5202.8 5257.3 5264.8 6330.7 6342.9 6352.2 6375.0 6383.6 5884.4 5962.5 5971.4 6132.6 6150.8 6229.9 6276.5 6285.3 6332.3 6350.6 6005.2 6007.6 6012.3 6023.7 6086.8 5911.4 5966.4 6138.9 6151.5 6158.0 5782.6 5807.0 5813.4 5840.9 5842.8 4767.9 5853.4 5854.4 5860.9 5892.8 5735.7 5736.5 5740.9 5754.0 5770.1 5757.9 5830.7 5835.6 5840.8 5882.8 5673.3 5737.1 5746.5 5748.9 5755.1 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:68041.30 Median:37249.22 stddev:148183.15 (397.8%) stddev-sans-first:148512.01 18:27:57 INFO - PROCESS | 1654 | Values: 475.4 488.6 521.2 524.0 524.2 29997.4 30673.8 39402.6 40312.6 40568.4 41380.0 41752.6 41807.0 41820.8 41865.4 9407.3 9585.0 9614.3 9616.1 9630.6 8559.7 8801.7 11441.4 11645.2 11691.8 11582.7 11650.2 11672.9 11681.6 11716.2 11578.3 11627.7 11650.4 11651.7 11654.9 8428.1 8630.5 8640.2 8714.8 8742.7 35202.6 38023.8 38982.0 40734.0 40737.0 3303.8 3312.3 3318.1 3432.5 3756.7 30532.1 30812.1 31141.8 31241.4 31583.4 3175.7 3178.4 3235.6 3324.9 3327.2 457826.0 459713.0 463808.0 465847.0 473004.0 50509.0 52162.5 52507.0 54704.0 59166.4 60577.4 60649.1 62164.7 62997.0 63008.7 15271.2 15407.8 15439.8 15441.4 15448.8 10809.2 11768.8 11865.2 11868.8 11884.2 14560.6 14997.9 16729.2 16826.7 17074.3 6352.8 6751.8 6906.4 7033.0 7104.6 855.7 991.2 1008.8 1043.4 1060.8 27050.9 28074.4 29239.6 29595.7 30574.8 1014.8 1052.2 1055.2 1069.8 1128.2 12757.0 15113.5 15332.8 15407.3 15933.9 625266.0 630577.0 631012.0 632228.0 633341.0 56465.4 56572.7 56610.4 56614.0 56626.4 1211.3 1241.5 1336.6 1349.2 1367.0 29865.3 30756.0 30963.4 31587.0 31879.4 48299.5 48990.3 49030.3 49062.0 49224.3 516482.0 517460.6 517567.0 517692.8 691384.0 25830.5 26025.2 26084.7 26088.0 26184.7 45868.8 46424.0 47450.8 47888.0 47910.5 32596.2 37282.6 39398.2 39812.0 40137.0 38091.6 38539.4 39518.6 39621.6 39691.8 36966.8 37215.8 37562.6 37608.8 38206.6 33240.2 33664.8 34128.8 34197.5 34348.1 25115.6 25133.5 25240.4 25281.6 25384.1 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34822.77 Median:14450.73 stddev:98190.70 (679.5%) stddev-sans-first:98432.04 18:27:57 INFO - PROCESS | 1654 | Values: 351.7 373.9 374.0 380.9 404.5 14480.0 14849.0 15118.9 20378.0 20510.8 18388.2 19490.9 19797.6 20905.0 21136.4 5861.6 5925.9 6168.3 6172.5 6181.9 5947.3 6065.3 6110.8 6120.0 6122.3 5741.5 5974.8 6075.1 6075.9 6082.8 5985.1 6044.6 6051.5 6066.4 6081.5 5070.5 5158.4 5163.7 5239.6 5247.6 25420.5 25524.1 25532.4 25624.2 25897.9 2287.5 3003.5 3021.2 3028.4 3037.6 14927.0 15680.2 15725.6 15797.3 15937.8 1770.2 1886.6 1910.1 1967.3 1969.4 119214.0 119537.2 121778.0 121900.3 123999.0 10588.3 10689.7 10778.9 11028.8 11283.3 13799.7 13806.7 13819.9 13848.9 13852.0 8072.6 8323.2 8351.5 8439.8 8474.7 6606.3 8329.7 8357.2 8382.6 8546.7 6383.7 6549.5 6566.0 6573.7 6593.2 1513.3 1804.4 1815.3 1820.3 1825.2 893.9 904.5 923.3 944.3 1007.1 15882.0 20539.1 21916.6 21929.2 22228.8 912.1 927.9 944.6 946.3 963.6 7926.9 10661.5 10815.7 11608.3 11648.2 564968.0 582244.0 590094.0 600039.0 602525.0 112499.0 113972.0 115975.3 124935.0 134840.3 948.2 978.2 991.7 992.2 1012.0 19186.9 19513.5 19699.7 19881.4 19932.9 41758.0 55073.0 56225.4 56538.9 57142.4 80724.6 80727.6 81978.6 83984.2 84079.0 7665.6 7872.3 8098.9 8132.3 8323.8 10935.8 12610.8 12848.2 12876.5 13015.7 11145.9 11177.4 11286.3 11410.3 11480.4 10785.3 10973.5 11099.3 11379.3 11551.9 11122.6 11233.6 11339.8 11470.6 11624.8 7812.9 7944.8 8050.6 8110.5 8202.3 8177.4 10637.7 10767.7 10931.2 11141.2 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5553.94 Median:4885.32 stddev:3150.95 (64.5%) stddev-sans-first:3143.79 18:27:57 INFO - PROCESS | 1654 | Values: 1328.8 1471.8 1494.1 1657.9 1845.9 7624.2 8130.8 8344.3 8685.9 8758.6 8580.8 8648.3 8652.8 8655.3 8661.9 2309.1 2333.8 2337.9 2343.5 2352.3 2285.8 2316.3 2334.8 2336.1 2336.2 2297.4 2322.7 2332.5 2344.8 2345.0 2272.6 2299.3 2310.0 2311.7 2320.0 2283.5 2292.6 2299.8 2302.5 2303.0 8524.7 8568.4 8622.8 8625.5 8627.0 4695.5 5032.6 5106.3 5226.5 5228.5 3183.5 3346.7 3577.4 3649.5 3649.8 1037.0 1070.0 1070.4 1094.5 1096.5 11430.5 11433.8 11529.2 11554.5 11561.2 4028.8 4047.1 4266.1 4317.3 4480.6 8867.4 8874.8 8879.6 8880.0 8889.4 5115.1 5119.9 5122.5 5125.8 5132.2 4263.5 4286.6 4445.7 4782.2 4816.6 3562.4 3590.8 3702.7 3706.8 3723.5 1129.0 1137.1 1146.2 1152.5 1157.3 732.5 743.2 801.9 874.2 909.9 5568.5 5675.3 5787.3 5918.7 5923.9 700.5 718.5 841.8 871.2 945.1 3730.2 3781.0 3790.3 3905.3 3962.4 10278.5 10740.0 10777.6 10795.2 10836.5 10320.4 10557.3 10712.6 10755.4 10764.8 8062.8 8219.3 8220.0 8221.4 8227.8 5590.6 5641.5 5726.7 5742.5 5830.3 9657.6 9834.5 9883.2 9931.2 9954.4 9506.5 9949.5 9956.0 10073.0 10117.9 6859.7 7052.4 7382.1 7540.0 7547.8 7742.0 7992.5 8111.6 8263.3 8382.0 7320.5 7382.0 7421.9 7537.3 7582.7 6221.8 7279.3 7303.0 7329.6 7362.5 6691.2 6841.8 6905.9 6911.9 6937.9 4683.6 4714.7 4737.5 4774.6 4888.0 4719.8 4768.2 4846.7 4882.7 4968.7 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4343.15 Median:4854.61 stddev:1346.92 (27.7%) stddev-sans-first:1316.03 18:27:57 INFO - PROCESS | 1654 | Values: 297.9 299.5 308.6 312.7 315.7 4729.0 4883.1 5190.8 5209.7 5226.9 4226.4 4432.8 4545.3 4813.6 5044.4 3584.2 3779.1 4006.3 4018.2 4020.9 3374.1 3786.1 3808.5 3924.2 3984.7 3439.6 3744.7 3836.1 3941.9 3965.9 3454.6 3911.2 3939.2 3958.3 3976.3 3010.3 3144.6 3425.6 3433.0 3447.9 4397.5 4544.4 4613.7 5087.5 5095.2 3630.8 4495.7 4520.5 4527.4 4541.5 4645.7 5429.0 5501.6 5505.0 5516.9 3284.6 3424.6 3735.8 3739.8 3752.5 5468.8 5689.8 5704.3 5763.8 5766.9 3630.1 3648.2 3787.5 3810.3 3817.8 3245.6 3254.3 3891.6 3893.4 3900.1 3804.1 4330.5 4536.8 4567.6 4577.5 4460.4 5491.8 5509.5 5529.9 5647.4 1031.8 1259.3 1289.6 1312.3 1314.3 696.5 705.7 812.2 873.0 877.9 5122.5 5154.3 5157.8 5159.7 5187.7 4742.3 5266.4 5514.7 5516.4 5530.0 4289.0 4826.1 5093.4 5145.5 5177.7 2795.3 2858.7 2891.2 2925.0 2949.9 4990.5 5015.7 5140.5 5634.7 5745.7 5000.7 5404.9 5663.7 5685.9 5686.9 5257.4 5704.4 5749.7 5751.8 5765.8 4413.6 5025.0 5476.6 5491.3 5602.8 4553.3 4655.8 5358.8 5450.8 5489.9 4819.0 5355.7 5439.9 5707.5 5721.5 3144.0 3148.8 3458.9 3464.5 3685.5 4563.5 5132.5 5514.9 5529.7 5544.9 4378.7 4676.9 4786.6 5109.7 5118.5 5078.9 5347.9 5371.2 5402.1 5443.6 5095.2 5271.5 5277.8 5284.4 5308.8 4961.9 5046.9 5425.6 5535.9 5547.5 4995.5 5141.3 5381.4 5440.5 5445.7 18:27:57 INFO - PROCESS | 1654 | 18:27:57 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:26724.54 Median:24272.45 stddev:43967.99 (181.1%) stddev-sans-first:44047.96 18:27:57 INFO - PROCESS | 1654 | Values: 712.7 752.2 756.4 794.9 802.6 15469.8 15830.9 15838.8 15869.4 15870.7 14150.2 14234.7 14246.2 14250.1 14552.3 671.5 689.2 815.9 816.0 842.6 827.9 829.4 831.3 836.0 836.2 883.7 887.6 887.8 888.3 890.0 875.6 878.3 881.5 881.6 884.8 516.8 530.7 641.7 655.3 656.4 14863.9 14900.0 14904.1 14915.6 14917.0 13500.9 13940.7 14087.2 14087.7 14180.2 12127.5 12535.4 12585.7 12586.0 12979.3 4461.5 4472.7 4554.4 4667.4 4688.9 95403.5 96849.5 116577.0 118999.0 123411.0 11407.5 11631.5 11714.2 11722.6 12189.4 23902.3 24136.9 24186.8 24248.5 24296.4 8012.1 8187.3 8487.2 8487.7 8527.2 3909.5 4177.4 4300.4 4372.3 4439.0 3653.2 3776.4 3790.1 3943.8 4159.9 709.4 713.0 731.4 737.3 739.4 560.4 614.7 641.8 643.3 655.3 9265.5 9295.9 9503.4 9517.8 9525.2 551.4 609.9 629.3 635.0 636.6 4414.6 4431.7 4623.7 4707.0 4809.2 159413.0 170743.3 171756.0 172019.1 178872.7 120044.0 144946.6 145653.3 147190.0 149315.7 122495.0 122853.4 123655.0 124830.8 125246.4 8479.3 8779.3 8832.1 8899.6 9192.2 64292.0 67211.0 67445.7 68048.3 68917.7 90038.9 101139.0 102625.8 104028.5 108788.5 11845.2 11898.0 12059.4 12153.9 12314.4 11830.0 13314.9 14608.2 14650.4 14758.5 13333.9 13659.3 13679.0 14226.6 14294.7 13938.5 14057.2 14264.4 14270.6 14709.1 10996.4 13438.5 13531.7 13657.2 13915.9 10842.8 11012.4 11135.9 11410.8 11441.5 10862.8 11200.8 11479.5 11545.3 11601.4 18:27:57 INFO - PROCESS | 1654 | -------- Summary: end -------- 18:27:57 INFO - PROCESS | 1654 | 18:27:58 INFO - TEST-INFO | 1654: exit 0 18:27:58 INFO - TEST-OK | dromaeo_css | took 1118715ms 18:27:58 INFO - TEST-START | kraken 18:27:58 INFO - operating with platform_type : mac_ 18:27:58 INFO - Initialising browser for kraken test... 18:27:58 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpbqWwZY/profile http://localhost:49271/getInfo.html) 18:28:00 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 18:28:00 INFO - PROCESS | 1660 | colorDepth:24 18:28:00 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 18:28:00 INFO - PROCESS | 1660 | __metrics 18:28:00 INFO - PROCESS | 1660 | 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" 18:28:00 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:28:01 INFO - TEST-INFO | 1660: exit 0 18:28:01 INFO - Browser initialized. 18:28:01 INFO - Running cycle 1/1 for kraken test... 18:28:01 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpbqWwZY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:13 INFO - PROCESS | 1661 | RSS: Main: 235372544 18:28:13 INFO - PROCESS | 1661 | 18:28:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 18:28:17 INFO - PROCESS | 1661 | RSS: Main: 294088704 18:28:17 INFO - PROCESS | 1661 | 18:28:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 18:28:20 INFO - PROCESS | 1661 | RSS: Main: 294682624 18:28:20 INFO - PROCESS | 1661 | 18:28:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 18:28:24 INFO - PROCESS | 1661 | RSS: Main: 384978944 18:28:24 INFO - PROCESS | 1661 | 18:28:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 18:28:27 INFO - PROCESS | 1661 | RSS: Main: 286633984 18:28:27 INFO - PROCESS | 1661 | 18:28:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:28:30 INFO - PROCESS | 1661 | RSS: Main: 290828288 18:28:30 INFO - PROCESS | 1661 | 18:28:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 18:28:33 INFO - PROCESS | 1661 | RSS: Main: 327651328 18:28:33 INFO - PROCESS | 1661 | 18:28:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 18:28:36 INFO - PROCESS | 1661 | RSS: Main: 329650176 18:28:36 INFO - PROCESS | 1661 | 18:28:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 18:28:40 INFO - PROCESS | 1661 | RSS: Main: 329269248 18:28:40 INFO - PROCESS | 1661 | 18:28:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:28:42 INFO - PROCESS | 1661 | RSS: Main: 301850624 18:28:42 INFO - PROCESS | 1661 | 18:28:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:28:45 INFO - PROCESS | 1661 | RSS: Main: 306110464 18:28:45 INFO - PROCESS | 1661 | 18:28:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:28:48 INFO - PROCESS | 1661 | RSS: Main: 314249216 18:28:48 INFO - PROCESS | 1661 | 18:28:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:28:51 INFO - PROCESS | 1661 | RSS: Main: 320966656 18:28:51 INFO - PROCESS | 1661 | 18:28:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:28:55 INFO - PROCESS | 1661 | RSS: Main: 320344064 18:28:55 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 18:28:57 INFO - PROCESS | 1661 | RSS: Main: 364380160 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | __start_tp_report 18:28:57 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 18:28:57 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 18:28:57 INFO - PROCESS | 1661 | |i|pagename|runs| 18:28:57 INFO - PROCESS | 1661 | |0;ai-astar;111;112;112;112;112;112;111;112;111;112 18:28:57 INFO - PROCESS | 1661 | |1;audio-beat-detection;147;114;115;151;114;116;112;112;142;113 18:28:57 INFO - PROCESS | 1661 | |2;audio-dft;185;184;183;184;184;182;184;181;182;182 18:28:57 INFO - PROCESS | 1661 | |3;audio-fft;81;59;59;87;62;58;59;76;103;80 18:28:57 INFO - PROCESS | 1661 | |4;audio-oscillator;82;71;90;92;89;99;129;70;67;88 18:28:57 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;86;89;86;88;86;89;86;88;85 18:28:57 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;96;94;93;93;99;93;94;93;93 18:28:57 INFO - PROCESS | 1661 | |7;imaging-desaturate;133;86;83;86;84;85;83;85;84;84 18:28:57 INFO - PROCESS | 1661 | |8;json-parse-financial;55;52;52;53;53;56;54;52;54;53 18:28:57 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;67;70;71;74;100;101;101;78;71;72 18:28:57 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;88;92;89;89;86;86;89;88;85;88 18:28:57 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;151;158;146;144;142;145;146;145;180;155 18:28:57 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;171;170;170;169;170;173;172;171;170;170 18:28:57 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;69;70;70;70;70;71;68;66;69;68 18:28:57 INFO - PROCESS | 1661 | __end_tp_report 18:28:57 INFO - PROCESS | 1661 | __start_cc_report 18:28:57 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2046 18:28:57 INFO - PROCESS | 1661 | __end_cc_report 18:28:57 INFO - PROCESS | 1661 | __startTimestamp1476235737320__endTimestamp 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | ------- Summary: start ------- 18:28:57 INFO - PROCESS | 1661 | Number of tests: 14 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:28:57 INFO - PROCESS | 1661 | Values: 111.0 112.0 112.0 112.0 112.0 112.0 111.0 112.0 111.0 112.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:115.50 stddev:16.11 (13.9%) stddev-sans-first:14.69 18:28:57 INFO - PROCESS | 1661 | Values: 147.0 114.0 115.0 151.0 114.0 116.0 112.0 112.0 142.0 113.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.29 (0.7%) stddev-sans-first:1.17 18:28:57 INFO - PROCESS | 1661 | Values: 185.0 184.0 183.0 184.0 184.0 182.0 184.0 181.0 182.0 182.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:72.40 Median:69.00 stddev:15.45 (22.4%) stddev-sans-first:16.07 18:28:57 INFO - PROCESS | 1661 | Values: 81.0 59.0 59.0 87.0 62.0 58.0 59.0 76.0 103.0 80.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:88.50 stddev:17.99 (20.3%) stddev-sans-first:18.96 18:28:57 INFO - PROCESS | 1661 | Values: 82.0 71.0 90.0 92.0 89.0 99.0 129.0 70.0 67.0 88.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.55 (1.8%) stddev-sans-first:1.50 18:28:57 INFO - PROCESS | 1661 | Values: 89.0 86.0 89.0 86.0 88.0 86.0 89.0 86.0 88.0 85.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.50 stddev:1.97 (2.1%) stddev-sans-first:2.05 18:28:57 INFO - PROCESS | 1661 | Values: 93.0 96.0 94.0 93.0 93.0 99.0 93.0 94.0 93.0 93.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:89.30 Median:84.50 stddev:15.39 (18.2%) stddev-sans-first:1.13 18:28:57 INFO - PROCESS | 1661 | Values: 133.0 86.0 83.0 86.0 84.0 85.0 83.0 85.0 84.0 84.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:1.35 (2.5%) stddev-sans-first:1.30 18:28:57 INFO - PROCESS | 1661 | Values: 55.0 52.0 52.0 53.0 53.0 56.0 54.0 52.0 54.0 53.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:71.00 stddev:14.20 (20.0%) stddev-sans-first:14.20 18:28:57 INFO - PROCESS | 1661 | Values: 67.0 70.0 71.0 74.0 100.0 101.0 101.0 78.0 71.0 72.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:88.50 stddev:2.00 (2.3%) stddev-sans-first:2.12 18:28:57 INFO - PROCESS | 1661 | Values: 88.0 92.0 89.0 89.0 86.0 86.0 89.0 88.0 85.0 88.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:148.50 stddev:11.34 (7.6%) stddev-sans-first:12.03 18:28:57 INFO - PROCESS | 1661 | Values: 151.0 158.0 146.0 144.0 142.0 145.0 146.0 145.0 180.0 155.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:170.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 18:28:57 INFO - PROCESS | 1661 | Values: 171.0 170.0 170.0 169.0 170.0 173.0 172.0 171.0 170.0 170.0 18:28:57 INFO - PROCESS | 1661 | 18:28:57 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:1.45 (2.1%) stddev-sans-first:1.54 18:28:57 INFO - PROCESS | 1661 | Values: 69.0 70.0 70.0 70.0 70.0 71.0 68.0 66.0 69.0 68.0 18:28:57 INFO - PROCESS | 1661 | -------- Summary: end -------- 18:28:57 INFO - PROCESS | 1661 | 18:28:58 INFO - TEST-INFO | 1661: exit 0 18:28:58 INFO - TEST-OK | kraken | took 59973ms 18:28:58 INFO - SUITE-END | took 1178s 18:28:58 INFO - Completed test suite (00:19:38) 18:28:58 INFO - javascript benchmark 18:28:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2867.1828528869264, 3281.8677556243097, 3313.801171929684, 3316.253842885351, 3321.4187517184487, 5380.704061276629, 5458.5087342139195, 5488.231647569343, 5557.555395568355, 5564.387917329095, 5474.178873169027, 5480.972595137028, 5552.7223638817995, 5563.165525171226, 5609.663420194781, 5006.774695138727, 5021.447640759532, 5062.291279220912, 5095.439501654815, 5099.4900509949075, 5089.770960306777, 5146.3309769729885, 5157.742112894359, 5164.044651739421, 5243.764030618612, 5144.717040562768, 5151.995360904625, 5154.38147422308, 5164.018836421087, 5220.738963051851, 4950.4801995790285, 5016.573591244749, 5031.924521132186, 5101.872453188663, 5105.0, 5023.171176755831, 5054.797808087672, 5054.974725126351, 5062.063518249136, 5102.107131252016, 5465.371482279549, 5557.5831812613815, 5561.440677966105, 5586.24585680936, 5621.381648018714, 5645.943540564624, 5675.829725108253, 5699.74351154199, 6027.78902738402, 6075.513958883279, 5107.489251074863, 6044.788432404889, 6075.605085669417, 6103.055501741071, 6163.2604087509335, 5634.4365563443735, 5635.943640563624, 5637.267155269788, 5650.406707295675, 5656.575756818255, 6041.335453100155, 6149.2620885494025, 6265.686715664198, 6318.462930650854, 6327.082573026977, 5810.593258471867, 5853.9414605854245, 5874.412558744091, 5898.4101589840675, 5900.350961394243, 6058.757649694051, 6064.36324185963, 6064.605800622945, 6068.362821903725, 6078.908816367758, 5842.977478941169, 5891.086881533369, 5902.498287645598, 5906.763729450774, 5925.970370148121, 5798.478136967693, 5881.05903055509, 5890.734916928748, 5991.910121348184, 5992.161097446361, 4788.688735232268, 4794.2808578712775, 4797.808087676523, 4810.7835147418455, 4812.494688057774, 4234.089670720809, 4278.187144442546, 4359.651227901761, 4372.562743725602, 4384.671149663788, 5516.979358818631, 5574.9442505574425, 5586.050371436865, 5589.888202235932, 5605.32736071674, 6040.365761595057, 6047.0324748040985, 6061.969690151609, 6067.211262535842, 6076.0, 5595.4124816894455, 5604.887902242014, 5609.80365687199, 5610.663360198401, 5611.663300202001, 5190.740462976836, 5201.973990130025, 5202.843914682566, 5257.263983042377, 5264.763085661154, 6330.746770129143, 6342.8731425370315, 6352.174217351715, 6375.0, 6383.585066970725, 5884.382139875252, 5962.493188079062, 5971.4327138921735, 6132.601380910227, 6150.7847225346895, 6229.906551401823, 6276.529260305404, 6285.340039295991, 6332.271788744353, 6350.618962862059, 6005.219321488178, 6007.639541627516, 6012.278526576839, 6023.656870436949, 6086.847828804315, 5911.438413350725, 5966.403359664172, 6138.877222455437, 6151.507879369729, 6158.0, 5782.566307527037, 5807.041838096667, 5813.3605303417435, 5840.853978650568, 5842.7662893485485, 4767.856964291077, 5853.414658534112, 5854.414558544111, 5860.853478663067, 5892.7642894283745, 5735.7418916148035, 5736.483716465371, 5740.942590574041, 5753.993051215937, 5770.13447982806, 5757.942420575908, 5830.737616807339, 5835.591508594358, 5840.8247752567495, 5882.764689412375, 5673.34756502991, 5737.08206686928, 5746.5402767779315, 5748.885022299447, 5755.079187330174], "value": 5527.812582155357, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.35589276530294, 488.6237597050271, 521.2311840035948, 524.035774175517, 524.1848924921748, 29997.400051998946, 30673.77304907801, 39402.605973940284, 40312.596874031326, 40568.391474127835, 41379.96550086249, 41752.58247417525, 41806.95482612936, 41820.79089604555, 41865.37201941966, 9407.294452916025, 9584.952075239615, 9614.278929080312, 9616.134547890688, 9630.614775408976, 8559.657613695445, 8801.735947921572, 11441.427928603578, 11645.184837061366, 11691.766164676701, 11582.652520424359, 11650.184487085908, 11672.949434550888, 11681.59114430995, 11716.238444501123, 11578.305301681927, 11627.651170464815, 11650.359230242333, 11651.708707282343, 11654.883451165464, 8428.115047919942, 8630.482171069756, 8640.22237998577, 8714.825703485958, 8742.737717868475, 35202.647973520194, 38023.80988095069, 38982.02544936371, 40734.0, 40737.0, 3303.752218583592, 3312.337532493511, 3318.0875259303884, 3432.5366075579977, 3756.6619004289473, 30532.08403747891, 30812.075637730904, 31141.8442907784, 31241.37517249665, 31583.42082895846, 3175.7300629446527, 3178.3802158579088, 3235.5793746813, 3324.8670053197843, 3327.1848397142853, 457826.0, 459712.9999999967, 463808.0, 465847.0, 473004.0, 50509.0, 52162.47837521576, 52507.0, 54704.0, 59166.40833591695, 60577.39422605806, 60649.09026364608, 62164.68917655383, 62997.0, 63008.68495657492, 15271.160086195143, 15407.845921540866, 15439.768403473958, 15441.382344706311, 15448.845511544965, 10809.189310801565, 11768.82346764782, 11865.228760130563, 11868.821967670492, 11884.227525210834, 14560.63598410027, 14997.850021499864, 16729.247183876756, 16826.747598786027, 17074.317027319015, 6352.809415717536, 6751.750926078691, 6906.407239185885, 7032.964835175791, 7104.6447677615915, 855.7176131876466, 991.2466525440717, 1008.8133695266399, 1043.415687215162, 1060.761328701036, 27050.91796328164, 28074.438511229662, 29239.56140657892, 29595.704042959725, 30574.84712576423, 1014.7919676466307, 1052.2318707343682, 1055.1664185293685, 1069.7539565899888, 1128.2440764687678, 12756.971554659282, 15113.47102851417, 15332.846671533365, 15407.306671199824, 15933.920330398274, 625266.0, 630577.0, 631012.0, 632228.0, 633341.0, 56465.43534564684, 56572.71713641405, 56610.43389566051, 56614.0, 56626.43373566212, 1211.3277131192096, 1241.522014024607, 1336.6457888659318, 1349.197227649547, 1367.0294091195362, 29865.25336866596, 30756.0, 30963.380732385678, 31587.0, 31879.362412752078, 48299.517004829504, 48990.265146022124, 49030.26454603255, 49062.01875962532, 49224.26163607477, 516482.0, 517460.6031517394, 517567.0, 517692.8230717795, 691384.0, 25830.483390332465, 26025.21924342273, 26084.73915260899, 26087.95636676318, 26184.73815261823, 45868.77065614651, 46424.0, 47450.76274618605, 47888.0, 47910.52089479061, 32596.185095372806, 37282.627173729, 39398.212035759694, 39812.0, 40137.0, 38091.619083809914, 38539.42190867083, 39518.60481395149, 39621.60378396179, 39691.80154099211, 36966.81516592507, 37215.813920930224, 37562.624373757004, 37608.811955940044, 38206.61793382031, 33240.1689957753, 33664.831675841466, 34128.829355853064, 34197.48703769396, 34348.14129646779, 25115.623265650665, 25133.49733005293, 25240.368990775376, 25281.62077568801, 25384.11155609545], "value": 19667.63807417691, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7309258417313, 373.88222709846394, 373.98130093495325, 380.8933498620386, 404.54286656078625, 14480.0, 14849.0, 15118.903827495391, 20377.981100944966, 20510.820822772494, 18388.172532236047, 19490.92799896005, 19797.634402151634, 20905.0, 21136.365909022716, 5861.648301101937, 5925.940740592582, 6168.259808822925, 6172.5370597205165, 6181.907271390933, 5947.286325640929, 6065.332813390523, 6110.786122485714, 6119.969400152993, 6122.265328160627, 5741.511971482429, 5974.79088231912, 6075.119107729377, 6075.908861367083, 6082.756689732405, 5985.072313791369, 6044.607100538451, 6051.515878729691, 6066.393360663942, 6081.513478921698, 5070.467600901889, 5158.40678321994, 5163.690178589297, 5239.580833533309, 5247.580193584504, 25420.49159016828, 25524.106656267126, 25532.361690957874, 25624.23127306184, 25897.928165746696, 2287.4853158039473, 3003.5344521599186, 3021.154076858461, 3028.37918226763, 3037.5747395364665, 14927.0, 15680.215989200495, 15725.606859828482, 15797.289121989537, 15937.760933586005, 1770.1768677564994, 1886.6328678113098, 1910.0927059646694, 1967.3409407848387, 1969.3993332033867, 119214.0, 119537.20825628415, 121778.0, 121900.32334038551, 123999.0, 10588.311759735592, 10689.679309620724, 10778.892211077946, 11028.779424411627, 11283.323000619988, 13799.655008624664, 13806.723865522834, 13819.930900345435, 13848.930755346159, 13852.021815309758, 8072.5963701815845, 8323.16768323175, 8351.498910065415, 8439.789005274795, 8474.74575762728, 6606.333484915822, 8329.66681332752, 8357.247847693738, 8382.622781974704, 8546.700865469678, 6383.744650214032, 6549.541532092708, 6566.0, 6573.683534676087, 6593.208814942238, 1513.28119143406, 1804.3684710351272, 1815.3101821307819, 1820.280989009334, 1825.2060353746162, 893.9329550283625, 904.5341649050744, 923.3121324613206, 944.2587568758629, 1007.0986467111894, 15882.047077175406, 20539.075741591365, 21916.561668766535, 21929.162396580854, 22228.777712222993, 912.1061359867367, 927.9257659387233, 944.6079876851168, 946.3281070439928, 963.643451922793, 7926.920730792734, 10661.466926653637, 10815.729606759736, 11608.30350178992, 11648.242864213798, 564968.0, 582244.0, 590094.0, 600039.0, 602525.0, 112499.0, 113972.0, 115975.2603710945, 124935.0, 134840.32579837038, 948.170350942925, 978.1538968791943, 991.7322322972901, 992.2409356842022, 1011.9595216191417, 19186.904065479583, 19513.51216219549, 19699.704504432157, 19881.403557893285, 19932.90033549823, 41757.999999998785, 55073.0, 56225.43774562202, 56538.86922261614, 57142.42857571371, 80724.59637701773, 80727.59636202017, 81978.59010704908, 83984.16015839763, 84079.0, 7665.578393188428, 7872.291493765589, 8098.919010809816, 8132.308753755997, 8323.83352332962, 10935.781284374427, 12610.810837837624, 12848.164869283466, 12876.549320773727, 13015.739685206432, 11145.88854111481, 11177.385243811343, 11286.266392684449, 11410.258333208314, 11480.425978700865, 10785.298955568176, 10973.451327433757, 11099.278546894426, 11379.260348077349, 11551.88448115508, 11122.61070862516, 11233.606823761129, 11339.773204536028, 11470.598529051444, 11624.825627615422, 7812.88280675779, 7944.761657150295, 8050.5572193529915, 8110.472819266729, 8202.26179643835, 8177.427580069338, 10637.680869573926, 10767.676969690921, 10931.234813562975, 11141.220114592224], "value": 9726.599013779927, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.8272524571805, 1471.8013068235791, 1494.1259363272486, 1657.8590819780325, 1845.9446216613499, 7624.23757624238, 8130.756077317676, 8344.2907352875, 8685.86971195431, 8758.649654013832, 8580.785480362994, 8648.264897483707, 8652.826943461143, 8655.307575393968, 8661.870071948926, 2309.145616122029, 2333.7782910623464, 2337.929862104131, 2343.5078633486987, 2352.2707960532202, 2285.7828506291958, 2316.328264803205, 2334.7781960713705, 2336.0538981712434, 2336.19401306549, 2297.4026753044163, 2322.7096612923383, 2332.47519308156, 2344.812415006795, 2345.026813872241, 2272.5909336319455, 2299.321700098475, 2309.988450057739, 2311.699479067727, 2319.9999999999914, 2283.4633861042644, 2292.64464008079, 2299.7815207555423, 2302.5164715409783, 2303.0442366417915, 8524.701635442792, 8568.357373197035, 8622.784430389227, 8625.525596092213, 8627.007894092134, 4695.5304469552775, 5032.6477146599755, 5106.285120083191, 5226.477352264781, 5228.4510126436935, 3183.458812001942, 3346.748993825473, 3577.4454959481322, 3649.489071529988, 3649.835757390897, 1037.0, 1069.9679009629724, 1070.4273213830563, 1094.458243169636, 1096.5065720425841, 11430.542778288858, 11433.828492572618, 11529.192956493049, 11554.53781848725, 11561.190716649753, 4028.7582745035393, 4047.1096358801015, 4266.0827921996915, 4317.266064768997, 4480.596746292849, 8867.379283450226, 8874.77813054666, 8879.556022198993, 8880.0, 8889.422187557788, 5115.104856650146, 5119.923201151985, 5122.513361230677, 5125.794968201305, 5132.1788513838355, 4263.488381394252, 4286.592773686557, 4445.711028783118, 4782.163121453732, 4816.638752093607, 3562.447820587813, 3590.7791350940806, 3702.7037836972977, 3706.777593344408, 3723.491985745777, 1128.9830652540218, 1137.113051819582, 1146.18620779246, 1152.4698638626157, 1157.346099453806, 732.5348403763542, 743.2418932688628, 801.9318357939641, 874.1695389380114, 909.9317551183688, 5568.52667523257, 5675.318961724537, 5787.2543322456595, 5918.674472904032, 5923.940760592425, 700.4676445901151, 718.4611541343992, 841.7600983719733, 871.1636828644572, 945.0879900895532, 3730.2166545025307, 3780.962190378061, 3790.2608991246993, 3905.2775236580756, 3962.405639154092, 10278.537465814057, 10739.979701928305, 10777.568897244179, 10795.190360722978, 10836.458177091114, 10320.43237621938, 10557.313774604627, 10712.625058122929, 10755.354678719303, 10764.838527422095, 8062.798430039179, 8219.342452603896, 8220.0, 8221.424500285042, 8227.8354432911, 5590.580706446952, 5641.548676105819, 5726.65640061581, 5742.454466825645, 5830.271216097988, 9657.565409556728, 9834.459104749307, 9883.159931405911, 9931.155851752683, 9954.352967057119, 9506.47714375716, 9949.502524873582, 9955.950220248853, 10073.043060909202, 10117.898821011695, 6859.657017149222, 7052.435805135474, 7382.114146302478, 7539.962300188464, 7547.7735667927855, 7742.0, 7992.480488768444, 8111.594420278844, 8263.297619702393, 8382.036065852504, 7320.487565870338, 7382.0, 7421.925780742124, 7537.321641052332, 7582.658780354787, 6221.782237621662, 7279.308465695751, 7302.96348518254, 7329.633518324169, 7362.5214361066355, 6691.230508491586, 6841.760538381132, 6905.896411553931, 6911.861762764616, 6937.861242775217, 4683.555062269079, 4714.66997310185, 4737.502562231054, 4774.594159496482, 4887.951120488749, 4719.764011799465, 4768.237082066853, 4846.660733748745, 4882.6826256293225, 4968.701877887338], "value": 4432.748925737183, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.85702862625925, 299.53871038600556, 308.60960884481136, 312.7154289596468, 315.67800843139986, 4728.976355118229, 4883.096627123985, 5190.844274671761, 5209.7134657593815, 5226.8693282667955, 4226.372846454115, 4432.756198409094, 4545.340925565799, 4813.590844778198, 5044.445111037783, 3584.211473475831, 3779.130799916021, 4006.3389540725743, 4018.176273863851, 4020.8994775130564, 3374.0721301641997, 3786.148116673754, 3808.504894363729, 3924.2347742190286, 3984.6812255019527, 3439.6044454887633, 3744.700423966076, 3836.0601652595155, 3941.9211615767667, 3965.8611948581806, 3454.6372630873775, 3911.242573985348, 3939.231849789292, 3958.3270843956598, 3976.324024915771, 3010.3377257003426, 3144.559761633373, 3425.6060553036473, 3433.0215888471917, 3447.913802154941, 4397.494288156871, 4544.431946006749, 4613.685099746556, 5087.49125087492, 5095.235714642796, 3630.8366123524506, 4495.730256184639, 4520.547945205486, 4527.4340707411575, 4541.477730061019, 4645.65157613177, 5429.0, 5501.559875209973, 5505.0, 5516.91724624131, 3284.5730055092686, 3424.6404127566498, 3735.82321568705, 3739.775613463201, 3752.512173417438, 5468.781248750045, 5689.829305120853, 5704.34400043992, 5763.827085187451, 5766.94233057664, 3630.146915474861, 3648.21563363878, 3787.4697542344093, 3810.2950954073585, 3817.809109544567, 3245.626752923444, 3254.3491301739746, 3891.63792672568, 3893.377059670439, 3900.1419687668667, 3804.097951024488, 4330.501992270866, 4536.77316134192, 4567.611753001032, 4577.496475387705, 4460.397846290774, 5491.807786727525, 5509.539971907448, 5529.861753456115, 5647.350554686263, 1031.803108394274, 1259.2696236182992, 1289.607224197864, 1312.2716892124963, 1314.3165553912104, 696.5437638346891, 705.7071315404049, 812.1959260332228, 872.9607167677469, 877.8902637170354, 5122.513361230677, 5154.252633368215, 5157.767900444489, 5159.658488792866, 5187.662801917863, 4742.264948932921, 5266.4470230626, 5514.6691198528215, 5516.420775818561, 5529.972350138223, 4289.048482940317, 4826.131296366688, 5093.414257360451, 5145.4597267287145, 5177.689338639693, 2795.329121010943, 2858.7284208000206, 2891.209982367026, 2924.9707502925125, 2949.8672559734864, 4990.50094990498, 5015.724135172601, 5140.511651393112, 5634.7182640867795, 5745.68398738065, 5000.724960127228, 5404.919016196698, 5663.716814159276, 5685.857853553612, 5686.914696279559, 5257.369115706063, 5704.372519022904, 5749.683767392667, 5751.814310562043, 5765.769369225268, 4413.58070983256, 5025.0, 5476.58925580591, 5491.341039075336, 5602.803901863415, 4553.271476563734, 4655.813767449264, 5358.767483478744, 5450.809221677381, 5489.85895500747, 4818.98801251727, 5355.714628489212, 5439.857629897766, 5707.486326230659, 5721.4812890073135, 3143.9528407073453, 3148.811575954781, 3458.8616455341503, 3464.5149679045458, 3685.4840322355426, 4563.498015218257, 5132.461091585479, 5514.889702206014, 5529.700520377628, 5544.916826247691, 4378.715383500063, 4676.92984605238, 4786.641001924939, 5109.74451277442, 5118.539331460186, 5078.898422031465, 5347.893042139213, 5371.167469042305, 5402.135658294654, 5443.591730620298, 5095.235714642833, 5271.472852714698, 5277.8416647500635, 5284.392294886137, 5308.814191503279, 4961.900761984813, 5046.889684269455, 5425.620206585659, 5535.8892822144135, 5547.472990065938, 4995.525425084611, 5141.305923700402, 5381.408045114956, 5440.483154100354, 5445.727713614383], "value": 3913.4418963650123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.6971037309143, 752.243994785241, 756.3684323589803, 794.9244821741933, 802.5866678660489, 15469.84530154698, 15830.92084539577, 15838.76241856373, 15869.444569440071, 15870.682586348266, 14150.221737804417, 14234.71530569388, 14246.216458094827, 14250.144991300529, 14552.272386380691, 671.5097978475716, 689.1764341611776, 815.9143289954559, 816.020775069917, 842.6039761312173, 827.9254867061964, 829.4152622401193, 831.2726364431123, 835.9884539706962, 836.2056046755606, 883.7127933421664, 887.6227603268617, 887.8135591525787, 888.2893685051965, 890.0031964200105, 875.5928493250669, 878.3412440669498, 881.4887365328126, 881.6209030117047, 884.8097659003281, 516.7622893469008, 530.7478947499945, 641.7144370755016, 655.2922843329193, 656.3961155736686, 14863.925680371636, 14900.0314979526, 14904.105753654816, 14915.552533424014, 14917.0, 13500.864991350158, 13940.651483712887, 14087.225202613852, 14087.71824563503, 14180.22008789516, 12127.471672130216, 12535.435905384189, 12585.74828503434, 12586.0, 12979.286139262336, 4461.486929003141, 4472.731636101845, 4554.43069616298, 4667.393238878958, 4688.882777930579, 95403.52298238465, 96849.51575242079, 116577.0, 118999.0, 123411.0, 11407.48666310018, 11631.53473861053, 11714.180007399571, 11722.648320550397, 12189.390530473442, 23902.282931512083, 24136.87931560366, 24186.758132418803, 24248.51502969966, 24296.392590185034, 8012.1186669466315, 8187.26314631686, 8487.193716596914, 8487.745367638981, 8527.232549070613, 3909.5113110861143, 4177.415161877339, 4300.3549467580115, 4372.344148377705, 4438.955610443919, 3653.1780349421697, 3776.376897811885, 3790.1093243087853, 3943.7830919299713, 4159.896002599899, 709.4005565297316, 712.9928700713031, 731.3673672273552, 737.3474475089478, 739.3752279323962, 560.4479587606205, 614.6527212125135, 641.810665853565, 643.3341491556217, 655.2693746472648, 9265.490398028172, 9295.907040929504, 9503.429794212369, 9517.762055948657, 9525.237980961507, 551.3879593651044, 609.8597322615823, 629.3234772619452, 634.9903653191448, 636.5766765101156, 4414.580614841585, 4431.667624928144, 4623.722576645345, 4706.976465117721, 4809.206480930678, 159413.0, 170743.29256707523, 171756.0, 172019.13990429966, 178872.7465429486, 120044.0, 144946.5505344954, 145653.27173364066, 147190.0, 149315.69798805375, 122495.0, 122853.38573307254, 123655.0, 124830.75169248373, 125246.37376813058, 8479.279261262862, 8779.297656187362, 8832.072632373509, 8899.599518021834, 9192.218661413588, 64292.03561946481, 67210.99183512153, 67445.66277168778, 68048.3195168042, 68917.65541172262, 90038.89649931538, 101139.0, 102625.78993680637, 104028.47985760022, 108788.4560577192, 11845.170838041255, 11898.0, 12059.397030148633, 12153.939230303791, 12314.445849936596, 11829.994450471806, 13314.93342533281, 14608.196549189897, 14650.413983440969, 14758.48345307909, 13333.933330333286, 13659.317034148451, 13679.04246702761, 14226.573202803933, 14294.71410571762, 13938.512152074629, 14057.22685252321, 14264.358103885143, 14270.643233919234, 14709.117452952856, 10996.395198264172, 13438.529651462151, 13531.729365412833, 13657.180569165881, 13915.886729061853, 10842.783144337227, 11012.39431831257, 11135.944320278346, 11410.82883756761, 11441.542338306375, 10862.837057443987, 11200.846024532482, 11479.483423246142, 11545.307281562797, 11601.419929003347], "value": 7991.417357675818, "unit": "score"}], "name": "dromaeo_css", "value": 7261.539148758402, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0, 111.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 115.0, 151.0, 114.0, 116.0, 112.0, 112.0, 142.0, 113.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 184.0, 184.0, 182.0, 184.0, 181.0, 182.0, 182.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 87.0, 62.0, 58.0, 59.0, 76.0, 103.0, 80.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 90.0, 92.0, 89.0, 99.0, 129.0, 70.0, 67.0, 88.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 88.0, 86.0, 89.0, 86.0, 88.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 94.0, 93.0, 93.0, 99.0, 93.0, 94.0, 93.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 86.0, 83.0, 86.0, 84.0, 85.0, 83.0, 85.0, 84.0, 84.0], "value": 89.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 52.0, 53.0, 53.0, 56.0, 54.0, 52.0, 54.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 71.0, 74.0, 100.0, 101.0, 101.0, 78.0, 71.0, 72.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 89.0, 89.0, 86.0, 86.0, 89.0, 88.0, 85.0, 88.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 158.0, 146.0, 144.0, 142.0, 145.0, 146.0, 145.0, 180.0, 155.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 170.0, 169.0, 170.0, 173.0, 172.0, 171.0, 170.0, 170.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 70.0, 70.0, 71.0, 68.0, 66.0, 69.0, 68.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1461.8999999999999, "alertThreshold": 2.0}]} 18:28:58 INFO - Return code: 0 18:28:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:28:58 INFO - # TBPL SUCCESS # 18:28:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:58 INFO - 2016-10-11 18:28:58.625 screenresolution[1665:9789] starting screenresolution argv=screenresolution get 18:28:58 INFO - 2016-10-11 18:28:58.628 screenresolution[1665:9789] Display 0: 1600x1200x32@60 18:28:58 INFO - 2016-10-11 18:28:58.634 screenresolution[1666:9792] starting screenresolution argv=screenresolution list 18:28:58 INFO - Available Modes on Display 0 18:28:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:58 INFO - Intel Iris: 18:28:58 INFO - Chipset Model: Intel Iris 18:28:58 INFO - Type: GPU 18:28:58 INFO - Bus: Built-In 18:28:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:58 INFO - Vendor: Intel (0x8086) 18:28:58 INFO - Device ID: 0x0a2e 18:28:58 INFO - Revision ID: 0x0009 18:28:58 INFO - Displays: 18:28:58 INFO - Display: 18:28:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:58 INFO - Display Serial Number: 1600x1200 60 18:28:58 INFO - Main Display: Yes 18:28:58 INFO - Mirror: Off 18:28:58 INFO - Online: Yes 18:28:58 INFO - Rotation: Supported 18:28:58 INFO - Return code: 0 18:28:58 INFO - Running post-action listener: _resource_record_post_action 18:28:58 INFO - [mozharness: 2016-10-12 01:28:58.872837Z] Finished run-tests step (success) 18:28:58 INFO - Running post-run listener: _resource_record_post_run 18:28:58 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38302720; Write bytes: 707651072; Read time: 370; Write time: 3478 18:28:58 INFO - TinderboxPrint: CPU usage
53.5% 18:28:58 INFO - TinderboxPrint: I/O read bytes / time
38,302,720 / 370 18:28:58 INFO - TinderboxPrint: I/O write bytes / time
707,651,072 / 3,478 18:28:58 INFO - TinderboxPrint: CPU idle
2,259.7 (46.5%) 18:28:58 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 18:28:58 INFO - TinderboxPrint: CPU user
1,626.5 (33.5%) 18:28:58 INFO - TinderboxPrint: Swap in / out
445,419,520 / 0 18:28:58 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402792960; Write bytes: 389253120; Read time: 29709; Write time: 696 18:28:58 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31071232; Write bytes: 297328128; Read time: 287; Write time: 2736 18:28:59 INFO - Running post-run listener: _upload_blobber_files 18:28:59 INFO - Blob upload gear active. 18:28:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:28:59 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 18:28:59 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'] 18:28:59 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 18:28:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:28:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:28:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:29:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:00 INFO - (blobuploader) - INFO - Done attempting. 18:29:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:29:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:29:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:01 INFO - (blobuploader) - INFO - Done attempting. 18:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:29:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:04 INFO - (blobuploader) - INFO - Done attempting. 18:29:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:29:04 INFO - Return code: 0 18:29:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:29:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:29:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e92635ea6f69f228e51c3b0a9948e5f1620db5aeeb1c375b0866a8d403eec818659b2a9c757251a85d433a08f2160829dd1b5a21aef10321df3eaa5de2cfce8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5b289a30aaf13167f68ba5d1efb349c0a1bcc5d8f2d47c948d4c73b6ad918e629214a32d143010a8c672a64a63d38030ac2c08ebe779f3117f3ce23762d1249", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/34e1b07f92ad9de5ee6bbf974a8b6e92c9ebaf484018f19d843bb678ad908c8e854de65425d3feb0dfe94f9605ebc1dde55f80558b850bc667cc280ff347291f"} 18:29:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:29:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:29:04 INFO - Contents: 18:29:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e92635ea6f69f228e51c3b0a9948e5f1620db5aeeb1c375b0866a8d403eec818659b2a9c757251a85d433a08f2160829dd1b5a21aef10321df3eaa5de2cfce8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5b289a30aaf13167f68ba5d1efb349c0a1bcc5d8f2d47c948d4c73b6ad918e629214a32d143010a8c672a64a63d38030ac2c08ebe779f3117f3ce23762d1249", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/34e1b07f92ad9de5ee6bbf974a8b6e92c9ebaf484018f19d843bb678ad908c8e854de65425d3feb0dfe94f9605ebc1dde55f80558b850bc667cc280ff347291f"} 18:29:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:29:04 INFO - Copying logs to upload dir... 18:29:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:29:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.294369 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-11 18:29:04.632537) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 18:29:04.636027) ========= 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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/e92635ea6f69f228e51c3b0a9948e5f1620db5aeeb1c375b0866a8d403eec818659b2a9c757251a85d433a08f2160829dd1b5a21aef10321df3eaa5de2cfce8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5b289a30aaf13167f68ba5d1efb349c0a1bcc5d8f2d47c948d4c73b6ad918e629214a32d143010a8c672a64a63d38030ac2c08ebe779f3117f3ce23762d1249", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/34e1b07f92ad9de5ee6bbf974a8b6e92c9ebaf484018f19d843bb678ad908c8e854de65425d3feb0dfe94f9605ebc1dde55f80558b850bc667cc280ff347291f"} build_url:https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/VKJvcQAoSge6V27fZrUETQ/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/e92635ea6f69f228e51c3b0a9948e5f1620db5aeeb1c375b0866a8d403eec818659b2a9c757251a85d433a08f2160829dd1b5a21aef10321df3eaa5de2cfce8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5b289a30aaf13167f68ba5d1efb349c0a1bcc5d8f2d47c948d4c73b6ad918e629214a32d143010a8c672a64a63d38030ac2c08ebe779f3117f3ce23762d1249", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/34e1b07f92ad9de5ee6bbf974a8b6e92c9ebaf484018f19d843bb678ad908c8e854de65425d3feb0dfe94f9605ebc1dde55f80558b850bc667cc280ff347291f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 18:29:04.668850) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 18:29:04.669141) ========= 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.bUvxUxDdg4/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.5OAHeBq3j6/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.004959 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 18:29:04.721002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 18:29:04.721856) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 18:29:04.722648) ========= ========= Total master_lag: 0.21 =========