builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0291 starttime: 1475164196.03 results: success (0) buildid: 20160929063119 builduid: d3516f8196034f159c168a8911aebccd revision: d271311721bbfad7128b6ce73e23569059deae03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.032995) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.033500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.033791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.059829) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.060258) ========= 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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-29 08:49:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.4M=0.001s 2016-09-29 08:49:56 (14.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.390927 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.476433) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.476786) ========= 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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.021699 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:56.525778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 08:49:56.529152) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d271311721bbfad7128b6ce73e23569059deae03 --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 d271311721bbfad7128b6ce73e23569059deae03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n4D2uPQ1VX/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.DInOcMYwoc/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-29 08:49:56,611 truncating revision to first 12 chars 2016-09-29 08:49:56,611 Setting DEBUG logging. 2016-09-29 08:49:56,611 attempt 1/10 2016-09-29 08:49:56,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d271311721bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 08:49:57,713 unpacking tar archive at: autoland-d271311721bb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 08:49:57.938207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:57.938516) ========= script_repo_revision: d271311721bbfad7128b6ce73e23569059deae03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:57.938930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:57.939199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 08:49:57.957150) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-29 08:49:57.957452) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n4D2uPQ1VX/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.DInOcMYwoc/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:49:58 INFO - MultiFileLogger online at 20160929 08:49:58 in /builds/slave/test 08:49:58 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 08:49:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:58 INFO - {'append_to_log': False, 08:49:58 INFO - 'base_work_dir': '/builds/slave/test', 08:49:58 INFO - 'blob_upload_branch': 'Autoland', 08:49:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:58 INFO - 'branch': 'Autoland', 08:49:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:58 INFO - 'config_files': ('talos/mac_config.py',), 08:49:58 INFO - 'default_actions': ('clobber', 08:49:58 INFO - 'read-buildbot-config', 08:49:58 INFO - 'download-and-extract', 08:49:58 INFO - 'populate-webroot', 08:49:58 INFO - 'create-virtualenv', 08:49:58 INFO - 'install', 08:49:58 INFO - 'run-tests'), 08:49:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:58 INFO - 'download_minidump_stackwalk': True, 08:49:58 INFO - 'download_symbols': 'ondemand', 08:49:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:58 INFO - 'installer_path': 'installer.exe', 08:49:58 INFO - 'log_level': 'info', 08:49:58 INFO - 'log_name': 'talos', 08:49:58 INFO - 'log_to_console': True, 08:49:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:49:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:49:58 INFO - 'opt_config_files': (), 08:49:58 INFO - 'pip_index': False, 08:49:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:58 INFO - 'cmd': ('bash', 08:49:58 INFO - '-c', 08:49:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:58 INFO - 'enabled': True, 08:49:58 INFO - 'halt_on_failure': False, 08:49:58 INFO - 'name': 'check_screen_resolution'},), 08:49:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:58 INFO - 'cmd': ('bash', 08:49:58 INFO - '-c', 08:49:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:58 INFO - 'enabled': True, 08:49:58 INFO - 'halt_on_failure': False, 08:49:58 INFO - 'name': 'check_screen_resolution'},), 08:49:58 INFO - 'run_cmd_checks_enabled': True, 08:49:58 INFO - 'sps_profile': False, 08:49:58 INFO - 'sps_profile_interval': 0, 08:49:58 INFO - 'suite': 'dromaeojs', 08:49:58 INFO - 'system_bits': '32', 08:49:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:58 INFO - 'title': 't-yosemite-r7-0291', 08:49:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:58 INFO - 'use_talos_json': True, 08:49:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:58 INFO - 'work_dir': 'build'} 08:49:58 INFO - [mozharness: 2016-09-29 15:49:58.171442Z] Running clobber step. 08:49:58 INFO - Running pre-action listener: _resource_record_pre_action 08:49:58 INFO - Running main action method: clobber 08:49:58 INFO - rmtree: /builds/slave/test/build 08:49:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:59 INFO - Running post-action listener: _resource_record_post_action 08:49:59 INFO - [mozharness: 2016-09-29 15:49:59.753701Z] Finished clobber step (success) 08:49:59 INFO - [mozharness: 2016-09-29 15:49:59.753861Z] Running read-buildbot-config step. 08:49:59 INFO - Running pre-action listener: _resource_record_pre_action 08:49:59 INFO - Running main action method: read_buildbot_config 08:49:59 INFO - Using buildbot properties: 08:49:59 INFO - { 08:49:59 INFO - "project": "", 08:49:59 INFO - "product": "firefox", 08:49:59 INFO - "script_repo_revision": "production", 08:49:59 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 08:49:59 INFO - "repository": "", 08:49:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 08:49:59 INFO - "buildid": "20160929063119", 08:49:59 INFO - "pgo_build": "False", 08:49:59 INFO - "basedir": "/builds/slave/test", 08:49:59 INFO - "buildnumber": 316, 08:49:59 INFO - "slavename": "t-yosemite-r7-0291", 08:49:59 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:49:59 INFO - "platform": "yosemite_r7", 08:49:59 INFO - "scheduler": "tests-autoland-macosx64-talos", 08:49:59 INFO - "branch": "autoland", 08:49:59 INFO - "repo_path": "integration/autoland", 08:49:59 INFO - "revision": "d271311721bbfad7128b6ce73e23569059deae03", 08:49:59 INFO - "stage_platform": "macosx64", 08:49:59 INFO - "builduid": "d3516f8196034f159c168a8911aebccd", 08:49:59 INFO - "slavebuilddir": "test" 08:49:59 INFO - } 08:49:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:49:59 INFO - Running post-action listener: _resource_record_post_action 08:49:59 INFO - [mozharness: 2016-09-29 15:49:59.757074Z] Finished read-buildbot-config step (success) 08:49:59 INFO - [mozharness: 2016-09-29 15:49:59.757182Z] Running download-and-extract step. 08:49:59 INFO - Running pre-action listener: _resource_record_pre_action 08:49:59 INFO - Running main action method: download_and_extract 08:49:59 INFO - mkdir: /builds/slave/test/build/tests 08:49:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:59 INFO - trying https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:49:59 INFO - Downloading https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/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 08:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/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 08:50:00 INFO - Downloaded 1199 bytes. 08:50:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:50:00 INFO - Using the following test package requirements: 08:50:00 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:50:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:50:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:50:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'jsshell-mac.zip'], 08:50:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:50:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:50:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:50:00 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:50:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:50:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:50:00 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:50:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:50:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:50:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:50:00 INFO - Fetch https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:50:01 INFO - Http code: 200 08:50:01 INFO - Content-Encoding: None 08:50:01 INFO - Content-Type: application/zip 08:50:01 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 08:50:01 INFO - x-amz-cf-id: 7YWz1I0OjzgDRN1r6SdqDubbqFbd7PjNwOwDAJsCS-FWoqh_ETcwmA== 08:50:01 INFO - x-amz-version-id: jLNaub_9Xe_0tFkddqR2ol8EMx9VTHUM 08:50:01 INFO - x-cache: Miss from cloudfront 08:50:02 INFO - Expected file size: 16473572 08:50:02 INFO - Obtained file size: 16473572 08:50:09 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 08:50:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:50:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:50:09 INFO - Fetch https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:50:09 INFO - Http code: 200 08:50:09 INFO - Content-Encoding: None 08:50:09 INFO - Content-Type: application/zip 08:50:09 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 08:50:09 INFO - x-amz-cf-id: A1hAAVCe0Blv80MENYeHOJxN53YuYSTQH3xK3yer31taFLKmM_OJ9w== 08:50:09 INFO - x-amz-version-id: jLNaub_9Xe_0tFkddqR2ol8EMx9VTHUM 08:50:09 INFO - x-cache: Hit from cloudfront 08:50:10 INFO - Expected file size: 16473572 08:50:10 INFO - Obtained file size: 16473572 08:50:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:50:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:50:16 INFO - Fetch https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:50:17 INFO - Http code: 200 08:50:17 INFO - Content-Encoding: None 08:50:17 INFO - Content-Type: application/zip 08:50:17 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 08:50:17 INFO - x-amz-cf-id: EKuqUIcvX6Xuqv0upK5yaFYRMn80BJMGjLC5_OmJyPzz3yMFSoIqqw== 08:50:17 INFO - x-amz-version-id: CNGUYkWM8zzHAOvJPZdVNESfBYrvVZ97 08:50:17 INFO - x-cache: Miss from cloudfront 08:50:17 INFO - Expected file size: 14414067 08:50:17 INFO - Obtained file size: 14414067 08:50:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:18 INFO - trying https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:50:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:50:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/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 08:50:21 INFO - Downloaded 111802954 bytes. 08:50:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:50:21 INFO - mkdir: /builds/slave/test/properties 08:50:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:50:21 INFO - Writing to file /builds/slave/test/properties/build_url 08:50:21 INFO - Contents: 08:50:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:50:22 INFO - Running post-action listener: _resource_record_post_action 08:50:22 INFO - Running post-action listener: set_extra_try_arguments 08:50:22 INFO - [mozharness: 2016-09-29 15:50:22.590892Z] Finished download-and-extract step (success) 08:50:22 INFO - [mozharness: 2016-09-29 15:50:22.591025Z] Running populate-webroot step. 08:50:22 INFO - Running pre-action listener: _resource_record_pre_action 08:50:22 INFO - Running main action method: populate_webroot 08:50:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:50:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:50:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:50:22 INFO - '--tppagecycles', 08:50:22 INFO - '7'], 08:50:22 INFO - 'tests': ['tsvgm']}}, 08:50:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:50:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:50:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:50:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:50:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['damp', 'tps']}, 08:50:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'tests': ['damp', 'tps']}, 08:50:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['dromaeo_dom']}, 08:50:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:50:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['basic_compositor_video']}, 08:50:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:50:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['a11yr', 08:50:22 INFO - 'ts_paint', 08:50:22 INFO - 'tpaint', 08:50:22 INFO - 'sessionrestore', 08:50:22 INFO - 'sessionrestore_no_auto_restore', 08:50:22 INFO - 'tabpaint']}, 08:50:22 INFO - 'other-e10s': {'tests': ['a11yr', 08:50:22 INFO - 'ts_paint', 08:50:22 INFO - 'tpaint', 08:50:22 INFO - 'sessionrestore', 08:50:22 INFO - 'sessionrestore_no_auto_restore', 08:50:22 INFO - 'tabpaint']}, 08:50:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:50:22 INFO - 'ts_paint', 08:50:22 INFO - 'tpaint', 08:50:22 INFO - 'sessionrestore', 08:50:22 INFO - 'sessionrestore_no_auto_restore', 08:50:22 INFO - 'tabpaint']}, 08:50:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:50:22 INFO - 'ts_paint', 08:50:22 INFO - 'tpaint', 08:50:22 INFO - 'sessionrestore', 08:50:22 INFO - 'sessionrestore_no_auto_restore', 08:50:22 INFO - 'tabpaint']}, 08:50:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['a11yr', 08:50:22 INFO - 'ts_paint', 08:50:22 INFO - 'tpaint', 08:50:22 INFO - 'sessionrestore', 08:50:22 INFO - 'sessionrestore_no_auto_restore', 08:50:22 INFO - 'tabpaint']}, 08:50:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['a11yr', 08:50:22 INFO - 'ts_paint', 08:50:22 INFO - 'tpaint', 08:50:22 INFO - 'sessionrestore', 08:50:22 INFO - 'sessionrestore_no_auto_restore', 08:50:22 INFO - 'tabpaint']}, 08:50:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['tsvgx', 08:50:22 INFO - 'tsvgr_opacity', 08:50:22 INFO - 'tart', 08:50:22 INFO - 'tscrollx', 08:50:22 INFO - 'cart']}, 08:50:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:50:22 INFO - 'tsvgr_opacity', 08:50:22 INFO - 'tart', 08:50:22 INFO - 'tscrollx', 08:50:22 INFO - 'cart']}, 08:50:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'talos_options': ['--disable-e10s'], 08:50:22 INFO - 'tests': ['tp5o']}, 08:50:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'tests': ['tp5o']}, 08:50:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'talos_options': ['--disable-e10s', 08:50:22 INFO - '--xperf_path', 08:50:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:22 INFO - 'tests': ['tp5n']}, 08:50:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:22 INFO - 'talos_options': ['--xperf_path', 08:50:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:22 INFO - 'tests': ['tp5n']}}, 08:50:22 INFO - 'talos.zip': {'path': '', 08:50:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:50:22 INFO - Running post-action listener: _resource_record_post_action 08:50:22 INFO - [mozharness: 2016-09-29 15:50:22.609649Z] Finished populate-webroot step (success) 08:50:22 INFO - [mozharness: 2016-09-29 15:50:22.609765Z] Running create-virtualenv step. 08:50:22 INFO - Running pre-action listener: _resource_record_pre_action 08:50:22 INFO - Running main action method: create_virtualenv 08:50:22 INFO - Creating virtualenv /builds/slave/test/build/venv 08:50:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:50:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:50:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:50:22 INFO - Using real prefix '/tools/python27' 08:50:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:50:23 INFO - Installing distribute.............................................................................................................................................................................................done. 08:50:26 INFO - Installing pip.................done. 08:50:26 INFO - Return code: 0 08:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:50:26 INFO - Reading from file tmpfile_stdout 08:50:26 INFO - Output received: 08:50:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:50:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:26 INFO - 'HOME': '/Users/cltbld', 08:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:26 INFO - 'LOGNAME': 'cltbld', 08:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:26 INFO - 'MOZ_NO_REMOTE': '1', 08:50:26 INFO - 'NO_EM_RESTART': '1', 08:50:26 INFO - 'PAGER': '/bin/cat', 08:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:26 INFO - 'PWD': '/builds/slave/test', 08:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:26 INFO - 'SHELL': '/bin/bash', 08:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:26 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:26 INFO - 'USER': 'cltbld', 08:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:26 INFO - 'XPC_FLAGS': '0x0', 08:50:26 INFO - 'XPC_SERVICE_NAME': '0', 08:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:50:26 INFO - Cleaning up... 08:50:26 INFO - Return code: 0 08:50:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:26 INFO - 'HOME': '/Users/cltbld', 08:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:26 INFO - 'LOGNAME': 'cltbld', 08:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:26 INFO - 'MOZ_NO_REMOTE': '1', 08:50:26 INFO - 'NO_EM_RESTART': '1', 08:50:26 INFO - 'PAGER': '/bin/cat', 08:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:26 INFO - 'PWD': '/builds/slave/test', 08:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:26 INFO - 'SHELL': '/bin/bash', 08:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:26 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:26 INFO - 'USER': 'cltbld', 08:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:26 INFO - 'XPC_FLAGS': '0x0', 08:50:26 INFO - 'XPC_SERVICE_NAME': '0', 08:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:27 INFO - Downloading/unpacking psutil>=3.1.1 08:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:50:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:30 INFO - Installing collected packages: psutil 08:50:30 INFO - Running setup.py install for psutil 08:50:30 INFO - building 'psutil._psutil_osx' extension 08:50:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:50:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:50:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:50:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:50:31 INFO - building 'psutil._psutil_posix' extension 08:50:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:50:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:50:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:50:31 INFO - ^ 08:50:31 INFO - 1 warning generated. 08:50:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:50:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:31 INFO - Successfully installed psutil 08:50:31 INFO - Cleaning up... 08:50:31 INFO - Return code: 0 08:50:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:31 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:50:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:50:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:31 INFO - 'HOME': '/Users/cltbld', 08:50:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:31 INFO - 'LOGNAME': 'cltbld', 08:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:31 INFO - 'MOZ_NO_REMOTE': '1', 08:50:31 INFO - 'NO_EM_RESTART': '1', 08:50:31 INFO - 'PAGER': '/bin/cat', 08:50:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:31 INFO - 'PWD': '/builds/slave/test', 08:50:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:31 INFO - 'SHELL': '/bin/bash', 08:50:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:31 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:31 INFO - 'USER': 'cltbld', 08:50:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:31 INFO - 'XPC_FLAGS': '0x0', 08:50:31 INFO - 'XPC_SERVICE_NAME': '0', 08:50:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:50: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 08:50: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 08:50: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 08:50: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 08:50: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 08:50: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 08:50:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:50:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:50:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:50:34 INFO - Installing collected packages: mozsystemmonitor 08:50:34 INFO - Running setup.py install for mozsystemmonitor 08:50:34 INFO - Successfully installed mozsystemmonitor 08:50:34 INFO - Cleaning up... 08:50:34 INFO - Return code: 0 08:50:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:34 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:50:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:34 INFO - 'HOME': '/Users/cltbld', 08:50:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:34 INFO - 'LOGNAME': 'cltbld', 08:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:34 INFO - 'MOZ_NO_REMOTE': '1', 08:50:34 INFO - 'NO_EM_RESTART': '1', 08:50:34 INFO - 'PAGER': '/bin/cat', 08:50:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:34 INFO - 'PWD': '/builds/slave/test', 08:50:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:34 INFO - 'SHELL': '/bin/bash', 08:50:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:34 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:34 INFO - 'USER': 'cltbld', 08:50:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:34 INFO - 'XPC_FLAGS': '0x0', 08:50:34 INFO - 'XPC_SERVICE_NAME': '0', 08:50:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:50:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:50:37 INFO - warning: no files found matching '*.txt' 08:50:37 INFO - no previously-included directories found matching 'build' 08:50:37 INFO - no previously-included directories found matching 'dist' 08:50:37 INFO - no previously-included directories found matching '.git*' 08:50:37 INFO - Installing collected packages: jsonschema, functools32 08:50:37 INFO - Running setup.py install for functools32 08:50:37 INFO - warning: no files found matching '*.txt' 08:50:37 INFO - no previously-included directories found matching 'build' 08:50:37 INFO - no previously-included directories found matching 'dist' 08:50:37 INFO - no previously-included directories found matching '.git*' 08:50:37 INFO - Successfully installed jsonschema functools32 08:50:37 INFO - Cleaning up... 08:50:38 INFO - Return code: 0 08:50:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:50: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')]} 08:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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')]} 08:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:38 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:38 INFO - 'HOME': '/Users/cltbld', 08:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:38 INFO - 'LOGNAME': 'cltbld', 08:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:38 INFO - 'MOZ_NO_REMOTE': '1', 08:50:38 INFO - 'NO_EM_RESTART': '1', 08:50:38 INFO - 'PAGER': '/bin/cat', 08:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:38 INFO - 'PWD': '/builds/slave/test', 08:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:38 INFO - 'SHELL': '/bin/bash', 08:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:38 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:38 INFO - 'USER': 'cltbld', 08:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:38 INFO - 'XPC_FLAGS': '0x0', 08:50:38 INFO - 'XPC_SERVICE_NAME': '0', 08:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:50:38 INFO - Cleaning up... 08:50:38 INFO - Return code: 0 08:50:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:50: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')]} 08:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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')]} 08:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:38 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:38 INFO - 'HOME': '/Users/cltbld', 08:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:38 INFO - 'LOGNAME': 'cltbld', 08:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:38 INFO - 'MOZ_NO_REMOTE': '1', 08:50:38 INFO - 'NO_EM_RESTART': '1', 08:50:38 INFO - 'PAGER': '/bin/cat', 08:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:38 INFO - 'PWD': '/builds/slave/test', 08:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:38 INFO - 'SHELL': '/bin/bash', 08:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:38 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:38 INFO - 'USER': 'cltbld', 08:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:38 INFO - 'XPC_FLAGS': '0x0', 08:50:38 INFO - 'XPC_SERVICE_NAME': '0', 08:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:38 INFO - Downloading/unpacking blobuploader==1.2.4 08:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:41 INFO - Downloading blobuploader-1.2.4.tar.gz 08:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:50:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:50:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:42 INFO - Downloading docopt-0.6.1.tar.gz 08:50:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:50:42 INFO - Installing collected packages: blobuploader, requests, docopt 08:50:42 INFO - Running setup.py install for blobuploader 08:50:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:50:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:50:42 INFO - Running setup.py install for requests 08:50:42 INFO - Running setup.py install for docopt 08:50:43 INFO - Successfully installed blobuploader requests docopt 08:50:43 INFO - Cleaning up... 08:50:43 INFO - Return code: 0 08:50:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50: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')]} 08:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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')]} 08:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:50:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:43 INFO - 'HOME': '/Users/cltbld', 08:50:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:43 INFO - 'LOGNAME': 'cltbld', 08:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:43 INFO - 'MOZ_NO_REMOTE': '1', 08:50:43 INFO - 'NO_EM_RESTART': '1', 08:50:43 INFO - 'PAGER': '/bin/cat', 08:50:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:43 INFO - 'PWD': '/builds/slave/test', 08:50:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:43 INFO - 'SHELL': '/bin/bash', 08:50:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:43 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:43 INFO - 'USER': 'cltbld', 08:50:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:43 INFO - 'XPC_FLAGS': '0x0', 08:50:43 INFO - 'XPC_SERVICE_NAME': '0', 08:50:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:43 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-BAW4NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:43 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-lScT5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:43 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-J6tKdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:43 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Y7chiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:43 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-1M8bty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-8XnEwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-nxG4nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-CZEX6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-iITOR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-w_dFKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-5SLDRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-0FW13q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Q_KfpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Kuomv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:44 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-0pP2QN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:45 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-otpAUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:45 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-h6YY_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:50:45 INFO - Running setup.py install for manifestparser 08:50:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:50:45 INFO - Running setup.py install for mozcrash 08:50:45 INFO - Running setup.py install for mozdebug 08:50:45 INFO - Running setup.py install for mozdevice 08:50:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:50:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:50:45 INFO - Running setup.py install for mozfile 08:50:46 INFO - Running setup.py install for mozhttpd 08:50:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Running setup.py install for mozinfo 08:50:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Running setup.py install for mozInstall 08:50:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Running setup.py install for mozleak 08:50:46 INFO - Running setup.py install for mozlog 08:50:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Running setup.py install for moznetwork 08:50:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:50:46 INFO - Running setup.py install for mozprocess 08:50:47 INFO - Running setup.py install for mozprofile 08:50:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:50:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:50:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:50:47 INFO - Running setup.py install for mozrunner 08:50:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:50:47 INFO - Running setup.py install for mozscreenshot 08:50:47 INFO - Running setup.py install for moztest 08:50:47 INFO - Running setup.py install for mozversion 08:50:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:50:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:50:47 INFO - Cleaning up... 08:50:47 INFO - Return code: 0 08:50:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:47 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:50:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:47 INFO - 'HOME': '/Users/cltbld', 08:50:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:47 INFO - 'LOGNAME': 'cltbld', 08:50:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:47 INFO - 'MOZ_NO_REMOTE': '1', 08:50:47 INFO - 'NO_EM_RESTART': '1', 08:50:47 INFO - 'PAGER': '/bin/cat', 08:50:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:47 INFO - 'PWD': '/builds/slave/test', 08:50:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:47 INFO - 'SHELL': '/bin/bash', 08:50:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:47 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:47 INFO - 'USER': 'cltbld', 08:50:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:47 INFO - 'XPC_FLAGS': '0x0', 08:50:47 INFO - 'XPC_SERVICE_NAME': '0', 08:50:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-3g8GhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-1lh8R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:48 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)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-zfiFLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-F5XlEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-XcoGx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-0JSG4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-VDuCKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:48 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-hIGad4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-bj7uH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-IH3U9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-ChIrDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-KcEA6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-EZxwd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-Z_eTDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:49 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)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-f1YfHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-DE0G47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:49 INFO - Running setup.py (path:/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/pip-AqHyNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:50:49 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)) 08:50:49 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)) 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:50:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:50:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:52 INFO - Downloading blessings-1.6.tar.gz 08:50:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:50:52 INFO - Installing collected packages: blessings 08:50:52 INFO - Running setup.py install for blessings 08:50:52 INFO - Successfully installed blessings 08:50:52 INFO - Cleaning up... 08:50:52 INFO - Return code: 0 08:50:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:50:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:53 INFO - Reading from file tmpfile_stdout 08:50:53 INFO - Current package versions: 08:50:53 INFO - blessings == 1.6 08:50:53 INFO - blobuploader == 1.2.4 08:50:53 INFO - docopt == 0.6.1 08:50:53 INFO - functools32 == 3.2.3-2 08:50:53 INFO - jsonschema == 2.5.1 08:50:53 INFO - manifestparser == 1.1 08:50:53 INFO - mozInstall == 1.12 08:50:53 INFO - mozcrash == 0.17 08:50:53 INFO - mozdebug == 0.1 08:50:53 INFO - mozdevice == 0.48 08:50:53 INFO - mozfile == 1.2 08:50:53 INFO - mozhttpd == 0.7 08:50:53 INFO - mozinfo == 0.9 08:50:53 INFO - mozleak == 0.1 08:50:53 INFO - mozlog == 3.4 08:50:53 INFO - moznetwork == 0.27 08:50:53 INFO - mozprocess == 0.23 08:50:53 INFO - mozprofile == 0.28 08:50:53 INFO - mozrunner == 6.12 08:50:53 INFO - mozscreenshot == 0.1 08:50:53 INFO - mozsystemmonitor == 0.3 08:50:53 INFO - moztest == 0.7 08:50:53 INFO - mozversion == 1.4 08:50:53 INFO - psutil == 3.1.1 08:50:53 INFO - requests == 1.2.3 08:50:53 INFO - wsgiref == 0.1.2 08:50:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:54 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:54 INFO - 'HOME': '/Users/cltbld', 08:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:54 INFO - 'LOGNAME': 'cltbld', 08:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:54 INFO - 'MOZ_NO_REMOTE': '1', 08:50:54 INFO - 'NO_EM_RESTART': '1', 08:50:54 INFO - 'PAGER': '/bin/cat', 08:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:54 INFO - 'PWD': '/builds/slave/test', 08:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:54 INFO - 'SHELL': '/bin/bash', 08:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:54 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:54 INFO - 'USER': 'cltbld', 08:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:54 INFO - 'XPC_FLAGS': '0x0', 08:50:54 INFO - 'XPC_SERVICE_NAME': '0', 08:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:50:54 INFO - Cleaning up... 08:50:54 INFO - Return code: 0 08:50:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:54 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 0x107506490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff028c8bbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, '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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:54 INFO - 'HOME': '/Users/cltbld', 08:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:54 INFO - 'LOGNAME': 'cltbld', 08:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:54 INFO - 'MOZ_NO_REMOTE': '1', 08:50:54 INFO - 'NO_EM_RESTART': '1', 08:50:54 INFO - 'PAGER': '/bin/cat', 08:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:54 INFO - 'PWD': '/builds/slave/test', 08:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:54 INFO - 'SHELL': '/bin/bash', 08:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:50:54 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:54 INFO - 'USER': 'cltbld', 08:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:54 INFO - 'XPC_FLAGS': '0x0', 08:50:54 INFO - 'XPC_SERVICE_NAME': '0', 08:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:50:55 INFO - Cleaning up... 08:50:55 INFO - Return code: 0 08:50:55 INFO - Running post-action listener: _resource_record_post_action 08:50:55 INFO - Running post-action listener: _start_resource_monitoring 08:50:55 INFO - Starting resource monitoring. 08:50:55 INFO - [mozharness: 2016-09-29 15:50:55.086793Z] Finished create-virtualenv step (success) 08:50:55 INFO - [mozharness: 2016-09-29 15:50:55.087756Z] Running install step. 08:50:55 INFO - Running pre-action listener: _resource_record_pre_action 08:50:55 INFO - Running main action method: install 08:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:55 INFO - Reading from file tmpfile_stdout 08:50:55 INFO - Detecting whether we're running mozinstall >=1.0... 08:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:50:55 INFO - Reading from file tmpfile_stdout 08:50:55 INFO - Output received: 08:50:55 INFO - Usage: mozinstall [options] installer 08:50:55 INFO - Options: 08:50:55 INFO - -h, --help show this help message and exit 08:50:55 INFO - -d DEST, --destination=DEST 08:50:55 INFO - Directory to install application into. [default: 08:50:55 INFO - "/builds/slave/test"] 08:50:55 INFO - --app=APP Application being installed. [default: firefox] 08:50:55 INFO - mkdir: /builds/slave/test/build/application 08:50:55 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'] 08:50:55 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 08:51:29 INFO - Reading from file tmpfile_stdout 08:51:29 INFO - Output received: 08:51:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:51:29 INFO - Running post-action listener: _resource_record_post_action 08:51:29 INFO - [mozharness: 2016-09-29 15:51:29.695583Z] Finished install step (success) 08:51:29 INFO - [mozharness: 2016-09-29 15:51:29.695712Z] Running run-tests step. 08:51:29 INFO - Running pre-action listener: _resource_record_pre_action 08:51:29 INFO - Running main action method: run_tests 08:51:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:29 INFO - 2016-09-29 08:51:29.710 screenresolution[2550:24391] starting screenresolution argv=screenresolution get 08:51:29 INFO - 2016-09-29 08:51:29.713 screenresolution[2550:24391] Display 0: 1600x1200x32@60 08:51:29 INFO - 2016-09-29 08:51:29.718 screenresolution[2551:24393] starting screenresolution argv=screenresolution list 08:51:29 INFO - Available Modes on Display 0 08:51:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:29 INFO - Intel Iris: 08:51:29 INFO - Chipset Model: Intel Iris 08:51:29 INFO - Type: GPU 08:51:29 INFO - Bus: Built-In 08:51:29 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:29 INFO - Vendor: Intel (0x8086) 08:51:29 INFO - Device ID: 0x0a2e 08:51:29 INFO - Revision ID: 0x0009 08:51:29 INFO - Displays: 08:51:29 INFO - Display: 08:51:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:29 INFO - Display Serial Number: 1600x1200 60 08:51:29 INFO - Main Display: Yes 08:51:29 INFO - Mirror: Off 08:51:29 INFO - Online: Yes 08:51:29 INFO - Rotation: Supported 08:51:29 INFO - Return code: 0 08:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:29 INFO - Python 2.7.3 08:51:29 INFO - Return code: 0 08:51:29 INFO - grabbing minidump binary from tooltool 08:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:29 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 0x10731ad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107312b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:51:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:51:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:51:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:30 INFO - Return code: 0 08:51:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:51:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:51:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:30 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/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:51:30 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/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0291 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:51:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n4D2uPQ1VX/Render', 08:51:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:30 INFO - 'HOME': '/Users/cltbld', 08:51:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:30 INFO - 'LOGNAME': 'cltbld', 08:51:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:30 INFO - 'MOZ_NO_REMOTE': '1', 08:51:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:30 INFO - 'NO_EM_RESTART': '1', 08:51:30 INFO - 'PAGER': '/bin/cat', 08:51:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:30 INFO - 'PWD': '/builds/slave/test', 08:51:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:30 INFO - 'SHELL': '/bin/bash', 08:51:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DInOcMYwoc/Listeners', 08:51:30 INFO - 'TMPDIR': '/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/', 08:51:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:30 INFO - 'USER': 'cltbld', 08:51:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:30 INFO - 'XPC_FLAGS': '0x0', 08:51:30 INFO - 'XPC_SERVICE_NAME': '0', 08:51:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:30 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/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0291', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:51:30 INFO - mozversion application_buildid: 20160929063119 08:51:30 INFO - mozversion application_changeset: d271311721bbfad7128b6ce73e23569059deae03 08:51:30 INFO - mozversion application_display_name: Nightly 08:51:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:30 INFO - mozversion application_name: Firefox 08:51:30 INFO - mozversion application_remotingname: firefox 08:51:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:51:30 INFO - mozversion application_vendor: Mozilla 08:51:30 INFO - mozversion application_version: 52.0a1 08:51:30 INFO - mozversion platform_buildid: 20160929063119 08:51:30 INFO - mozversion platform_changeset: d271311721bbfad7128b6ce73e23569059deae03 08:51:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:51:30 INFO - mozversion platform_version: 52.0a1 08:51:30 INFO - using testdate: 1475164290 08:51:30 INFO - actual date: 1475164290 08:51:30 INFO - starting webserver on 'localhost:52658' 08:51:30 INFO - SUITE-START | Running 2 tests 08:51:30 INFO - TEST-START | dromaeo_css 08:51:30 INFO - operating with platform_type : mac_ 08:51:30 INFO - Initialising browser for dromaeo_css test... 08:51:30 INFO - TEST-INFO | started process 2561 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmp1XAO4E/profile http://localhost:52658/getInfo.html) 08:51:32 INFO - PROCESS | 2561 | __metrics Screen width/height:1600/1200 08:51:32 INFO - PROCESS | 2561 | colorDepth:24 08:51:32 INFO - PROCESS | 2561 | Browser inner width/height: 1024/689 08:51:32 INFO - PROCESS | 2561 | __metrics 08:51:32 INFO - PROCESS | 2561 | 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" 08:51:32 INFO - PROCESS | 2561 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:33 INFO - TEST-INFO | 2561: exit 0 08:51:33 INFO - Browser initialized. 08:51:33 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:33 INFO - TEST-INFO | started process 2563 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmp1XAO4E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:45 INFO - PROCESS | 2563 | RSS: Main: 236298240 08:51:45 INFO - PROCESS | 2563 | 08:52:37 INFO - PROCESS | 2563 | 1475164357310 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] 08:54:48 INFO - PROCESS | 2563 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52658/tests/dromaeo/cssquery-ext.html) 08:54:50 INFO - PROCESS | 2563 | RSS: Main: 353943552 08:54:50 INFO - PROCESS | 2563 | 08:57:52 INFO - PROCESS | 2563 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-ext.html (next: http://localhost:52658/tests/dromaeo/cssquery-jquery.html) 08:57:53 INFO - PROCESS | 2563 | RSS: Main: 370884608 08:57:53 INFO - PROCESS | 2563 | 09:00:55 INFO - PROCESS | 2563 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52658/tests/dromaeo/cssquery-mootools.html) 09:00:56 INFO - PROCESS | 2563 | RSS: Main: 346906624 09:00:56 INFO - PROCESS | 2563 | 09:03:59 INFO - PROCESS | 2563 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52658/tests/dromaeo/cssquery-prototype.html) 09:03:59 INFO - PROCESS | 2563 | RSS: Main: 345845760 09:03:59 INFO - PROCESS | 2563 | 09:07:02 INFO - PROCESS | 2563 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52658/tests/dromaeo/cssquery-yui.html) 09:07:03 INFO - PROCESS | 2563 | RSS: Main: 328011776 09:07:03 INFO - PROCESS | 2563 | 09:10:06 INFO - PROCESS | 2563 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-yui.html (next: http://localhost:52658/tests/dromaeo/cssquery-dojo.html) 09:10:07 INFO - PROCESS | 2563 | RSS: Main: 450457600 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | __start_tp_report 09:10:07 INFO - PROCESS | 2563 | _x_x_mozilla_page_load 09:10:07 INFO - PROCESS | 2563 | _x_x_mozilla_page_load_details 09:10:07 INFO - PROCESS | 2563 | |i|pagename|runs| 09:10:07 INFO - PROCESS | 2563 | |0;dojo.html;3011.9698803011966;3276.999999999999;3306.966930330696;3308.553345298384;3350.497425386192;5553.250311207986;5596.16057591361;5667.0932650775885;5682.51698605618;5682.687452190133;5588.748506317215;5608.186812912136;5690.886182276362;5697.544196464283;5712.8857422851515;5010.574101201402;5013.3482647255805;5035.874103147414;5082.822101226458;5180.197069454241;5078.695278283296;5180.715060671661;5193.662411943212;5220.451852555484;5259.894802103956;5137.152369858969;5151.201563757623;5161.74191290434;5180.300659410969;5249.107651699221;5100.15847385181;5141.845744627667;5177;5210.452902445227;5218.60860435465;5059.3169922060415;5144.048351055067;5168.5865130789825;5188.662736922088;5191.610629202825;5573.41479144688;5589.636673616243;5607.327120745536;5629.549636029108;5654.03881340169;6133.294671112834;6167.321594624587;6192.195014648112;6199.504039676815;6200.286966998808;6070.514358851281;6131.356207598227;6141.170941922873;6258.968705156445;6260.060990851363;4770.594499467515;5650.067738823089;5702.914456283076;5706.686132262683;5708.143778433268;6251.999680051261;6274.529410294246;6285.308616052231;6291.402316779899;6292.653904035231;5905.852353691193;5985.9401405986255;6052.757889684452;6084.269887613515;6099.390060993954;6118.893302669218;6149.200603921461;6165.753369865155;6175;6229.626222426669;6126.989046807316;6133.707433238647;6140.17107690465;6154;6158.661273629904;6103.055501741115;6120.385176771874;6180.783672571439;6186.4741496973265;6196.597221180609;4087.182563487255;4834.589059929875;4868;4875.902481950341;4891.948231130253;4369.9126017479475;4385.649148068147;4408.625266852354;4421.845235416745;4478.395416618715;5676.829695109153;5699.857503562361;5700.116481945305;5707.286589176353;5713.74288157034;6025.487833534199;6138.970878344271;6140.289530736369;6215.471684906744;6225.595336303126;4694.976525117421;5682.602217844711;5720;5730.627509211888;5775.075987841925;5340.599455040888;5348.090824559834;5350.892982140335;5351.545118664957;5364.7049412282695;5087.592992560512;6221.688915554114;6287.779927702509;6307.716152773136;6341.112244285896;6116.366205733216;6126.670355029888;6165.321814600301;6194.876102478015;6219.875602487835;6285.088662144055;6312.14786003902;6323.241211054703;6328.620282782864;6339.366063393808;6108.236470441195;6116.204893364013;6132.478739307209;6134.38656134383;6152.969235153795;5950.375210602824;5995.070764031582;6123.618175568305;6130.092410195535;6168.938310617016;6074.969625151846;6088.238970128734;6089.421504957022;6096.329403765671;6131.478824299985;5897.764089436375;6074.271087469532;6078.7264573093435;6087.969560152171;6091.177691011657;5653.491185793298;5751;5817.069268916953;5817.70911454434;5875.970620146872;6104.7926496150985;6107.46191531438;6114.938850611437;6115.174451448997;6116.518381401779;5710.3433105193435;5914.02418600926;5919.082542205965;5926.051831707078;5982.940170598238 09:10:07 INFO - PROCESS | 2563 | |1;ext.html;448.64556999970006;461.213630759555;499.7551199912044;499.86530575593383;515.0934355534259;31036.06891793247;40729.59270407295;42439.575604243946;42517;43781;42654.786726066406;43116;43472.78263608686;43498.56501434985;43604.781976090075;9743.366681165737;9938.403695778241;9942.90057099427;9947.90052099477;9978.20174386051;9733.17268032218;9835.508224588777;9844.90155098447;9853.359531630456;9854.556544955494;9777.511124443785;9818.067283608047;9836.409815411098;9853.852192217122;9863.95068024659;9772.853407198898;9811.705648830546;9842.65550705729;9852.261080418926;9873.111419972201;9739.26955478342;9806.65676701312;9810.362326448758;9818.116369526777;9826.263030272758;41157.588424115675;41573;41604.37593436101;41642.37536436956;41763;3231.2083539532728;3259.608846938359;3289.3914625794064;3317.9004629861142;3330.9500357494658;30491.542626860613;30914.690853091408;31117.68882311193;31362.843185784153;32149.714011439515;3145.653978062411;3203.9199020024685;3219.7746157768966;3223.774335796495;3328.8169150696704;421545;480875;481044;481167;482566.5871670654;58522.53693657693;58819.41180588225;58876;58960;59021.40978590245;35099.64900351015;35377.938661840184;35806.10484737902;35864.28271434609;36842.8157859209;16451.83548164527;16675.49973500797;16684.582885427717;16705.83294167067;16737.497875063767;12723.618291451272;12836.679083022813;12842.614721558368;12847.550335738391;12853.228806271654;15582.922085389728;18762.718559221623;18922.716159257623;18964.715529267072;18998.335058272893;6342.936570634327;7292.781216563511;7355.448341374418;7421.183669796318;7497.775066748006;816.6325153680867;871.7471933139445;918.5590916360051;942.5993952570267;963.3160456076124;26840.731592684213;29566.852165739034;29808;30539.389212216076;30588.847055764578;845.3490812074791;967.9612815487443;1040.3965699894043;1082.2748758331936;1088.2654208409292;14605.342759575844;15181.544553663407;15429.225106933209;15454.38182472711;15550.76673849893;695371;699836.0081995879;702662;703702;705894.234759437;58146;58466.707666462244;58501;58602.706986465644;58670.70664646735;1191.0412118244897;1226.5093962414926;1234.530878266289;1319.1689235781396;1343.2813444807177;26832.597511036958;30817.69182308148;32047.35905281928;32068.03795886127;32377.190570235933;54436.45563544314;55080;55168;55229.17156242579;55318.44681553133;598325;600646;601777;602299;604386;26494.86752566225;26679;27007.379557226628;27280.908763650026;27358;45534.861628459556;46502.76748616235;47482.52517474781;48257;48298;38392.6160738389;38417.42373864338;38807.805960970014;39914;40125.39811902765;38107.809460952514;39129.41305880357;39197.82406527809;40313;41119.177616448105;33669.79127609049;33860.830695846365;38316.425253621775;38892.6110738889;39312.410313844746;27417;34119.4882076774;34854.12864678403;36463;36741.26517469582;24189.274321770376;24702.888370023753;25306.861191246808;25757.431949765756;25842.61236081498 09:10:07 INFO - PROCESS | 2563 | |2;jquery.html;336.8400009995251;369.88718440875533;375.3712531509719;381.1481339206873;403.8626866865265;14899.776503352445;15080.698386032273;16332.18339083044;20646.690299645517;20750.27374041909;16518.339266429357;19335.516612084706;19587.804121958776;20035.298764543244;20512;5928.940710592904;6189.350118237588;6193.628382297076;6199;6199.69001549923;5211.921821172686;6777.423918966893;6871.828204294882;6903;6913.861722765541;6547.541672082956;6716.093327400812;6802.013708012335;6841.281665425134;6865.897011544831;6651.600903945779;6734.360235777618;6784.389404953528;6787.5927444353;6788.524803263774;5023.196288593844;5810.012297909366;5860.70696465175;5868.8239352819055;5871.178035075093;24320.905559249877;24860.643813085182;24983.87508062466;25193.62209566858;25525.361865953313;2328.382978510696;2426.308502076917;2472.7279999200073;2944.5435957426635;2993.9550906736417;14498.927505362512;15095.245237738069;15342.923285383502;15378.76931846012;15793.605159871095;1877.6807942649782;1897.8007309232607;1920.0879582198484;1923.9230430782752;1947.4255094747084;82303;109727;114164;117436;128020.35989820084;9367.203787678123;9424.858627120599;9983.950080249697;10446.589710389153;10661.946690266499;13307;13314.267715275559;13332.866671333357;13340.266285354204;13345.065845390729;8171.264586187272;8438.831223375499;8463.195996380335;8612.913870861212;8625.52559609215;8483.70307039263;8520.654466222271;8624.051354351017;8804.911950880538;8815.911840881638;6391.041343798374;6525.934740652628;6533.836654083686;6539.6403197824575;6540.117084193668;1777.8666600005051;1806.3677712800416;1807.3854889337688;1819.4905426480607;1825.9269629214682;866.5927014303268;985.9753506162403;997.33677104726;1006.5219020964983;1015.6242190389603;19728.30950916711;20059.699104513445;20970.47573810667;21447.67828482574;21519.354419367442;831.8793774902605;962.6726912849665;963.5471328475745;964.0319100500855;986.1075726467558;11016.669499915015;11261.887381126084;11363.715907102389;11384.21086833731;11408.315501069963;499268;576260;593013.0349348226;593111;595435;77877.61061194658;88667.66998494898;126279.3686031564;127821;131058.68941310464;975.6146322202691;1006.833872411044;1049.396596956753;1050.1179009631794;1050.5010120192846;16520.75218871742;18523.44429667112;18703.158357873657;18947.90526047361;18955.431337059912;43194.784026079666;51154.232686508985;51399.74300128625;51546.74226628843;51650.48349516457;59422.70288648529;70232.64883675754;71045;71070.2232444193;72343.93427549275;6782.389584937379;7602.543847369176;7708.706744618936;8084.070331911906;8126.309263712651;12682.302473364054;12968.416421261254;12992.415341309475;13211.273379964194;13226.801597975846;11202.775944481227;11284.492197850952;11376.772464550828;11451.656450306504;11660.591879284184;10201.74495637615;10912.454377281263;11027.944860275968;11325.603603873826;11437.970582647602;11328.603498877499;11458.083353331547;11472.024877885473;11630.883691163319;11765.647030589096;7803.609819509115;7826.413019023482;7870.016247969004;7941.682332706627;8051.866255442195;11127.666170014912;11203.943980280046;11300.886991130312;11533.423328833356;11547.711307217387 09:10:07 INFO - PROCESS | 2563 | |3;mootools.html;1305.732324873401;1363.7954306853965;1536.0553259745252;1683.5454427304628;1909.1218039701728;7930.801729956754;8466.44968077076;8476.491410515366;8481.109483504235;8494.023187333458;8534.786630334245;8722;8820.117988201177;8972.551372431384;8973.012968573468;2399.4121440247122;2427.7693619106244;2429.854208747472;2430.1858877276136;2441.182203961666;2372.5729368713623;2399.6760437341;2414.541237164933;2417.637354396837;2420.818438617102;2378.4053986503372;2407.16952651335;2411.8191135664806;2415.1546958564536;2424.3939015246187;2371.34787933318;2388.0686532252435;2395.2335252719135;2403.8918248678765;2404.0984630763464;2236.6533187355985;2263.6151854184827;2264.615015447378;2265.501589650291;2268.1267711931027;8856.601452934568;8889.822203555957;8960.283177345797;8971.506567138806;8980.012198658142;5179.766910489037;5268.6575372600655;5427.240186373911;5446.128619420913;5477.370102438231;3522.559680039995;3654.7441679082476;3723.8510459581585;3738.980950286842;3740.8503659853573;1081.9080378167864;1085.0505807418508;1085.7882712870994;1108.4568561404935;1108.5011744714911;11767.293962362286;11847.94076029623;11904.761904761855;11937.641870743892;11964.760704785942;4272.764997925144;4425.336199570091;4623.306504024423;4647.790849411785;4673.6728429009645;9280.72157835266;9283.11810378013;9287.024862389488;9303.209227215628;9316.720498385059;4479.014616784302;5195.454477279907;5268.81559145428;5279.762410691528;5289.629725919226;4185.518665353462;4444.11117776446;4624.491305956342;4624.537546245416;4952.083864485082;3086.42901063304;3747.1756213632957;3788.8295026723863;3821.2548553032448;3826.043489127718;1042.3276986343917;1048.2400259811727;1054.0724162736742;1058.5818601652459;1064.4677661169414;853.2406158518924;863.9913600864037;896.2784958108655;913.2602591900448;928.4243768863348;5418.02475554404;5473.425290344457;5822.475977162076;6071.635701857903;6081.513478921675;800.203797221768;878.8549889268327;939.8637197606308;947.303731757158;1001.0790073132736;3932.4297976793778;4019.7422810552343;4021.5174179098694;4062.72487407053;4126.731762435432;10213.846792298122;10838.945805271082;11012.229143960007;11033.841446648146;11050.281731687415;10202.897971020195;10970.506327215295;10981.913436447208;10996.450177491093;11019.944900275448;7920.405969552191;8157.95921020403;8296.70961516344;8321.875171872427;8343.624536895853;5995.340512543529;6001.2498437695285;6038.8792224154395;6137.938620613737;6153.323134455296;9623.711288661352;10220.875703672738;10245.48772561354;10289.228307876789;10307.536160872929;9441.811163776823;10205.540750666089;10307.763068431836;10308.896911030793;10329.586816527255;7055.647217638995;7147.964260178665;7300.415966722756;7304.853902922018;7305.780826575211;8088.110307866013;8298.128696486781;8354.665813367641;8407.411481196503;8415.789605259917;7494.887576686463;7560.773176804704;7574;7593.696252149852;7628.313451789366;7158.391536719437;7313.268673132644;7395.926040739524;7457.776266712007;7461.477696561188;6824.385805277549;7047.189573199148;7128.643567821692;7135.964320178366;7150.284971502808;4908.852734417973;4994.550490455877;5011.974940125276;5074.391073071255;5204.2193670948145;4952.207646776643;5055.266986287042;5081.517255860687;5146.871328216825;5246.160614301693 09:10:07 INFO - PROCESS | 2563 | |4;prototype.html;303.7888667376173;304.1635502368486;313.8760189725058;320.46642340503064;321.43106701139;4627.699199552027;5316.746602717825;5358.89282214356;5365.624406291561;5367.651102678333;4799.009030608163;4989.3513843200335;5028.597712183026;5199.376074871011;5357.624966252364;3782.527184101994;4087.509498860141;4096.456565071207;4103.2409004334295;4134.937975930364;4100.1594673092095;4106.384042393635;4106.38404239365;4133;4140.358244472111;3349.866005359783;3812.7972503097394;4060.634542891139;4065.471488706479;4097.873084901661;3463.1515278756615;3960.9009774755573;4065.186962607461;4073.246449406869;4079.1841631673637;3096.9535456968165;3288.638249792521;3493.3188028334484;3510.80690562019;3510.859565617372;3912.315344814646;4493.707908985905;4916.262560615901;5193.350831146106;5224.242484839715;3677.7425580209397;3729.2099792099943;4565.497795242521;4611.1930412177735;4624.838130665411;5268.762905669254;5594.692291923942;5595.832125036255;5619.775208991636;5635.408282130358;3523.964760352415;3773.811309434517;3801.1257410795397;3805.562360328542;3821.6942644588366;4434.5787150221;5462.99999999996;5873.588848780587;5921.703914804242;5941.346451890288;3832.0419895026243;3858.575556688762;3869.168128852254;3872.9930218143486;3878.3600705883778;3907.8632247871756;3931.938376638349;3953.3872249801325;3962.5244970603135;3965.345717956505;3739.700823934079;4046.037671729209;4420.226460369487;4647.558481944277;4653.789450011218;5619.831405057855;5638.436156384329;5641.12562552805;5642.7178641067785;5689.34572524165;1089.7657003744232;1215.1372525506813;1260.590308149855;1271.288078675926;1346;709.5175280808996;866.3285953386176;877.5875338590852;900.4642237868458;906.805036917066;5253.185756207794;5266.8419947401635;5267.78928842842;5271.578273738091;5327.696827966685;5119.078565858107;5590.524805391506;5765.250517432707;5792.9420705793245;5834.795782147605;4058.918821623551;4952.5542701157065;5280.445553216934;5296.4703529647495;5321.281626980308;2990.685977972325;2994.215379696903;3002.354493783847;3011.8192908425563;3012.472817256971;5599.80400685974;5894.263217097863;5964.552658550626;5988.221531201003;5988.640681559121;5292.708901010405;5327.467253274642;5825.7087145642545;5834.737436815353;5847.707614619252;5039.547909681806;5735.283089613798;5904.4095590441475;5914.76340946366;5920.319163296103;5314.202869569595;5658.632188907708;5667.546596272371;5709.429057094258;5719.370869204301;4303.892402689958;4795.352627395397;5314.28257185275;5604.85987850307;5644.802431915027;4761.500127459839;4907.386576677915;5392.892142157213;5441.156620723794;5917.792877249448;3565.8442278584157;3852.1910398816563;3874.709396795308;3898.3567711327314;3923.2545816294814;5525.696086715269;5554.305711786027;5827.921834460638;5830.221782356252;5835.328937172279;5390.0921831673295;5659.9151012735665;5688.232088667977;5717.17101020358;5729.828105156852;5123.541609413705;5234.371875374979;5651.15232715096;5669.54643628517;5698.065496269342;4575.702579332466;4971.850844474671;5299.231611416246;5448.237246785374;5458.508734213781;5276;5576.33084029919;5643.576731745217;5690.630109042899;5722.394891218506;4717.858464246078;5501.202325662836;5504.91742623869;5508.724563771875;5584.788746470715 09:10:07 INFO - PROCESS | 2563 | |5;yui.html;694.8679750847339;760.7109298466581;776.2858170483155;821.1542111625029;828.2752591482451;15274.69450610987;15865.682686346296;15880.841191588095;15931.52205433836;15960.52118436446;14303.282376348487;14389.856101438982;14400.991930564865;14408.35162417691;14933.850661493381;684.4592771710343;707.0984494769159;707.324505097633;711.7615598774416;836.4395854777283;696.1228851646911;703.6165289916981;833.8540755367817;859.2352806002632;863.6329559937033;899.7525680437866;906.0486489186361;909.3861643390685;911.0297533127232;912.333996182787;899.5097671768901;899.7300809757079;901.9143181397757;902.6299217320867;907.0340087806464;535.0850046420586;555.2420945409486;678.9035707332672;679.6805501414377;680.7957612716166;14827.406903723839;15538.533843984698;15550.144742039183;15576.844231557767;15872.841271587251;14050;14695.853041469556;14775.113493190443;14924.328405221791;14969.326380312912;10154.746131346701;11932.343721095336;11988.160828741991;11988.640340789703;12064.396780161045;3822.789746563938;3835.712321575865;3849.788261645608;3894.415837624351;3920.3923391874305;101578.95263142191;101584;103318.48340758323;103672;104319;10482.68551943443;11233.269837460539;11255.493502792395;11298.661040168809;11698.70753231176;23599.7640023601;23788.52422951531;23801.880990594935;23816;23893.044278229023;7149.748793961036;7997.920020799717;8104.230098140667;8176.182381761895;8279.461834980708;4370.759608221515;4393.84621538251;4442.73343599385;4468.419105516327;4604.516525764813;3013.7588992880956;3545.645435456434;3632.891013269606;3813.237352529505;3817.2365526894732;607.8784243151299;649.8797722421367;650.0541711809307;699.5452955578914;706.6113637499354;600.2347007565389;671.0906721392442;671.3085521912438;674.3122015544216;689.3864460630043;8901.910980890108;9195.172434480928;9436.103570160823;9508.90491095094;9544.236461083097;632.8164832198703;645.3320812958565;648.8150877000032;665.9933400665931;669.9062131301622;4242.172776308652;4642.396488456547;4651.488336283006;4672.2057250267535;4728.148933192058;104828.95171048192;145200;150229;151945;154925;136897.31551342178;139812;141678;141985;145910.54089459183;111419;116316.41841790736;116703;116743;116886.41556792162;8727.563621818757;8922.795422618123;8968.10318968098;9049.411788233743;9180.540972951192;64703.029454557276;65379.346206537324;66176.67646647137;67313.66343168252;67503;64393.67803161142;96280.0371996299;98014.50992745276;98659.01340986499;102385.48807255916;11653.00949419309;12138.453769580567;12354.135210535176;12407.255564665787;12625.368731563569;10896;12827.294498802283;12904.225746455242;13205.20768753878;13657.795133072814;11881.287122772661;12066.879331206927;12349.32078735678;12525.436355364212;12603.92866606312;11831.881681183078;12273.140880138304;12289.201201921847;12534.185277956904;12600.767468840053;11685.182037257311;11688.88311116878;11765.588204412805;11939.582114626293;12212.816807747713;9877.259205559469;10183.13443357323;10201.38791672502;10375.533101010624;10404.377233218964;9332.553454214607;9441.862789187084;9882.85175722379;10295.330803497749;10366.274360794672 09:10:07 INFO - PROCESS | 2563 | __end_tp_report 09:10:07 INFO - PROCESS | 2563 | __start_cc_report 09:10:07 INFO - PROCESS | 2563 | _x_x_mozilla_cycle_collect,1150 09:10:07 INFO - PROCESS | 2563 | __end_cc_report 09:10:07 INFO - PROCESS | 2563 | __startTimestamp1475165407091__endTimestamp 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | ------- Summary: start ------- 09:10:07 INFO - PROCESS | 2563 | Number of tests: 6 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | [#0] dojo.html Cycles:165 Average:5667.96 Median:5897.76 stddev:660.34 (11.2%) stddev-sans-first:628.64 09:10:07 INFO - PROCESS | 2563 | Values: 3012.0 3277.0 3307.0 3308.6 3350.5 5553.3 5596.2 5667.1 5682.5 5682.7 5588.7 5608.2 5690.9 5697.5 5712.9 5010.6 5013.3 5035.9 5082.8 5180.2 5078.7 5180.7 5193.7 5220.5 5259.9 5137.2 5151.2 5161.7 5180.3 5249.1 5100.2 5141.8 5177.0 5210.5 5218.6 5059.3 5144.0 5168.6 5188.7 5191.6 5573.4 5589.6 5607.3 5629.5 5654.0 6133.3 6167.3 6192.2 6199.5 6200.3 6070.5 6131.4 6141.2 6259.0 6260.1 4770.6 5650.1 5702.9 5706.7 5708.1 6252.0 6274.5 6285.3 6291.4 6292.7 5905.9 5985.9 6052.8 6084.3 6099.4 6118.9 6149.2 6165.8 6175.0 6229.6 6127.0 6133.7 6140.2 6154.0 6158.7 6103.1 6120.4 6180.8 6186.5 6196.6 4087.2 4834.6 4868.0 4875.9 4891.9 4369.9 4385.6 4408.6 4421.8 4478.4 5676.8 5699.9 5700.1 5707.3 5713.7 6025.5 6139.0 6140.3 6215.5 6225.6 4695.0 5682.6 5720.0 5730.6 5775.1 5340.6 5348.1 5350.9 5351.5 5364.7 5087.6 6221.7 6287.8 6307.7 6341.1 6116.4 6126.7 6165.3 6194.9 6219.9 6285.1 6312.1 6323.2 6328.6 6339.4 6108.2 6116.2 6132.5 6134.4 6153.0 5950.4 5995.1 6123.6 6130.1 6168.9 6075.0 6088.2 6089.4 6096.3 6131.5 5897.8 6074.3 6078.7 6088.0 6091.2 5653.5 5751.0 5817.1 5817.7 5876.0 6104.8 6107.5 6114.9 6115.2 6116.5 5710.3 5914.0 5919.1 5926.1 5982.9 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | [#1] ext.html Cycles:180 Average:71535.60 Median:41365.29 stddev:160361.21 (387.7%) stddev-sans-first:160722.25 09:10:07 INFO - PROCESS | 2563 | Values: 448.6 461.2 499.8 499.9 515.1 31036.1 40729.6 42439.6 42517.0 43781.0 42654.8 43116.0 43472.8 43498.6 43604.8 9743.4 9938.4 9942.9 9947.9 9978.2 9733.2 9835.5 9844.9 9853.4 9854.6 9777.5 9818.1 9836.4 9853.9 9864.0 9772.9 9811.7 9842.7 9852.3 9873.1 9739.3 9806.7 9810.4 9818.1 9826.3 41157.6 41573.0 41604.4 41642.4 41763.0 3231.2 3259.6 3289.4 3317.9 3331.0 30491.5 30914.7 31117.7 31362.8 32149.7 3145.7 3203.9 3219.8 3223.8 3328.8 421545.0 480875.0 481044.0 481167.0 482566.6 58522.5 58819.4 58876.0 58960.0 59021.4 35099.6 35377.9 35806.1 35864.3 36842.8 16451.8 16675.5 16684.6 16705.8 16737.5 12723.6 12836.7 12842.6 12847.6 12853.2 15582.9 18762.7 18922.7 18964.7 18998.3 6342.9 7292.8 7355.4 7421.2 7497.8 816.6 871.7 918.6 942.6 963.3 26840.7 29566.9 29808.0 30539.4 30588.8 845.3 968.0 1040.4 1082.3 1088.3 14605.3 15181.5 15429.2 15454.4 15550.8 695371.0 699836.0 702662.0 703702.0 705894.2 58146.0 58466.7 58501.0 58602.7 58670.7 1191.0 1226.5 1234.5 1319.2 1343.3 26832.6 30817.7 32047.4 32068.0 32377.2 54436.5 55080.0 55168.0 55229.2 55318.4 598325.0 600646.0 601777.0 602299.0 604386.0 26494.9 26679.0 27007.4 27280.9 27358.0 45534.9 46502.8 47482.5 48257.0 48298.0 38392.6 38417.4 38807.8 39914.0 40125.4 38107.8 39129.4 39197.8 40313.0 41119.2 33669.8 33860.8 38316.4 38892.6 39312.4 27417.0 34119.5 34854.1 36463.0 36741.3 24189.3 24702.9 25306.9 25757.4 25842.6 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | [#2] jquery.html Cycles:180 Average:33341.35 Median:25088.75 stddev:95148.56 (379.2%) stddev-sans-first:95383.21 09:10:07 INFO - PROCESS | 2563 | Values: 336.8 369.9 375.4 381.1 403.9 14899.8 15080.7 16332.2 20646.7 20750.3 16518.3 19335.5 19587.8 20035.3 20512.0 5928.9 6189.4 6193.6 6199.0 6199.7 5211.9 6777.4 6871.8 6903.0 6913.9 6547.5 6716.1 6802.0 6841.3 6865.9 6651.6 6734.4 6784.4 6787.6 6788.5 5023.2 5810.0 5860.7 5868.8 5871.2 24320.9 24860.6 24983.9 25193.6 25525.4 2328.4 2426.3 2472.7 2944.5 2994.0 14498.9 15095.2 15342.9 15378.8 15793.6 1877.7 1897.8 1920.1 1923.9 1947.4 82303.0 109727.0 114164.0 117436.0 128020.4 9367.2 9424.9 9984.0 10446.6 10661.9 13307.0 13314.3 13332.9 13340.3 13345.1 8171.3 8438.8 8463.2 8612.9 8625.5 8483.7 8520.7 8624.1 8804.9 8815.9 6391.0 6525.9 6533.8 6539.6 6540.1 1777.9 1806.4 1807.4 1819.5 1825.9 866.6 986.0 997.3 1006.5 1015.6 19728.3 20059.7 20970.5 21447.7 21519.4 831.9 962.7 963.5 964.0 986.1 11016.7 11261.9 11363.7 11384.2 11408.3 499268.0 576260.0 593013.0 593111.0 595435.0 77877.6 88667.7 126279.4 127821.0 131058.7 975.6 1006.8 1049.4 1050.1 1050.5 16520.8 18523.4 18703.2 18947.9 18955.4 43194.8 51154.2 51399.7 51546.7 51650.5 59422.7 70232.6 71045.0 71070.2 72343.9 6782.4 7602.5 7708.7 8084.1 8126.3 12682.3 12968.4 12992.4 13211.3 13226.8 11202.8 11284.5 11376.8 11451.7 11660.6 10201.7 10912.5 11027.9 11325.6 11438.0 11328.6 11458.1 11472.0 11630.9 11765.6 7803.6 7826.4 7870.0 7941.7 8051.9 11127.7 11203.9 11300.9 11533.4 11547.7 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | [#3] mootools.html Cycles:180 Average:5700.82 Median:4952.15 stddev:3219.61 (65.0%) stddev-sans-first:3211.70 09:10:07 INFO - PROCESS | 2563 | Values: 1305.7 1363.8 1536.1 1683.5 1909.1 7930.8 8466.4 8476.5 8481.1 8494.0 8534.8 8722.0 8820.1 8972.6 8973.0 2399.4 2427.8 2429.9 2430.2 2441.2 2372.6 2399.7 2414.5 2417.6 2420.8 2378.4 2407.2 2411.8 2415.2 2424.4 2371.3 2388.1 2395.2 2403.9 2404.1 2236.7 2263.6 2264.6 2265.5 2268.1 8856.6 8889.8 8960.3 8971.5 8980.0 5179.8 5268.7 5427.2 5446.1 5477.4 3522.6 3654.7 3723.9 3739.0 3740.9 1081.9 1085.1 1085.8 1108.5 1108.5 11767.3 11847.9 11904.8 11937.6 11964.8 4272.8 4425.3 4623.3 4647.8 4673.7 9280.7 9283.1 9287.0 9303.2 9316.7 4479.0 5195.5 5268.8 5279.8 5289.6 4185.5 4444.1 4624.5 4624.5 4952.1 3086.4 3747.2 3788.8 3821.3 3826.0 1042.3 1048.2 1054.1 1058.6 1064.5 853.2 864.0 896.3 913.3 928.4 5418.0 5473.4 5822.5 6071.6 6081.5 800.2 878.9 939.9 947.3 1001.1 3932.4 4019.7 4021.5 4062.7 4126.7 10213.8 10838.9 11012.2 11033.8 11050.3 10202.9 10970.5 10981.9 10996.5 11019.9 7920.4 8158.0 8296.7 8321.9 8343.6 5995.3 6001.2 6038.9 6137.9 6153.3 9623.7 10220.9 10245.5 10289.2 10307.5 9441.8 10205.5 10307.8 10308.9 10329.6 7055.6 7148.0 7300.4 7304.9 7305.8 8088.1 8298.1 8354.7 8407.4 8415.8 7494.9 7560.8 7574.0 7593.7 7628.3 7158.4 7313.3 7395.9 7457.8 7461.5 6824.4 7047.2 7128.6 7136.0 7150.3 4908.9 4994.6 5012.0 5074.4 5204.2 4952.2 5055.3 5081.5 5146.9 5246.2 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | [#4] prototype.html Cycles:180 Average:4512.80 Median:5196.36 stddev:1396.89 (26.9%) stddev-sans-first:1364.62 09:10:07 INFO - PROCESS | 2563 | Values: 303.8 304.2 313.9 320.5 321.4 4627.7 5316.7 5358.9 5365.6 5367.7 4799.0 4989.4 5028.6 5199.4 5357.6 3782.5 4087.5 4096.5 4103.2 4134.9 4100.2 4106.4 4106.4 4133.0 4140.4 3349.9 3812.8 4060.6 4065.5 4097.9 3463.2 3960.9 4065.2 4073.2 4079.2 3097.0 3288.6 3493.3 3510.8 3510.9 3912.3 4493.7 4916.3 5193.4 5224.2 3677.7 3729.2 4565.5 4611.2 4624.8 5268.8 5594.7 5595.8 5619.8 5635.4 3524.0 3773.8 3801.1 3805.6 3821.7 4434.6 5463.0 5873.6 5921.7 5941.3 3832.0 3858.6 3869.2 3873.0 3878.4 3907.9 3931.9 3953.4 3962.5 3965.3 3739.7 4046.0 4420.2 4647.6 4653.8 5619.8 5638.4 5641.1 5642.7 5689.3 1089.8 1215.1 1260.6 1271.3 1346.0 709.5 866.3 877.6 900.5 906.8 5253.2 5266.8 5267.8 5271.6 5327.7 5119.1 5590.5 5765.3 5792.9 5834.8 4058.9 4952.6 5280.4 5296.5 5321.3 2990.7 2994.2 3002.4 3011.8 3012.5 5599.8 5894.3 5964.6 5988.2 5988.6 5292.7 5327.5 5825.7 5834.7 5847.7 5039.5 5735.3 5904.4 5914.8 5920.3 5314.2 5658.6 5667.5 5709.4 5719.4 4303.9 4795.4 5314.3 5604.9 5644.8 4761.5 4907.4 5392.9 5441.2 5917.8 3565.8 3852.2 3874.7 3898.4 3923.3 5525.7 5554.3 5827.9 5830.2 5835.3 5390.1 5659.9 5688.2 5717.2 5729.8 5123.5 5234.4 5651.2 5669.5 5698.1 4575.7 4971.9 5299.2 5448.2 5458.5 5276.0 5576.3 5643.6 5690.6 5722.4 4717.9 5501.2 5504.9 5508.7 5584.8 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - PROCESS | 2563 | [#5] yui.html Cycles:180 Average:24923.42 Median:3815.24 stddev:40230.10 (1054.5%) stddev-sans-first:40301.83 09:10:07 INFO - PROCESS | 2563 | Values: 694.9 760.7 776.3 821.2 828.3 15274.7 15865.7 15880.8 15931.5 15960.5 14303.3 14389.9 14401.0 14408.4 14933.9 684.5 707.1 707.3 711.8 836.4 696.1 703.6 833.9 859.2 863.6 899.8 906.0 909.4 911.0 912.3 899.5 899.7 901.9 902.6 907.0 535.1 555.2 678.9 679.7 680.8 14827.4 15538.5 15550.1 15576.8 15872.8 14050.0 14695.9 14775.1 14924.3 14969.3 10154.7 11932.3 11988.2 11988.6 12064.4 3822.8 3835.7 3849.8 3894.4 3920.4 101579.0 101584.0 103318.5 103672.0 104319.0 10482.7 11233.3 11255.5 11298.7 11698.7 23599.8 23788.5 23801.9 23816.0 23893.0 7149.7 7997.9 8104.2 8176.2 8279.5 4370.8 4393.8 4442.7 4468.4 4604.5 3013.8 3545.6 3632.9 3813.2 3817.2 607.9 649.9 650.1 699.5 706.6 600.2 671.1 671.3 674.3 689.4 8901.9 9195.2 9436.1 9508.9 9544.2 632.8 645.3 648.8 666.0 669.9 4242.2 4642.4 4651.5 4672.2 4728.1 104829.0 145200.0 150229.0 151945.0 154925.0 136897.3 139812.0 141678.0 141985.0 145910.5 111419.0 116316.4 116703.0 116743.0 116886.4 8727.6 8922.8 8968.1 9049.4 9180.5 64703.0 65379.3 66176.7 67313.7 67503.0 64393.7 96280.0 98014.5 98659.0 102385.5 11653.0 12138.5 12354.1 12407.3 12625.4 10896.0 12827.3 12904.2 13205.2 13657.8 11881.3 12066.9 12349.3 12525.4 12603.9 11831.9 12273.1 12289.2 12534.2 12600.8 11685.2 11688.9 11765.6 11939.6 12212.8 9877.3 10183.1 10201.4 10375.5 10404.4 9332.6 9441.9 9882.9 10295.3 10366.3 09:10:07 INFO - PROCESS | 2563 | -------- Summary: end -------- 09:10:07 INFO - PROCESS | 2563 | 09:10:07 INFO - TEST-INFO | 2563: exit 0 09:10:07 INFO - TEST-OK | dromaeo_css | took 1117461ms 09:10:07 INFO - TEST-START | kraken 09:10:07 INFO - operating with platform_type : mac_ 09:10:07 INFO - Initialising browser for kraken test... 09:10:07 INFO - TEST-INFO | started process 2566 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpy_EAnE/profile http://localhost:52658/getInfo.html) 09:10:10 INFO - PROCESS | 2566 | __metrics Screen width/height:1600/1200 09:10:10 INFO - PROCESS | 2566 | colorDepth:24 09:10:10 INFO - PROCESS | 2566 | Browser inner width/height: 1024/689 09:10:10 INFO - PROCESS | 2566 | __metrics 09:10:10 INFO - PROCESS | 2566 | 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" 09:10:10 INFO - PROCESS | 2566 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:11 INFO - TEST-INFO | 2566: exit 0 09:10:11 INFO - Browser initialized. 09:10:11 INFO - Running cycle 1/1 for kraken test... 09:10:11 INFO - TEST-INFO | started process 2567 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/T/tmpy_EAnE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:23 INFO - PROCESS | 2567 | RSS: Main: 244219904 09:10:23 INFO - PROCESS | 2567 | 09:10:25 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:26 INFO - PROCESS | 2567 | RSS: Main: 294576128 09:10:26 INFO - PROCESS | 2567 | 09:10:29 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-dft) 09:10:29 INFO - PROCESS | 2567 | RSS: Main: 293052416 09:10:29 INFO - PROCESS | 2567 | 09:10:33 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-fft) 09:10:34 INFO - PROCESS | 2567 | RSS: Main: 384131072 09:10:34 INFO - PROCESS | 2567 | 09:10:36 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-oscillator) 09:10:36 INFO - PROCESS | 2567 | RSS: Main: 285937664 09:10:36 INFO - PROCESS | 2567 | 09:10:38 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52658/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:10:39 INFO - PROCESS | 2567 | RSS: Main: 290988032 09:10:39 INFO - PROCESS | 2567 | 09:10:42 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52658/tests/kraken/driver.html?testName=imaging-darkroom) 09:10:42 INFO - PROCESS | 2567 | RSS: Main: 328339456 09:10:42 INFO - PROCESS | 2567 | 09:10:45 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52658/tests/kraken/driver.html?testName=imaging-desaturate) 09:10:46 INFO - PROCESS | 2567 | RSS: Main: 329076736 09:10:46 INFO - PROCESS | 2567 | 09:10:49 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52658/tests/kraken/driver.html?testName=json-parse-financial) 09:10:49 INFO - PROCESS | 2567 | RSS: Main: 327151616 09:10:49 INFO - PROCESS | 2567 | 09:10:51 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52658/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:10:52 INFO - PROCESS | 2567 | RSS: Main: 301207552 09:10:52 INFO - PROCESS | 2567 | 09:10:53 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:10:54 INFO - PROCESS | 2567 | RSS: Main: 307863552 09:10:54 INFO - PROCESS | 2567 | 09:10:56 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:10:57 INFO - PROCESS | 2567 | RSS: Main: 313004032 09:10:57 INFO - PROCESS | 2567 | 09:11:00 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:11:01 INFO - PROCESS | 2567 | RSS: Main: 316903424 09:11:01 INFO - PROCESS | 2567 | 09:11:03 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:11:04 INFO - PROCESS | 2567 | RSS: Main: 318390272 09:11:04 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52658/tests/kraken/driver.html?testName=ai-astar) 09:11:06 INFO - PROCESS | 2567 | RSS: Main: 362352640 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | __start_tp_report 09:11:06 INFO - PROCESS | 2567 | _x_x_mozilla_page_load 09:11:06 INFO - PROCESS | 2567 | _x_x_mozilla_page_load_details 09:11:06 INFO - PROCESS | 2567 | |i|pagename|runs| 09:11:06 INFO - PROCESS | 2567 | |0;ai-astar;115;111;112;112;113;113;112;113;112;112 09:11:06 INFO - PROCESS | 2567 | |1;audio-beat-detection;148;111;134;177;109;111;108;109;140;111 09:11:06 INFO - PROCESS | 2567 | |2;audio-dft;182;183;185;211;266;186;215;265;180;182 09:11:06 INFO - PROCESS | 2567 | |3;audio-fft;82;60;60;87;60;62;58;60;82;58 09:11:06 INFO - PROCESS | 2567 | |4;audio-oscillator;83;70;91;69;65;68;89;66;67;88 09:11:06 INFO - PROCESS | 2567 | |5;imaging-gaussian-blur;87;84;87;84;87;83;87;84;97;119 09:11:06 INFO - PROCESS | 2567 | |6;imaging-darkroom;95;95;102;95;95;96;95;96;95;96 09:11:06 INFO - PROCESS | 2567 | |7;imaging-desaturate;131;141;101;86;86;87;87;86;85;87 09:11:06 INFO - PROCESS | 2567 | |8;json-parse-financial;55;52;53;53;52;55;54;54;53;53 09:11:06 INFO - PROCESS | 2567 | |9;json-stringify-tinderbox;64;74;68;68;76;70;68;69;71;70 09:11:06 INFO - PROCESS | 2567 | |10;stanford-crypto-aes;84;85;89;87;86;88;87;89;84;111 09:11:06 INFO - PROCESS | 2567 | |11;stanford-crypto-ccm;140;139;138;146;153;152;156;139;150;141 09:11:06 INFO - PROCESS | 2567 | |12;stanford-crypto-pbkdf2;169;168;167;169;168;168;167;168;169;170 09:11:06 INFO - PROCESS | 2567 | |13;stanford-crypto-sha256-iterative;67;67;68;67;68;66;67;66;68;67 09:11:06 INFO - PROCESS | 2567 | __end_tp_report 09:11:06 INFO - PROCESS | 2567 | __start_cc_report 09:11:06 INFO - PROCESS | 2567 | _x_x_mozilla_cycle_collect,1908 09:11:06 INFO - PROCESS | 2567 | __end_cc_report 09:11:06 INFO - PROCESS | 2567 | __startTimestamp1475165466495__endTimestamp 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | ------- Summary: start ------- 09:11:06 INFO - PROCESS | 2567 | Number of tests: 14 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.50 stddev:1.08 (1.0%) stddev-sans-first:0.67 09:11:06 INFO - PROCESS | 2567 | Values: 115.0 111.0 112.0 112.0 113.0 113.0 112.0 113.0 112.0 112.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#1] audio-beat-detection Cycles:10 Average:125.80 Median:122.50 stddev:23.38 (19.1%) stddev-sans-first:23.38 09:11:06 INFO - PROCESS | 2567 | Values: 148.0 111.0 134.0 177.0 109.0 111.0 108.0 109.0 140.0 111.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#2] audio-dft Cycles:10 Average:205.50 Median:198.50 stddev:33.96 (17.1%) stddev-sans-first:34.94 09:11:06 INFO - PROCESS | 2567 | Values: 182.0 183.0 185.0 211.0 266.0 186.0 215.0 265.0 180.0 182.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#3] audio-fft Cycles:10 Average:66.90 Median:61.00 stddev:11.70 (19.2%) stddev-sans-first:11.07 09:11:06 INFO - PROCESS | 2567 | Values: 82.0 60.0 60.0 87.0 60.0 62.0 58.0 60.0 82.0 58.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.50 stddev:10.73 (14.0%) stddev-sans-first:11.04 09:11:06 INFO - PROCESS | 2567 | Values: 83.0 70.0 91.0 69.0 65.0 68.0 89.0 66.0 67.0 88.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:87.00 stddev:10.97 (12.6%) stddev-sans-first:11.58 09:11:06 INFO - PROCESS | 2567 | Values: 87.0 84.0 87.0 84.0 87.0 83.0 87.0 84.0 97.0 119.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:95.00 stddev:2.16 (2.3%) stddev-sans-first:2.26 09:11:06 INFO - PROCESS | 2567 | Values: 95.0 95.0 102.0 95.0 95.0 96.0 95.0 96.0 95.0 96.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:86.00 stddev:20.84 (24.2%) stddev-sans-first:18.30 09:11:06 INFO - PROCESS | 2567 | Values: 131.0 141.0 101.0 86.0 86.0 87.0 87.0 86.0 85.0 87.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:1.07 (2.0%) stddev-sans-first:0.97 09:11:06 INFO - PROCESS | 2567 | Values: 55.0 52.0 53.0 53.0 52.0 55.0 54.0 54.0 53.0 53.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.36 (4.8%) stddev-sans-first:2.83 09:11:06 INFO - PROCESS | 2567 | Values: 64.0 74.0 68.0 68.0 76.0 70.0 68.0 69.0 71.0 70.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:87.00 stddev:7.94 (9.1%) stddev-sans-first:8.22 09:11:06 INFO - PROCESS | 2567 | Values: 84.0 85.0 89.0 87.0 86.0 88.0 87.0 89.0 84.0 111.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:148.00 stddev:6.83 (4.6%) stddev-sans-first:6.96 09:11:06 INFO - PROCESS | 2567 | Values: 140.0 139.0 138.0 146.0 153.0 152.0 156.0 139.0 150.0 141.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 09:11:06 INFO - PROCESS | 2567 | Values: 169.0 168.0 167.0 169.0 168.0 168.0 167.0 168.0 169.0 170.0 09:11:06 INFO - PROCESS | 2567 | 09:11:06 INFO - PROCESS | 2567 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 09:11:06 INFO - PROCESS | 2567 | Values: 67.0 67.0 68.0 67.0 68.0 66.0 67.0 66.0 68.0 67.0 09:11:06 INFO - PROCESS | 2567 | -------- Summary: end -------- 09:11:06 INFO - PROCESS | 2567 | 09:11:07 INFO - TEST-INFO | 2567: exit 0 09:11:07 INFO - TEST-OK | kraken | took 59633ms 09:11:07 INFO - SUITE-END | took 1177s 09:11:07 INFO - Completed test suite (00:19:37) 09:11:07 INFO - javascript benchmark 09:11:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.9698803011966, 3276.999999999999, 3306.966930330696, 3308.553345298384, 3350.497425386192, 5553.250311207986, 5596.16057591361, 5667.0932650775885, 5682.51698605618, 5682.687452190133, 5588.748506317215, 5608.186812912136, 5690.886182276362, 5697.544196464283, 5712.8857422851515, 5010.574101201402, 5013.3482647255805, 5035.874103147414, 5082.822101226458, 5180.197069454241, 5078.695278283296, 5180.715060671661, 5193.662411943212, 5220.451852555484, 5259.894802103956, 5137.152369858969, 5151.201563757623, 5161.74191290434, 5180.300659410969, 5249.107651699221, 5100.15847385181, 5141.845744627667, 5177.0, 5210.452902445227, 5218.60860435465, 5059.3169922060415, 5144.048351055067, 5168.5865130789825, 5188.662736922088, 5191.610629202825, 5573.41479144688, 5589.636673616243, 5607.327120745536, 5629.549636029108, 5654.03881340169, 6133.294671112834, 6167.321594624587, 6192.195014648112, 6199.504039676815, 6200.286966998808, 6070.514358851281, 6131.356207598227, 6141.170941922873, 6258.968705156445, 6260.060990851363, 4770.594499467515, 5650.067738823089, 5702.914456283076, 5706.686132262683, 5708.143778433268, 6251.999680051261, 6274.529410294246, 6285.308616052231, 6291.402316779899, 6292.653904035231, 5905.852353691193, 5985.9401405986255, 6052.757889684452, 6084.269887613515, 6099.390060993954, 6118.893302669218, 6149.200603921461, 6165.753369865155, 6175.0, 6229.626222426669, 6126.989046807316, 6133.707433238647, 6140.17107690465, 6154.0, 6158.661273629904, 6103.055501741115, 6120.385176771874, 6180.783672571439, 6186.4741496973265, 6196.597221180609, 4087.182563487255, 4834.589059929875, 4868.0, 4875.902481950341, 4891.948231130253, 4369.9126017479475, 4385.649148068147, 4408.625266852354, 4421.845235416745, 4478.395416618715, 5676.829695109153, 5699.857503562361, 5700.116481945305, 5707.286589176353, 5713.74288157034, 6025.487833534199, 6138.970878344271, 6140.289530736369, 6215.471684906744, 6225.595336303126, 4694.976525117421, 5682.602217844711, 5720.0, 5730.627509211888, 5775.075987841925, 5340.599455040888, 5348.090824559834, 5350.892982140335, 5351.545118664957, 5364.7049412282695, 5087.592992560512, 6221.688915554114, 6287.779927702509, 6307.716152773136, 6341.112244285896, 6116.366205733216, 6126.670355029888, 6165.321814600301, 6194.876102478015, 6219.875602487835, 6285.088662144055, 6312.14786003902, 6323.241211054703, 6328.620282782864, 6339.366063393808, 6108.236470441195, 6116.204893364013, 6132.478739307209, 6134.38656134383, 6152.969235153795, 5950.375210602824, 5995.070764031582, 6123.618175568305, 6130.092410195535, 6168.938310617016, 6074.969625151846, 6088.238970128734, 6089.421504957022, 6096.329403765671, 6131.478824299985, 5897.764089436375, 6074.271087469532, 6078.7264573093435, 6087.969560152171, 6091.177691011657, 5653.491185793298, 5751.0, 5817.069268916953, 5817.70911454434, 5875.970620146872, 6104.7926496150985, 6107.46191531438, 6114.938850611437, 6115.174451448997, 6116.518381401779, 5710.3433105193435, 5914.02418600926, 5919.082542205965, 5926.051831707078, 5982.940170598238], "value": 5624.4470085526855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.64556999970006, 461.213630759555, 499.7551199912044, 499.86530575593383, 515.0934355534259, 31036.06891793247, 40729.59270407295, 42439.575604243946, 42517.0, 43781.0, 42654.786726066406, 43116.0, 43472.78263608686, 43498.56501434985, 43604.781976090075, 9743.366681165737, 9938.403695778241, 9942.90057099427, 9947.90052099477, 9978.20174386051, 9733.17268032218, 9835.508224588777, 9844.90155098447, 9853.359531630456, 9854.556544955494, 9777.511124443785, 9818.067283608047, 9836.409815411098, 9853.852192217122, 9863.95068024659, 9772.853407198898, 9811.705648830546, 9842.65550705729, 9852.261080418926, 9873.111419972201, 9739.26955478342, 9806.65676701312, 9810.362326448758, 9818.116369526777, 9826.263030272758, 41157.588424115675, 41573.0, 41604.37593436101, 41642.37536436956, 41763.0, 3231.2083539532728, 3259.608846938359, 3289.3914625794064, 3317.9004629861142, 3330.9500357494658, 30491.542626860613, 30914.690853091408, 31117.68882311193, 31362.843185784153, 32149.714011439515, 3145.653978062411, 3203.9199020024685, 3219.7746157768966, 3223.774335796495, 3328.8169150696704, 421545.0, 480875.0, 481044.0, 481167.0, 482566.5871670654, 58522.53693657693, 58819.41180588225, 58876.0, 58960.0, 59021.40978590245, 35099.64900351015, 35377.938661840184, 35806.10484737902, 35864.28271434609, 36842.8157859209, 16451.83548164527, 16675.49973500797, 16684.582885427717, 16705.83294167067, 16737.497875063767, 12723.618291451272, 12836.679083022813, 12842.614721558368, 12847.550335738391, 12853.228806271654, 15582.922085389728, 18762.718559221623, 18922.716159257623, 18964.715529267072, 18998.335058272893, 6342.936570634327, 7292.781216563511, 7355.448341374418, 7421.183669796318, 7497.775066748006, 816.6325153680867, 871.7471933139445, 918.5590916360051, 942.5993952570267, 963.3160456076124, 26840.731592684213, 29566.852165739034, 29808.0, 30539.389212216076, 30588.847055764578, 845.3490812074791, 967.9612815487443, 1040.3965699894043, 1082.2748758331936, 1088.2654208409292, 14605.342759575844, 15181.544553663407, 15429.225106933209, 15454.38182472711, 15550.76673849893, 695371.0, 699836.0081995879, 702662.0, 703702.0, 705894.234759437, 58146.0, 58466.707666462244, 58501.0, 58602.706986465644, 58670.70664646735, 1191.0412118244897, 1226.5093962414926, 1234.530878266289, 1319.1689235781396, 1343.2813444807177, 26832.597511036958, 30817.69182308148, 32047.35905281928, 32068.03795886127, 32377.190570235933, 54436.45563544314, 55080.0, 55168.0, 55229.17156242579, 55318.44681553133, 598325.0, 600646.0, 601777.0, 602299.0, 604386.0, 26494.86752566225, 26679.0, 27007.379557226628, 27280.908763650026, 27358.0, 45534.861628459556, 46502.76748616235, 47482.52517474781, 48257.0, 48298.0, 38392.6160738389, 38417.42373864338, 38807.805960970014, 39914.0, 40125.39811902765, 38107.809460952514, 39129.41305880357, 39197.82406527809, 40313.0, 41119.177616448105, 33669.79127609049, 33860.830695846365, 38316.425253621775, 38892.6110738889, 39312.410313844746, 27417.0, 34119.4882076774, 34854.12864678403, 36463.0, 36741.26517469582, 24189.274321770376, 24702.888370023753, 25306.861191246808, 25757.431949765756, 25842.61236081498], "value": 19695.23221059526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.8400009995251, 369.88718440875533, 375.3712531509719, 381.1481339206873, 403.8626866865265, 14899.776503352445, 15080.698386032273, 16332.18339083044, 20646.690299645517, 20750.27374041909, 16518.339266429357, 19335.516612084706, 19587.804121958776, 20035.298764543244, 20512.0, 5928.940710592904, 6189.350118237588, 6193.628382297076, 6199.0, 6199.69001549923, 5211.921821172686, 6777.423918966893, 6871.828204294882, 6903.0, 6913.861722765541, 6547.541672082956, 6716.093327400812, 6802.013708012335, 6841.281665425134, 6865.897011544831, 6651.600903945779, 6734.360235777618, 6784.389404953528, 6787.5927444353, 6788.524803263774, 5023.196288593844, 5810.012297909366, 5860.70696465175, 5868.8239352819055, 5871.178035075093, 24320.905559249877, 24860.643813085182, 24983.87508062466, 25193.62209566858, 25525.361865953313, 2328.382978510696, 2426.308502076917, 2472.7279999200073, 2944.5435957426635, 2993.9550906736417, 14498.927505362512, 15095.245237738069, 15342.923285383502, 15378.76931846012, 15793.605159871095, 1877.6807942649782, 1897.8007309232607, 1920.0879582198484, 1923.9230430782752, 1947.4255094747084, 82303.0, 109727.0, 114164.0, 117436.0, 128020.35989820084, 9367.203787678123, 9424.858627120599, 9983.950080249697, 10446.589710389153, 10661.946690266499, 13307.0, 13314.267715275559, 13332.866671333357, 13340.266285354204, 13345.065845390729, 8171.264586187272, 8438.831223375499, 8463.195996380335, 8612.913870861212, 8625.52559609215, 8483.70307039263, 8520.654466222271, 8624.051354351017, 8804.911950880538, 8815.911840881638, 6391.041343798374, 6525.934740652628, 6533.836654083686, 6539.6403197824575, 6540.117084193668, 1777.8666600005051, 1806.3677712800416, 1807.3854889337688, 1819.4905426480607, 1825.9269629214682, 866.5927014303268, 985.9753506162403, 997.33677104726, 1006.5219020964983, 1015.6242190389603, 19728.30950916711, 20059.699104513445, 20970.47573810667, 21447.67828482574, 21519.354419367442, 831.8793774902605, 962.6726912849665, 963.5471328475745, 964.0319100500855, 986.1075726467558, 11016.669499915015, 11261.887381126084, 11363.715907102389, 11384.21086833731, 11408.315501069963, 499268.0, 576260.0, 593013.0349348226, 593111.0, 595435.0, 77877.61061194658, 88667.66998494898, 126279.3686031564, 127821.0, 131058.68941310464, 975.6146322202691, 1006.833872411044, 1049.396596956753, 1050.1179009631794, 1050.5010120192846, 16520.75218871742, 18523.44429667112, 18703.158357873657, 18947.90526047361, 18955.431337059912, 43194.784026079666, 51154.232686508985, 51399.74300128625, 51546.74226628843, 51650.48349516457, 59422.70288648529, 70232.64883675754, 71045.0, 71070.2232444193, 72343.93427549275, 6782.389584937379, 7602.543847369176, 7708.706744618936, 8084.070331911906, 8126.309263712651, 12682.302473364054, 12968.416421261254, 12992.415341309475, 13211.273379964194, 13226.801597975846, 11202.775944481227, 11284.492197850952, 11376.772464550828, 11451.656450306504, 11660.591879284184, 10201.74495637615, 10912.454377281263, 11027.944860275968, 11325.603603873826, 11437.970582647602, 11328.603498877499, 11458.083353331547, 11472.024877885473, 11630.883691163319, 11765.647030589096, 7803.609819509115, 7826.413019023482, 7870.016247969004, 7941.682332706627, 8051.866255442195, 11127.666170014912, 11203.943980280046, 11300.886991130312, 11533.423328833356, 11547.711307217387], "value": 9719.107581393027, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.732324873401, 1363.7954306853965, 1536.0553259745252, 1683.5454427304628, 1909.1218039701728, 7930.801729956754, 8466.44968077076, 8476.491410515366, 8481.109483504235, 8494.023187333458, 8534.786630334245, 8722.0, 8820.117988201177, 8972.551372431384, 8973.012968573468, 2399.4121440247122, 2427.7693619106244, 2429.854208747472, 2430.1858877276136, 2441.182203961666, 2372.5729368713623, 2399.6760437341, 2414.541237164933, 2417.637354396837, 2420.818438617102, 2378.4053986503372, 2407.16952651335, 2411.8191135664806, 2415.1546958564536, 2424.3939015246187, 2371.34787933318, 2388.0686532252435, 2395.2335252719135, 2403.8918248678765, 2404.0984630763464, 2236.6533187355985, 2263.6151854184827, 2264.615015447378, 2265.501589650291, 2268.1267711931027, 8856.601452934568, 8889.822203555957, 8960.283177345797, 8971.506567138806, 8980.012198658142, 5179.766910489037, 5268.6575372600655, 5427.240186373911, 5446.128619420913, 5477.370102438231, 3522.559680039995, 3654.7441679082476, 3723.8510459581585, 3738.980950286842, 3740.8503659853573, 1081.9080378167864, 1085.0505807418508, 1085.7882712870994, 1108.4568561404935, 1108.5011744714911, 11767.293962362286, 11847.94076029623, 11904.761904761855, 11937.641870743892, 11964.760704785942, 4272.764997925144, 4425.336199570091, 4623.306504024423, 4647.790849411785, 4673.6728429009645, 9280.72157835266, 9283.11810378013, 9287.024862389488, 9303.209227215628, 9316.720498385059, 4479.014616784302, 5195.454477279907, 5268.81559145428, 5279.762410691528, 5289.629725919226, 4185.518665353462, 4444.11117776446, 4624.491305956342, 4624.537546245416, 4952.083864485082, 3086.42901063304, 3747.1756213632957, 3788.8295026723863, 3821.2548553032448, 3826.043489127718, 1042.3276986343917, 1048.2400259811727, 1054.0724162736742, 1058.5818601652459, 1064.4677661169414, 853.2406158518924, 863.9913600864037, 896.2784958108655, 913.2602591900448, 928.4243768863348, 5418.02475554404, 5473.425290344457, 5822.475977162076, 6071.635701857903, 6081.513478921675, 800.203797221768, 878.8549889268327, 939.8637197606308, 947.303731757158, 1001.0790073132736, 3932.4297976793778, 4019.7422810552343, 4021.5174179098694, 4062.72487407053, 4126.731762435432, 10213.846792298122, 10838.945805271082, 11012.229143960007, 11033.841446648146, 11050.281731687415, 10202.897971020195, 10970.506327215295, 10981.913436447208, 10996.450177491093, 11019.944900275448, 7920.405969552191, 8157.95921020403, 8296.70961516344, 8321.875171872427, 8343.624536895853, 5995.340512543529, 6001.2498437695285, 6038.8792224154395, 6137.938620613737, 6153.323134455296, 9623.711288661352, 10220.875703672738, 10245.48772561354, 10289.228307876789, 10307.536160872929, 9441.811163776823, 10205.540750666089, 10307.763068431836, 10308.896911030793, 10329.586816527255, 7055.647217638995, 7147.964260178665, 7300.415966722756, 7304.853902922018, 7305.780826575211, 8088.110307866013, 8298.128696486781, 8354.665813367641, 8407.411481196503, 8415.789605259917, 7494.887576686463, 7560.773176804704, 7574.0, 7593.696252149852, 7628.313451789366, 7158.391536719437, 7313.268673132644, 7395.926040739524, 7457.776266712007, 7461.477696561188, 6824.385805277549, 7047.189573199148, 7128.643567821692, 7135.964320178366, 7150.284971502808, 4908.852734417973, 4994.550490455877, 5011.974940125276, 5074.391073071255, 5204.2193670948145, 4952.207646776643, 5055.266986287042, 5081.517255860687, 5146.871328216825, 5246.160614301693], "value": 4558.10593072834, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.7888667376173, 304.1635502368486, 313.8760189725058, 320.46642340503064, 321.43106701139, 4627.699199552027, 5316.746602717825, 5358.89282214356, 5365.624406291561, 5367.651102678333, 4799.009030608163, 4989.3513843200335, 5028.597712183026, 5199.376074871011, 5357.624966252364, 3782.527184101994, 4087.509498860141, 4096.456565071207, 4103.2409004334295, 4134.937975930364, 4100.1594673092095, 4106.384042393635, 4106.38404239365, 4133.0, 4140.358244472111, 3349.866005359783, 3812.7972503097394, 4060.634542891139, 4065.471488706479, 4097.873084901661, 3463.1515278756615, 3960.9009774755573, 4065.186962607461, 4073.246449406869, 4079.1841631673637, 3096.9535456968165, 3288.638249792521, 3493.3188028334484, 3510.80690562019, 3510.859565617372, 3912.315344814646, 4493.707908985905, 4916.262560615901, 5193.350831146106, 5224.242484839715, 3677.7425580209397, 3729.2099792099943, 4565.497795242521, 4611.1930412177735, 4624.838130665411, 5268.762905669254, 5594.692291923942, 5595.832125036255, 5619.775208991636, 5635.408282130358, 3523.964760352415, 3773.811309434517, 3801.1257410795397, 3805.562360328542, 3821.6942644588366, 4434.5787150221, 5462.99999999996, 5873.588848780587, 5921.703914804242, 5941.346451890288, 3832.0419895026243, 3858.575556688762, 3869.168128852254, 3872.9930218143486, 3878.3600705883778, 3907.8632247871756, 3931.938376638349, 3953.3872249801325, 3962.5244970603135, 3965.345717956505, 3739.700823934079, 4046.037671729209, 4420.226460369487, 4647.558481944277, 4653.789450011218, 5619.831405057855, 5638.436156384329, 5641.12562552805, 5642.7178641067785, 5689.34572524165, 1089.7657003744232, 1215.1372525506813, 1260.590308149855, 1271.288078675926, 1346.0, 709.5175280808996, 866.3285953386176, 877.5875338590852, 900.4642237868458, 906.805036917066, 5253.185756207794, 5266.8419947401635, 5267.78928842842, 5271.578273738091, 5327.696827966685, 5119.078565858107, 5590.524805391506, 5765.250517432707, 5792.9420705793245, 5834.795782147605, 4058.918821623551, 4952.5542701157065, 5280.445553216934, 5296.4703529647495, 5321.281626980308, 2990.685977972325, 2994.215379696903, 3002.354493783847, 3011.8192908425563, 3012.472817256971, 5599.80400685974, 5894.263217097863, 5964.552658550626, 5988.221531201003, 5988.640681559121, 5292.708901010405, 5327.467253274642, 5825.7087145642545, 5834.737436815353, 5847.707614619252, 5039.547909681806, 5735.283089613798, 5904.4095590441475, 5914.76340946366, 5920.319163296103, 5314.202869569595, 5658.632188907708, 5667.546596272371, 5709.429057094258, 5719.370869204301, 4303.892402689958, 4795.352627395397, 5314.28257185275, 5604.85987850307, 5644.802431915027, 4761.500127459839, 4907.386576677915, 5392.892142157213, 5441.156620723794, 5917.792877249448, 3565.8442278584157, 3852.1910398816563, 3874.709396795308, 3898.3567711327314, 3923.2545816294814, 5525.696086715269, 5554.305711786027, 5827.921834460638, 5830.221782356252, 5835.328937172279, 5390.0921831673295, 5659.9151012735665, 5688.232088667977, 5717.17101020358, 5729.828105156852, 5123.541609413705, 5234.371875374979, 5651.15232715096, 5669.54643628517, 5698.065496269342, 4575.702579332466, 4971.850844474671, 5299.231611416246, 5448.237246785374, 5458.508734213781, 5276.0, 5576.33084029919, 5643.576731745217, 5690.630109042899, 5722.394891218506, 4717.858464246078, 5501.202325662836, 5504.91742623869, 5508.724563771875, 5584.788746470715], "value": 4065.6839787643944, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.8679750847339, 760.7109298466581, 776.2858170483155, 821.1542111625029, 828.2752591482451, 15274.69450610987, 15865.682686346296, 15880.841191588095, 15931.52205433836, 15960.52118436446, 14303.282376348487, 14389.856101438982, 14400.991930564865, 14408.35162417691, 14933.850661493381, 684.4592771710343, 707.0984494769159, 707.324505097633, 711.7615598774416, 836.4395854777283, 696.1228851646911, 703.6165289916981, 833.8540755367817, 859.2352806002632, 863.6329559937033, 899.7525680437866, 906.0486489186361, 909.3861643390685, 911.0297533127232, 912.333996182787, 899.5097671768901, 899.7300809757079, 901.9143181397757, 902.6299217320867, 907.0340087806464, 535.0850046420586, 555.2420945409486, 678.9035707332672, 679.6805501414377, 680.7957612716166, 14827.406903723839, 15538.533843984698, 15550.144742039183, 15576.844231557767, 15872.841271587251, 14050.0, 14695.853041469556, 14775.113493190443, 14924.328405221791, 14969.326380312912, 10154.746131346701, 11932.343721095336, 11988.160828741991, 11988.640340789703, 12064.396780161045, 3822.789746563938, 3835.712321575865, 3849.788261645608, 3894.415837624351, 3920.3923391874305, 101578.95263142191, 101584.0, 103318.48340758323, 103672.0, 104319.0, 10482.68551943443, 11233.269837460539, 11255.493502792395, 11298.661040168809, 11698.70753231176, 23599.7640023601, 23788.52422951531, 23801.880990594935, 23816.0, 23893.044278229023, 7149.748793961036, 7997.920020799717, 8104.230098140667, 8176.182381761895, 8279.461834980708, 4370.759608221515, 4393.84621538251, 4442.73343599385, 4468.419105516327, 4604.516525764813, 3013.7588992880956, 3545.645435456434, 3632.891013269606, 3813.237352529505, 3817.2365526894732, 607.8784243151299, 649.8797722421367, 650.0541711809307, 699.5452955578914, 706.6113637499354, 600.2347007565389, 671.0906721392442, 671.3085521912438, 674.3122015544216, 689.3864460630043, 8901.910980890108, 9195.172434480928, 9436.103570160823, 9508.90491095094, 9544.236461083097, 632.8164832198703, 645.3320812958565, 648.8150877000032, 665.9933400665931, 669.9062131301622, 4242.172776308652, 4642.396488456547, 4651.488336283006, 4672.2057250267535, 4728.148933192058, 104828.95171048192, 145200.0, 150229.0, 151945.0, 154925.0, 136897.31551342178, 139812.0, 141678.0, 141985.0, 145910.54089459183, 111419.0, 116316.41841790736, 116703.0, 116743.0, 116886.41556792162, 8727.563621818757, 8922.795422618123, 8968.10318968098, 9049.411788233743, 9180.540972951192, 64703.029454557276, 65379.346206537324, 66176.67646647137, 67313.66343168252, 67503.0, 64393.67803161142, 96280.0371996299, 98014.50992745276, 98659.01340986499, 102385.48807255916, 11653.00949419309, 12138.453769580567, 12354.135210535176, 12407.255564665787, 12625.368731563569, 10896.0, 12827.294498802283, 12904.225746455242, 13205.20768753878, 13657.795133072814, 11881.287122772661, 12066.879331206927, 12349.32078735678, 12525.436355364212, 12603.92866606312, 11831.881681183078, 12273.140880138304, 12289.201201921847, 12534.185277956904, 12600.767468840053, 11685.182037257311, 11688.88311116878, 11765.588204412805, 11939.582114626293, 12212.816807747713, 9877.259205559469, 10183.13443357323, 10201.38791672502, 10375.533101010624, 10404.377233218964, 9332.553454214607, 9441.862789187084, 9882.85175722379, 10295.330803497749, 10366.274360794672], "value": 7697.166139872194, "unit": "score"}], "name": "dromaeo_css", "value": 7318.022948057645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 134.0, 177.0, 109.0, 111.0, 108.0, 109.0, 140.0, 111.0], "value": 125.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 185.0, 211.0, 266.0, 186.0, 215.0, 265.0, 180.0, 182.0], "value": 205.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 87.0, 60.0, 62.0, 58.0, 60.0, 82.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 91.0, 69.0, 65.0, 68.0, 89.0, 66.0, 67.0, 88.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 83.0, 87.0, 84.0, 97.0, 119.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 102.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 141.0, 101.0, 86.0, 86.0, 87.0, 87.0, 86.0, 85.0, 87.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 52.0, 55.0, 54.0, 54.0, 53.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 68.0, 68.0, 76.0, 70.0, 68.0, 69.0, 71.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 89.0, 87.0, 86.0, 88.0, 87.0, 89.0, 84.0, 111.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 138.0, 146.0, 153.0, 152.0, 156.0, 139.0, 150.0, 141.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 167.0, 169.0, 168.0, 168.0, 167.0, 168.0, 169.0, 170.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 66.0, 67.0, 66.0, 68.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1462.8999999999999, "alertThreshold": 2.0}]} 09:11:07 INFO - Return code: 0 09:11:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:07 INFO - # TBPL SUCCESS # 09:11:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:07 INFO - 2016-09-29 09:11:07.813 screenresolution[2571:26508] starting screenresolution argv=screenresolution get 09:11:07 INFO - 2016-09-29 09:11:07.816 screenresolution[2571:26508] Display 0: 1600x1200x32@60 09:11:07 INFO - 2016-09-29 09:11:07.821 screenresolution[2572:26510] starting screenresolution argv=screenresolution list 09:11:07 INFO - Available Modes on Display 0 09:11:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:08 INFO - Intel Iris: 09:11:08 INFO - Chipset Model: Intel Iris 09:11:08 INFO - Type: GPU 09:11:08 INFO - Bus: Built-In 09:11:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:08 INFO - Vendor: Intel (0x8086) 09:11:08 INFO - Device ID: 0x0a2e 09:11:08 INFO - Revision ID: 0x0009 09:11:08 INFO - Displays: 09:11:08 INFO - Display: 09:11:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:08 INFO - Display Serial Number: 1600x1200 60 09:11:08 INFO - Main Display: Yes 09:11:08 INFO - Mirror: Off 09:11:08 INFO - Online: Yes 09:11:08 INFO - Rotation: Supported 09:11:08 INFO - Return code: 0 09:11:08 INFO - Running post-action listener: _resource_record_post_action 09:11:08 INFO - [mozharness: 2016-09-29 16:11:08.057927Z] Finished run-tests step (success) 09:11:08 INFO - Running post-run listener: _resource_record_post_run 09:11:08 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 237568; Write bytes: 706985472; Read time: 1; Write time: 2002 09:11:08 INFO - TinderboxPrint: CPU usage
53.5% 09:11:08 INFO - TinderboxPrint: I/O read bytes / time
237,568 / 1 09:11:08 INFO - TinderboxPrint: I/O write bytes / time
706,985,472 / 2,002 09:11:08 INFO - TinderboxPrint: CPU idle
2,254.4 (46.5%) 09:11:08 INFO - TinderboxPrint: CPU system
972.4 (20.1%) 09:11:08 INFO - TinderboxPrint: CPU user
1,622.9 (33.5%) 09:11:08 INFO - TinderboxPrint: Swap in / out
406,806,528 / 0 09:11:08 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397511168; Write bytes: 391881216; Read time: 29724; Write time: 822 09:11:08 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 237568; Write bytes: 296274944; Read time: 1; Write time: 1132 09:11:08 INFO - Running post-run listener: _upload_blobber_files 09:11:08 INFO - Blob upload gear active. 09:11:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:11:08 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'] 09:11:08 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 09:11:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:11:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:11:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:10 INFO - (blobuploader) - INFO - Done attempting. 09:11:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:11:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:11:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:11 INFO - (blobuploader) - INFO - Done attempting. 09:11:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:14 INFO - (blobuploader) - INFO - Done attempting. 09:11:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:14 INFO - Return code: 0 09:11:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0fd269a485f2a82ba3d7c0a564b53c826edded45e92d1f3680f2e85e77959d572876511dd60ebfd78b936ef8b03b499f196f508fe452758de4fd2405d99c08e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0c730e9e25192becf568a3b02bcea132e8b2a88f50284c80961f4c3dcb05dfbb604849f56c26ad42910a44063b1a52c4c0a41cd755127e18accee7a9f0f9f7bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d462627ac2c659c13004a1ee2dba16b7bfe71ca0862e6a869baf264e30ac9bedcb8485543d90ad57210451f791e8a739fadbc8e1f56b3a2c445b0870f58a3be"} 09:11:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:14 INFO - Contents: 09:11:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0fd269a485f2a82ba3d7c0a564b53c826edded45e92d1f3680f2e85e77959d572876511dd60ebfd78b936ef8b03b499f196f508fe452758de4fd2405d99c08e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0c730e9e25192becf568a3b02bcea132e8b2a88f50284c80961f4c3dcb05dfbb604849f56c26ad42910a44063b1a52c4c0a41cd755127e18accee7a9f0f9f7bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d462627ac2c659c13004a1ee2dba16b7bfe71ca0862e6a869baf264e30ac9bedcb8485543d90ad57210451f791e8a739fadbc8e1f56b3a2c445b0870f58a3be"} 09:11:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:14 INFO - Copying logs to upload dir... 09:11:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.643917 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-29 09:11:14.650812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 09:11:14.659368) ========= 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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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/0fd269a485f2a82ba3d7c0a564b53c826edded45e92d1f3680f2e85e77959d572876511dd60ebfd78b936ef8b03b499f196f508fe452758de4fd2405d99c08e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0c730e9e25192becf568a3b02bcea132e8b2a88f50284c80961f4c3dcb05dfbb604849f56c26ad42910a44063b1a52c4c0a41cd755127e18accee7a9f0f9f7bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d462627ac2c659c13004a1ee2dba16b7bfe71ca0862e6a869baf264e30ac9bedcb8485543d90ad57210451f791e8a739fadbc8e1f56b3a2c445b0870f58a3be"} build_url:https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011686 build_url: 'https://queue.taskcluster.net/v1/task/ajdy96sfR4etZwwbrMzK1Q/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/0fd269a485f2a82ba3d7c0a564b53c826edded45e92d1f3680f2e85e77959d572876511dd60ebfd78b936ef8b03b499f196f508fe452758de4fd2405d99c08e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0c730e9e25192becf568a3b02bcea132e8b2a88f50284c80961f4c3dcb05dfbb604849f56c26ad42910a44063b1a52c4c0a41cd755127e18accee7a9f0f9f7bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d462627ac2c659c13004a1ee2dba16b7bfe71ca0862e6a869baf264e30ac9bedcb8485543d90ad57210451f791e8a739fadbc8e1f56b3a2c445b0870f58a3be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 09:11:14.695172) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:11:14.695506) ========= 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.n4D2uPQ1VX/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.DInOcMYwoc/Listeners TMPDIR=/var/folders/qc/ph39qp3s3z90v747tyg95m6m00000w/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.005346 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:11:14.743613) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 09:11:14.746946) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 09:11:14.747248) ========= ========= Total master_lag: 0.19 =========