builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0056 starttime: 1475575745.08 results: success (0) buildid: 20161004011300 builduid: bfe708bd2494427b8b87f4d4cddaf43c revision: 13feb326ae280eb9080ec7104e127946b7666c44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.084425) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.085194) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.085677) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.112805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.113329) ========= 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.PFGC8vmuvo/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.CljYH41lqW/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-04 03:09:05-- 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% 168M=0s 2016-10-04 03:09:05 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136206 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.272907) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.273260) ========= 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.PFGC8vmuvo/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.CljYH41lqW/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.028383 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:05.324469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-04 03:09:05.324968) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 13feb326ae280eb9080ec7104e127946b7666c44 --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 13feb326ae280eb9080ec7104e127946b7666c44 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PFGC8vmuvo/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.CljYH41lqW/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-04 03:09:05,393 truncating revision to first 12 chars 2016-10-04 03:09:05,394 Setting DEBUG logging. 2016-10-04 03:09:05,394 attempt 1/10 2016-10-04 03:09:05,394 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/13feb326ae28?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 03:09:07,386 unpacking tar archive at: autoland-13feb326ae28/testing/mozharness/ program finished with exit code 0 elapsedTime=2.243056 ========= master_lag: 1.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-04 03:09:08.906154) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:08.906550) ========= script_repo_revision: 13feb326ae280eb9080ec7104e127946b7666c44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:08.907114) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:08.907386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 03:09:08.927195) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-04 03:09:08.927624) ========= /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.PFGC8vmuvo/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.CljYH41lqW/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:09 INFO - MultiFileLogger online at 20161004 03:09:09 in /builds/slave/test 03:09:09 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 03:09:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:09:09 INFO - {'append_to_log': False, 03:09:09 INFO - 'base_work_dir': '/builds/slave/test', 03:09:09 INFO - 'blob_upload_branch': 'Autoland', 03:09:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:09:09 INFO - 'branch': 'Autoland', 03:09:09 INFO - 'buildbot_json_path': 'buildprops.json', 03:09:09 INFO - 'config_files': ('talos/mac_config.py',), 03:09:09 INFO - 'default_actions': ('clobber', 03:09:09 INFO - 'read-buildbot-config', 03:09:09 INFO - 'download-and-extract', 03:09:09 INFO - 'populate-webroot', 03:09:09 INFO - 'create-virtualenv', 03:09:09 INFO - 'install', 03:09:09 INFO - 'run-tests'), 03:09:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:09:09 INFO - 'download_minidump_stackwalk': True, 03:09:09 INFO - 'download_symbols': 'ondemand', 03:09:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:09:09 INFO - 'tooltool.py': '/tools/tooltool.py', 03:09:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:09:09 INFO - '/tools/misc-python/virtualenv.py')}, 03:09:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:09:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:09:09 INFO - 'installer_path': 'installer.exe', 03:09:09 INFO - 'log_level': 'info', 03:09:09 INFO - 'log_name': 'talos', 03:09:09 INFO - 'log_to_console': True, 03:09:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:09:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:09:09 INFO - 'opt_config_files': (), 03:09:09 INFO - 'pip_index': False, 03:09:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:09 INFO - 'cmd': ('bash', 03:09:09 INFO - '-c', 03:09:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:09 INFO - 'enabled': True, 03:09:09 INFO - 'halt_on_failure': False, 03:09:09 INFO - 'name': 'check_screen_resolution'},), 03:09:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:09 INFO - 'cmd': ('bash', 03:09:09 INFO - '-c', 03:09:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:09 INFO - 'enabled': True, 03:09:09 INFO - 'halt_on_failure': False, 03:09:09 INFO - 'name': 'check_screen_resolution'},), 03:09:09 INFO - 'run_cmd_checks_enabled': True, 03:09:09 INFO - 'sps_profile': False, 03:09:09 INFO - 'sps_profile_interval': 0, 03:09:09 INFO - 'suite': 'dromaeojs', 03:09:09 INFO - 'system_bits': '32', 03:09:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:09:09 INFO - 'title': 't-yosemite-r7-0056', 03:09:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:09:09 INFO - 'use_talos_json': True, 03:09:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:09:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:09:09 INFO - 'work_dir': 'build'} 03:09:09 INFO - [mozharness: 2016-10-04 10:09:09.130071Z] Running clobber step. 03:09:09 INFO - Running pre-action listener: _resource_record_pre_action 03:09:09 INFO - Running main action method: clobber 03:09:09 INFO - rmtree: /builds/slave/test/build 03:09:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:09:11 INFO - Running post-action listener: _resource_record_post_action 03:09:11 INFO - [mozharness: 2016-10-04 10:09:11.183891Z] Finished clobber step (success) 03:09:11 INFO - [mozharness: 2016-10-04 10:09:11.184012Z] Running read-buildbot-config step. 03:09:11 INFO - Running pre-action listener: _resource_record_pre_action 03:09:11 INFO - Running main action method: read_buildbot_config 03:09:11 INFO - Using buildbot properties: 03:09:11 INFO - { 03:09:11 INFO - "project": "", 03:09:11 INFO - "product": "firefox", 03:09:11 INFO - "script_repo_revision": "production", 03:09:11 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:09:11 INFO - "repository": "", 03:09:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:09:11 INFO - "buildid": "20161004011300", 03:09:11 INFO - "pgo_build": "False", 03:09:11 INFO - "basedir": "/builds/slave/test", 03:09:11 INFO - "buildnumber": 325, 03:09:11 INFO - "slavename": "t-yosemite-r7-0056", 03:09:11 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:09:11 INFO - "platform": "yosemite_r7", 03:09:11 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:09:11 INFO - "branch": "autoland", 03:09:11 INFO - "repo_path": "integration/autoland", 03:09:11 INFO - "revision": "13feb326ae280eb9080ec7104e127946b7666c44", 03:09:11 INFO - "stage_platform": "macosx64", 03:09:11 INFO - "builduid": "bfe708bd2494427b8b87f4d4cddaf43c", 03:09:11 INFO - "slavebuilddir": "test" 03:09:11 INFO - } 03:09:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 03:09:11 INFO - Running post-action listener: _resource_record_post_action 03:09:11 INFO - [mozharness: 2016-10-04 10:09:11.187259Z] Finished read-buildbot-config step (success) 03:09:11 INFO - [mozharness: 2016-10-04 10:09:11.187339Z] Running download-and-extract step. 03:09:11 INFO - Running pre-action listener: _resource_record_pre_action 03:09:11 INFO - Running main action method: download_and_extract 03:09:11 INFO - mkdir: /builds/slave/test/build/tests 03:09:11 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')]} 03:09:11 INFO - trying https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 03:09:11 INFO - Downloading https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/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 03:09:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/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 03:09:12 INFO - Downloaded 1199 bytes. 03:09:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:09:12 INFO - Using the following test package requirements: 03:09:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:09:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 03:09:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 03:09:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'jsshell-mac.zip'], 03:09:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 03:09:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:09:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 03:09:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 03:09:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 03:09:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 03:09:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:09:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:09:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:09:12 INFO - Fetch https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:09:13 INFO - Http code: 200 03:09:13 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 03:09:13 INFO - x-amz-cf-id: 467we3jLBntTuChn1S29hB-CLACx-uQMjMAOfUHNdqUEonyIJoJ5uw== 03:09:13 INFO - x-amz-version-id: SIReuhvoWKpXxEoA1bQWLFfSKBsDLKk1 03:09:13 INFO - Expected file size: 16476641 03:09:13 INFO - Obtained file size: 16476641 03:09:20 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 03:09:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:09:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:09:20 INFO - Fetch https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:09:20 INFO - Http code: 200 03:09:20 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 03:09:20 INFO - x-amz-cf-id: 8n88dkz5Y2JSz0SmywSeU_-nglNb3GHS6GOFBRfsQnDbzlKDTAfopQ== 03:09:20 INFO - x-amz-version-id: SIReuhvoWKpXxEoA1bQWLFfSKBsDLKk1 03:09:20 INFO - Expected file size: 16476641 03:09:20 INFO - Obtained file size: 16476641 03:09:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 03:09:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:09:27 INFO - Fetch https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 03:09:27 INFO - Http code: 200 03:09:27 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 03:09:27 INFO - x-amz-cf-id: Hy8Pg5ILd8CTBOwOZX6igt43dA8VTWUhl0_yB-HQ7O_gaoCw6hhdDA== 03:09:27 INFO - x-amz-version-id: KXl4wrgUjE7wykX4sBrIeYD6ZZ_6Z113 03:09:28 INFO - Expected file size: 14408522 03:09:28 INFO - Obtained file size: 14408522 03:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:28 INFO - trying https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:09:28 INFO - Downloading https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 03:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/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 03:09:31 INFO - Downloaded 111678943 bytes. 03:09:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:09:31 INFO - mkdir: /builds/slave/test/properties 03:09:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:09:31 INFO - Writing to file /builds/slave/test/properties/build_url 03:09:31 INFO - Contents: 03:09:31 INFO - build_url:https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:09:32 INFO - Running post-action listener: _resource_record_post_action 03:09:32 INFO - Running post-action listener: set_extra_try_arguments 03:09:32 INFO - [mozharness: 2016-10-04 10:09:32.055012Z] Finished download-and-extract step (success) 03:09:32 INFO - [mozharness: 2016-10-04 10:09:32.055134Z] Running populate-webroot step. 03:09:32 INFO - Running pre-action listener: _resource_record_pre_action 03:09:32 INFO - Running main action method: populate_webroot 03:09:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:09:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:09:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:09:32 INFO - '--tppagecycles', 03:09:32 INFO - '7'], 03:09:32 INFO - 'tests': ['tsvgm']}}, 03:09:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:09:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:09:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:09:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:09:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['damp', 'tps']}, 03:09:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'tests': ['damp', 'tps']}, 03:09:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['dromaeo_dom']}, 03:09:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:09:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['basic_compositor_video']}, 03:09:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:09:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['a11yr', 03:09:32 INFO - 'ts_paint', 03:09:32 INFO - 'tpaint', 03:09:32 INFO - 'sessionrestore', 03:09:32 INFO - 'sessionrestore_no_auto_restore', 03:09:32 INFO - 'tabpaint']}, 03:09:32 INFO - 'other-e10s': {'tests': ['a11yr', 03:09:32 INFO - 'ts_paint', 03:09:32 INFO - 'tpaint', 03:09:32 INFO - 'sessionrestore', 03:09:32 INFO - 'sessionrestore_no_auto_restore', 03:09:32 INFO - 'tabpaint']}, 03:09:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:09:32 INFO - 'ts_paint', 03:09:32 INFO - 'tpaint', 03:09:32 INFO - 'sessionrestore', 03:09:32 INFO - 'sessionrestore_no_auto_restore', 03:09:32 INFO - 'tabpaint']}, 03:09:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:09:32 INFO - 'ts_paint', 03:09:32 INFO - 'tpaint', 03:09:32 INFO - 'sessionrestore', 03:09:32 INFO - 'sessionrestore_no_auto_restore', 03:09:32 INFO - 'tabpaint']}, 03:09:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['a11yr', 03:09:32 INFO - 'ts_paint', 03:09:32 INFO - 'tpaint', 03:09:32 INFO - 'sessionrestore', 03:09:32 INFO - 'sessionrestore_no_auto_restore', 03:09:32 INFO - 'tabpaint']}, 03:09:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['a11yr', 03:09:32 INFO - 'ts_paint', 03:09:32 INFO - 'tpaint', 03:09:32 INFO - 'sessionrestore', 03:09:32 INFO - 'sessionrestore_no_auto_restore', 03:09:32 INFO - 'tabpaint']}, 03:09:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['tsvgx', 03:09:32 INFO - 'tsvgr_opacity', 03:09:32 INFO - 'tart', 03:09:32 INFO - 'tscrollx', 03:09:32 INFO - 'cart']}, 03:09:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:09:32 INFO - 'tsvgr_opacity', 03:09:32 INFO - 'tart', 03:09:32 INFO - 'tscrollx', 03:09:32 INFO - 'cart']}, 03:09:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'talos_options': ['--disable-e10s'], 03:09:32 INFO - 'tests': ['tp5o']}, 03:09:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'tests': ['tp5o']}, 03:09:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'talos_options': ['--disable-e10s', 03:09:32 INFO - '--xperf_path', 03:09:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:32 INFO - 'tests': ['tp5n']}, 03:09:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:32 INFO - 'talos_options': ['--xperf_path', 03:09:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:32 INFO - 'tests': ['tp5n']}}, 03:09:32 INFO - 'talos.zip': {'path': '', 03:09:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:09:32 INFO - Running post-action listener: _resource_record_post_action 03:09:32 INFO - [mozharness: 2016-10-04 10:09:32.073982Z] Finished populate-webroot step (success) 03:09:32 INFO - [mozharness: 2016-10-04 10:09:32.074060Z] Running create-virtualenv step. 03:09:32 INFO - Running pre-action listener: _resource_record_pre_action 03:09:32 INFO - Running main action method: create_virtualenv 03:09:32 INFO - Creating virtualenv /builds/slave/test/build/venv 03:09:32 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 03:09:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:09:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:09:32 INFO - Using real prefix '/tools/python27' 03:09:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:09:32 INFO - Installing distribute.............................................................................................................................................................................................done. 03:09:35 INFO - Installing pip.................done. 03:09:35 INFO - Return code: 0 03:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:09:36 INFO - Reading from file tmpfile_stdout 03:09:36 INFO - Output received: 03:09:36 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) 03:09:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:09:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:36 INFO - 'HOME': '/Users/cltbld', 03:09:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:36 INFO - 'LOGNAME': 'cltbld', 03:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:36 INFO - 'MOZ_NO_REMOTE': '1', 03:09:36 INFO - 'NO_EM_RESTART': '1', 03:09:36 INFO - 'PAGER': '/bin/cat', 03:09:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:36 INFO - 'PWD': '/builds/slave/test', 03:09:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:36 INFO - 'SHELL': '/bin/bash', 03:09:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:36 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:36 INFO - 'USER': 'cltbld', 03:09:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:36 INFO - 'XPC_FLAGS': '0x0', 03:09:36 INFO - 'XPC_SERVICE_NAME': '0', 03:09:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:36 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 03:09:36 INFO - Cleaning up... 03:09:36 INFO - Return code: 0 03:09:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:09:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:36 INFO - 'HOME': '/Users/cltbld', 03:09:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:36 INFO - 'LOGNAME': 'cltbld', 03:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:36 INFO - 'MOZ_NO_REMOTE': '1', 03:09:36 INFO - 'NO_EM_RESTART': '1', 03:09:36 INFO - 'PAGER': '/bin/cat', 03:09:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:36 INFO - 'PWD': '/builds/slave/test', 03:09:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:36 INFO - 'SHELL': '/bin/bash', 03:09:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:36 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:36 INFO - 'USER': 'cltbld', 03:09:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:36 INFO - 'XPC_FLAGS': '0x0', 03:09:36 INFO - 'XPC_SERVICE_NAME': '0', 03:09:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:36 INFO - Downloading/unpacking psutil>=3.1.1 03:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:09:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:39 INFO - Installing collected packages: psutil 03:09:39 INFO - Running setup.py install for psutil 03:09:40 INFO - building 'psutil._psutil_osx' extension 03:09:40 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 03:09:40 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 03:09:40 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 03:09:40 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 03:09:40 INFO - building 'psutil._psutil_posix' extension 03:09:40 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 03:09:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:09:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:09:40 INFO - ^ 03:09:40 INFO - 1 warning generated. 03:09:40 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 03:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:41 INFO - Successfully installed psutil 03:09:41 INFO - Cleaning up... 03:09:41 INFO - Return code: 0 03:09:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:09:41 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')]} 03:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:41 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')]} 03:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:09:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:41 INFO - 'HOME': '/Users/cltbld', 03:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:41 INFO - 'LOGNAME': 'cltbld', 03:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:41 INFO - 'MOZ_NO_REMOTE': '1', 03:09:41 INFO - 'NO_EM_RESTART': '1', 03:09:41 INFO - 'PAGER': '/bin/cat', 03:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:41 INFO - 'PWD': '/builds/slave/test', 03:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:41 INFO - 'SHELL': '/bin/bash', 03:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:41 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:41 INFO - 'USER': 'cltbld', 03:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:41 INFO - 'XPC_FLAGS': '0x0', 03:09:41 INFO - 'XPC_SERVICE_NAME': '0', 03:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:09:44 INFO - Installing collected packages: mozsystemmonitor 03:09:44 INFO - Running setup.py install for mozsystemmonitor 03:09:44 INFO - Successfully installed mozsystemmonitor 03:09:44 INFO - Cleaning up... 03:09:44 INFO - Return code: 0 03:09:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:44 INFO - 'HOME': '/Users/cltbld', 03:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:44 INFO - 'LOGNAME': 'cltbld', 03:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:44 INFO - 'MOZ_NO_REMOTE': '1', 03:09:44 INFO - 'NO_EM_RESTART': '1', 03:09:44 INFO - 'PAGER': '/bin/cat', 03:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:44 INFO - 'PWD': '/builds/slave/test', 03:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:44 INFO - 'SHELL': '/bin/bash', 03:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:44 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:44 INFO - 'USER': 'cltbld', 03:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:44 INFO - 'XPC_FLAGS': '0x0', 03:09:44 INFO - 'XPC_SERVICE_NAME': '0', 03:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:44 INFO - Downloading/unpacking jsonschema==2.5.1 03:09:44 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 03:09:44 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 03:09:44 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 03:09:44 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 03:09:44 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 03:09:44 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 03:09:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:09:46 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 03:09:46 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 03:09:46 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 03:09:46 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 03:09:46 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 03:09:46 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 03:09:47 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:47 INFO - warning: no files found matching '*.txt' 03:09:47 INFO - no previously-included directories found matching 'build' 03:09:47 INFO - no previously-included directories found matching 'dist' 03:09:47 INFO - no previously-included directories found matching '.git*' 03:09:47 INFO - Installing collected packages: jsonschema, functools32 03:09:47 INFO - Running setup.py install for functools32 03:09:47 INFO - warning: no files found matching '*.txt' 03:09:47 INFO - no previously-included directories found matching 'build' 03:09:47 INFO - no previously-included directories found matching 'dist' 03:09:47 INFO - no previously-included directories found matching '.git*' 03:09:47 INFO - Successfully installed jsonschema functools32 03:09:47 INFO - Cleaning up... 03:09:47 INFO - Return code: 0 03:09:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:47 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 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:47 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 03:09:47 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 03:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:47 INFO - 'HOME': '/Users/cltbld', 03:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:47 INFO - 'LOGNAME': 'cltbld', 03:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:47 INFO - 'MOZ_NO_REMOTE': '1', 03:09:47 INFO - 'NO_EM_RESTART': '1', 03:09:47 INFO - 'PAGER': '/bin/cat', 03:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:47 INFO - 'PWD': '/builds/slave/test', 03:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:47 INFO - 'SHELL': '/bin/bash', 03:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:47 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:47 INFO - 'USER': 'cltbld', 03:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:47 INFO - 'XPC_FLAGS': '0x0', 03:09:47 INFO - 'XPC_SERVICE_NAME': '0', 03:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:09:47 INFO - Cleaning up... 03:09:47 INFO - Return code: 0 03:09:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:47 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 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:47 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 03:09:47 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 03:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:47 INFO - 'HOME': '/Users/cltbld', 03:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:47 INFO - 'LOGNAME': 'cltbld', 03:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:47 INFO - 'MOZ_NO_REMOTE': '1', 03:09:47 INFO - 'NO_EM_RESTART': '1', 03:09:47 INFO - 'PAGER': '/bin/cat', 03:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:47 INFO - 'PWD': '/builds/slave/test', 03:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:47 INFO - 'SHELL': '/bin/bash', 03:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:47 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:47 INFO - 'USER': 'cltbld', 03:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:47 INFO - 'XPC_FLAGS': '0x0', 03:09:47 INFO - 'XPC_SERVICE_NAME': '0', 03:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:48 INFO - Downloading/unpacking blobuploader==1.2.4 03:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:09:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:09:50 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 03:09:50 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 03:09:50 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 03:09:50 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 03:09:50 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 03:09:50 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 03:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:09:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:09:51 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 03:09:51 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 03:09:51 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 03:09:51 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 03:09:51 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 03:09:51 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 03:09:51 INFO - Downloading docopt-0.6.1.tar.gz 03:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:09:52 INFO - Installing collected packages: blobuploader, requests, docopt 03:09:52 INFO - Running setup.py install for blobuploader 03:09:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:09:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:09:52 INFO - Running setup.py install for requests 03:09:52 INFO - Running setup.py install for docopt 03:09:52 INFO - Successfully installed blobuploader requests docopt 03:09:52 INFO - Cleaning up... 03:09:52 INFO - Return code: 0 03:09:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:52 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 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:52 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 03:09:52 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 03:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:52 INFO - 'HOME': '/Users/cltbld', 03:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:52 INFO - 'LOGNAME': 'cltbld', 03:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:52 INFO - 'MOZ_NO_REMOTE': '1', 03:09:52 INFO - 'NO_EM_RESTART': '1', 03:09:52 INFO - 'PAGER': '/bin/cat', 03:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:52 INFO - 'PWD': '/builds/slave/test', 03:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:52 INFO - 'SHELL': '/bin/bash', 03:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:52 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:52 INFO - 'USER': 'cltbld', 03:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:52 INFO - 'XPC_FLAGS': '0x0', 03:09:52 INFO - 'XPC_SERVICE_NAME': '0', 03:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-OimRLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-uJf6Jc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-MtdSBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-BuQfM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-d00aNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-UcBXTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Br9GTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-wqJ0ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-B6ZMuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-NTO8jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-gkfPVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-zAM7bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-GgC7ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-4JsVPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-D33mYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-xEW78j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-5bOsy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:54 INFO - Running setup.py install for manifestparser 03:09:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:55 INFO - Running setup.py install for mozcrash 03:09:55 INFO - Running setup.py install for mozdebug 03:09:55 INFO - Running setup.py install for mozdevice 03:09:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:55 INFO - Running setup.py install for mozfile 03:09:55 INFO - Running setup.py install for mozhttpd 03:09:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:55 INFO - Running setup.py install for mozinfo 03:09:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:55 INFO - Running setup.py install for mozInstall 03:09:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Running setup.py install for mozleak 03:09:56 INFO - Running setup.py install for mozlog 03:09:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Running setup.py install for moznetwork 03:09:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Running setup.py install for mozprocess 03:09:56 INFO - Running setup.py install for mozprofile 03:09:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:56 INFO - Running setup.py install for mozrunner 03:09:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:57 INFO - Running setup.py install for mozscreenshot 03:09:57 INFO - Running setup.py install for moztest 03:09:57 INFO - Running setup.py install for mozversion 03:09:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:57 INFO - Cleaning up... 03:09:57 INFO - Return code: 0 03:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:57 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 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:09:57 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 03:09:57 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 03:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:09:57 INFO - 'HOME': '/Users/cltbld', 03:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:09:57 INFO - 'LOGNAME': 'cltbld', 03:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:57 INFO - 'MOZ_NO_REMOTE': '1', 03:09:57 INFO - 'NO_EM_RESTART': '1', 03:09:57 INFO - 'PAGER': '/bin/cat', 03:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:57 INFO - 'PWD': '/builds/slave/test', 03:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:09:57 INFO - 'SHELL': '/bin/bash', 03:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:09:57 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:09:57 INFO - 'USER': 'cltbld', 03:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:57 INFO - 'XPC_FLAGS': '0x0', 03:09:57 INFO - 'XPC_SERVICE_NAME': '0', 03:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:57 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-kwo4lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:57 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)) 03:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:57 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Le9fah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:57 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)) 03:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:57 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-zHL1fA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-sGGyDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-7alA3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-PaAzeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-lI2XXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-6IQCI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ot7e04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-aEC5jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-VIJLG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:58 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)) 03:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:58 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-BoDy1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:59 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)) 03:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-sGBMWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:59 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)) 03:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-vqZ7bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:59 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)) 03:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-yObEVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:59 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)) 03:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-_VUKad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:59 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)) 03:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-sk0iZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:59 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)) 03:09:59 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)) 03:09:59 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)) 03:09:59 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)) 03:09:59 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)) 03:09:59 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)) 03:09:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09:59 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 03:09:59 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 03:09:59 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 03:09:59 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 03:09:59 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 03:09:59 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 03:10:01 INFO - Downloading blessings-1.6.tar.gz 03:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:10:02 INFO - Installing collected packages: blessings 03:10:02 INFO - Running setup.py install for blessings 03:10:02 INFO - Successfully installed blessings 03:10:02 INFO - Cleaning up... 03:10:02 INFO - Return code: 0 03:10:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:02 INFO - Reading from file tmpfile_stdout 03:10:02 INFO - Current package versions: 03:10:02 INFO - blessings == 1.6 03:10:02 INFO - blobuploader == 1.2.4 03:10:02 INFO - docopt == 0.6.1 03:10:02 INFO - functools32 == 3.2.3-2 03:10:02 INFO - jsonschema == 2.5.1 03:10:02 INFO - manifestparser == 1.1 03:10:02 INFO - mozInstall == 1.12 03:10:02 INFO - mozcrash == 0.17 03:10:02 INFO - mozdebug == 0.1 03:10:02 INFO - mozdevice == 0.48 03:10:02 INFO - mozfile == 1.2 03:10:02 INFO - mozhttpd == 0.7 03:10:02 INFO - mozinfo == 0.9 03:10:02 INFO - mozleak == 0.1 03:10:02 INFO - mozlog == 3.4 03:10:02 INFO - moznetwork == 0.27 03:10:02 INFO - mozprocess == 0.23 03:10:02 INFO - mozprofile == 0.28 03:10:02 INFO - mozrunner == 6.12 03:10:02 INFO - mozscreenshot == 0.1 03:10:02 INFO - mozsystemmonitor == 0.3 03:10:02 INFO - moztest == 0.7 03:10:02 INFO - mozversion == 1.4 03:10:02 INFO - psutil == 3.1.1 03:10:02 INFO - requests == 1.2.3 03:10:02 INFO - wsgiref == 0.1.2 03:10:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:02 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')]} 03:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:03 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')]} 03:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:03 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 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:10:03 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 03:10:03 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 03:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:03 INFO - 'HOME': '/Users/cltbld', 03:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:03 INFO - 'LOGNAME': 'cltbld', 03:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:03 INFO - 'MOZ_NO_REMOTE': '1', 03:10:03 INFO - 'NO_EM_RESTART': '1', 03:10:03 INFO - 'PAGER': '/bin/cat', 03:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:03 INFO - 'PWD': '/builds/slave/test', 03:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:03 INFO - 'SHELL': '/bin/bash', 03:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:10:03 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:03 INFO - 'USER': 'cltbld', 03:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:03 INFO - 'XPC_FLAGS': '0x0', 03:10:03 INFO - 'XPC_SERVICE_NAME': '0', 03:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 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)) 03:10:03 INFO - Cleaning up... 03:10:03 INFO - Return code: 0 03:10:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:10:03 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')]} 03:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:03 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')]} 03:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050e6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10505c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d057fb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, '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.PFGC8vmuvo/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.CljYH41lqW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 03:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:04 INFO - 'HOME': '/Users/cltbld', 03:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:04 INFO - 'LOGNAME': 'cltbld', 03:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:04 INFO - 'MOZ_NO_REMOTE': '1', 03:10:04 INFO - 'NO_EM_RESTART': '1', 03:10:04 INFO - 'PAGER': '/bin/cat', 03:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:04 INFO - 'PWD': '/builds/slave/test', 03:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:04 INFO - 'SHELL': '/bin/bash', 03:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:10:04 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:04 INFO - 'USER': 'cltbld', 03:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:04 INFO - 'XPC_FLAGS': '0x0', 03:10:04 INFO - 'XPC_SERVICE_NAME': '0', 03:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:10:04 INFO - Cleaning up... 03:10:04 INFO - Return code: 0 03:10:04 INFO - Running post-action listener: _resource_record_post_action 03:10:04 INFO - Running post-action listener: _start_resource_monitoring 03:10:04 INFO - Starting resource monitoring. 03:10:04 INFO - [mozharness: 2016-10-04 10:10:04.369934Z] Finished create-virtualenv step (success) 03:10:04 INFO - [mozharness: 2016-10-04 10:10:04.370925Z] Running install step. 03:10:04 INFO - Running pre-action listener: _resource_record_pre_action 03:10:04 INFO - Running main action method: install 03:10:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:04 INFO - Reading from file tmpfile_stdout 03:10:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:10:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:10:04 INFO - Reading from file tmpfile_stdout 03:10:04 INFO - Output received: 03:10:04 INFO - Usage: mozinstall [options] installer 03:10:04 INFO - Options: 03:10:04 INFO - -h, --help show this help message and exit 03:10:04 INFO - -d DEST, --destination=DEST 03:10:04 INFO - Directory to install application into. [default: 03:10:04 INFO - "/builds/slave/test"] 03:10:04 INFO - --app=APP Application being installed. [default: firefox] 03:10:04 INFO - mkdir: /builds/slave/test/build/application 03:10:04 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'] 03:10:04 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 03:10:38 INFO - Reading from file tmpfile_stdout 03:10:38 INFO - Output received: 03:10:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:10:38 INFO - Running post-action listener: _resource_record_post_action 03:10:38 INFO - [mozharness: 2016-10-04 10:10:38.951760Z] Finished install step (success) 03:10:38 INFO - [mozharness: 2016-10-04 10:10:38.951884Z] Running run-tests step. 03:10:38 INFO - Running pre-action listener: _resource_record_pre_action 03:10:38 INFO - Running main action method: run_tests 03:10:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:10:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:10:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:10:38 INFO - 2016-10-04 03:10:38.966 screenresolution[1644:7544] starting screenresolution argv=screenresolution get 03:10:38 INFO - 2016-10-04 03:10:38.968 screenresolution[1644:7544] Display 0: 1600x1200x32@60 03:10:38 INFO - 2016-10-04 03:10:38.974 screenresolution[1645:7546] starting screenresolution argv=screenresolution list 03:10:38 INFO - Available Modes on Display 0 03:10:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:10:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:10:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:10:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:10:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:10:39 INFO - Intel Iris: 03:10:39 INFO - Chipset Model: Intel Iris 03:10:39 INFO - Type: GPU 03:10:39 INFO - Bus: Built-In 03:10:39 INFO - VRAM (Dynamic, Max): 1536 MB 03:10:39 INFO - Vendor: Intel (0x8086) 03:10:39 INFO - Device ID: 0x0a2e 03:10:39 INFO - Revision ID: 0x0009 03:10:39 INFO - Displays: 03:10:39 INFO - Display: 03:10:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:10:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:10:39 INFO - Display Serial Number: 1600x1200 60 03:10:39 INFO - Main Display: Yes 03:10:39 INFO - Mirror: Off 03:10:39 INFO - Online: Yes 03:10:39 INFO - Rotation: Supported 03:10:39 INFO - Return code: 0 03:10:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:10:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:10:39 INFO - Python 2.7.3 03:10:39 INFO - Return code: 0 03:10:39 INFO - grabbing minidump binary from tooltool 03:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:39 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 0x104efad30>, '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 0x104d20db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:10:39 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 03:10:39 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 03:10:39 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 03:10:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:10:39 INFO - Return code: 0 03:10:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:10:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:10:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:10:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:10:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:10:39 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/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 03:10:39 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/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0056 --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 03:10:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PFGC8vmuvo/Render', 03:10:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:39 INFO - 'HOME': '/Users/cltbld', 03:10:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:39 INFO - 'LOGNAME': 'cltbld', 03:10:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:10:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:39 INFO - 'MOZ_NO_REMOTE': '1', 03:10:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:10:39 INFO - 'NO_EM_RESTART': '1', 03:10:39 INFO - 'PAGER': '/bin/cat', 03:10:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:39 INFO - 'PWD': '/builds/slave/test', 03:10:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:10:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:39 INFO - 'SHELL': '/bin/bash', 03:10:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CljYH41lqW/Listeners', 03:10:39 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 03:10:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:39 INFO - 'USER': 'cltbld', 03:10:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:39 INFO - 'XPC_FLAGS': '0x0', 03:10:39 INFO - 'XPC_SERVICE_NAME': '0', 03:10:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:39 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/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 03:10:39 INFO - mozversion application_buildid: 20161004011300 03:10:39 INFO - mozversion application_changeset: 13feb326ae280eb9080ec7104e127946b7666c44 03:10:39 INFO - mozversion application_display_name: Nightly 03:10:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:10:39 INFO - mozversion application_name: Firefox 03:10:39 INFO - mozversion application_remotingname: firefox 03:10:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:10:39 INFO - mozversion application_vendor: Mozilla 03:10:39 INFO - mozversion application_version: 52.0a1 03:10:39 INFO - mozversion platform_buildid: 20161004011300 03:10:39 INFO - mozversion platform_changeset: 13feb326ae280eb9080ec7104e127946b7666c44 03:10:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:10:39 INFO - mozversion platform_version: 52.0a1 03:10:39 INFO - using testdate: 1475575839 03:10:39 INFO - actual date: 1475575839 03:10:39 INFO - starting webserver on 'localhost:49278' 03:10:39 INFO - SUITE-START | Running 2 tests 03:10:39 INFO - TEST-START | dromaeo_css 03:10:39 INFO - operating with platform_type : mac_ 03:10:39 INFO - Initialising browser for dromaeo_css test... 03:10:39 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpVxNQRx/profile http://localhost:49278/getInfo.html) 03:10:42 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 03:10:42 INFO - PROCESS | 1655 | colorDepth:24 03:10:42 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 03:10:42 INFO - PROCESS | 1655 | __metrics 03:10:42 INFO - PROCESS | 1655 | 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" 03:10:42 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:10:43 INFO - TEST-INFO | 1655: exit 0 03:10:43 INFO - Browser initialized. 03:10:43 INFO - Running cycle 1/1 for dromaeo_css test... 03:10:43 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpVxNQRx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:55 INFO - PROCESS | 1657 | RSS: Main: 235986944 03:10:55 INFO - PROCESS | 1657 | 03:11:46 INFO - PROCESS | 1657 | 1475575906727 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] 03:13:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 03:14:01 INFO - PROCESS | 1657 | RSS: Main: 406966272 03:14:01 INFO - PROCESS | 1657 | 03:17:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 03:17:04 INFO - PROCESS | 1657 | RSS: Main: 391434240 03:17:04 INFO - PROCESS | 1657 | 03:20:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 03:20:07 INFO - PROCESS | 1657 | RSS: Main: 342331392 03:20:07 INFO - PROCESS | 1657 | 03:23:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 03:23:10 INFO - PROCESS | 1657 | RSS: Main: 345546752 03:23:10 INFO - PROCESS | 1657 | 03:26:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 03:26:14 INFO - PROCESS | 1657 | RSS: Main: 330989568 03:26:14 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:29:18 INFO - PROCESS | 1657 | RSS: Main: 394178560 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | __start_tp_report 03:29:18 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 03:29:18 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 03:29:18 INFO - PROCESS | 1657 | |i|pagename|runs| 03:29:18 INFO - PROCESS | 1657 | |0;dojo.html;3322.76740628156;3342.2647017656113;3356.379069872074;3401.5407919930803;3412.0957946144263;5604.691741954191;5654.915176272354;5691.658500489973;5745.396733343002;5776.942230577693;5629.943700562993;5714.771409143629;5717.599768016241;5719;5748.942510574893;3917.5886531914166;5054.292399064134;5187.1181899077055;5209.479052094798;5214.113600687892;5304.549113325372;5307.363116426015;5328.0675881720545;5330.600204984622;5331.067063263913;5216.8695782605355;5258;5275.498827611371;5288.497592728685;5307.150855863063;5324.361076670824;5331.093714068617;5333.973330133324;5336.38631557372;5357.142857142839;5133;5153.330067091254;5190.844274671766;5259.631825772198;5264.92102618461;5646.265985421869;5709.229254050733;5763.308402991626;5764.33710123337;5811.767529298823;6297.307296197415;6299.937000630027;6300.621962682208;6313.526485513559;6314.968425157891;6177.227846519185;6265.7493700251935;6307.747690092437;6310.87378252437;6347.777827776052;5792.82621521355;5867.471927526501;5873.353931067664;5874.9118763218585;5875.030619947746;6263.275475168316;6294.370562943669;6307.653079080603;6312.274088479791;6318.052292156213;6044.1840351552855;6138.539609529304;6146.588539080381;6205.379462053849;6226.688665566703;6208.658523781145;6209.658468784261;6286.874262514815;6292.1505596743855;6301.40136687014;6206.193194884727;6283.340249273851;6292.276388215414;6297.590656607306;6302.369763023753;6074.4532992030045;6272.529560282997;6277.811665650038;6294.716737746812;6297.338779428185;4900.975495122501;4922;4928.950710492921;4929.088118698052;4930.950690493121;4462.196804575207;4483.887902802391;4499.775011249424;4523.638108951276;4531.252343363308;5772.884542309214;5773.826785196451;5773.826785196451;5789.9710501447225;5800.622959507618;5247.606429517725;6266.028765541349;6272.560920735505;6279.560430769802;6279.717412716439;5540.86147846299;5772.105323674838;5781.82654520365;5782.277215348082;5785.363610002896;4882.170031094723;5436.565074794007;5436.782528698887;5440.42875498067;5442.836714898559;6243.469305109116;6290;6305.905411418832;6340.9048864268;6375.203099612549;6329.272133704499;6330.873382532415;6332.841678958063;6343.873122537431;6348.968255158695;5613.691246981455;6229.96885015572;6234.812955611339;6298.527610429144;6336.809895703136;6243.313235543996;6243.531735119794;6257.217847769029;6267.561270711007;6277.780277690259;6164.8767024658355;6189.783357582463;6274.46667033289;6286.088517164894;6345.873082538231;6049.092636104443;6135.294441139326;6201.131841542097;6204.875902482016;6217.595856269328;6107.633541987495;6127.3259941407305;6128.136482154469;6130.428711073822;6209.906851397142;6033.311178787146;6066.488474766911;6066.998691470635;6073.632117382563;6099.84750381244;6140.904102378134;6157.784477543443;6160.722767475564;6206.534509911865;6216;5887.028640274307;5949.405059494016;5955.464008239279;5970.253718285215;6036.9698151508965 03:29:18 INFO - PROCESS | 1657 | |1;ext.html;477.30075439481175;482.8696252011959;515.3738208077186;523.0297797585479;523.9364090895483;30766.692333076662;31239.531407028884;32222.516662250055;40193.39709904354;41889.790551047205;42708.57291427085;43211;43273;43307.35038974418;43342;9860.60557577696;10011.549480273368;10040;10047.196224302075;10054.447005414699;9854.507274636275;9960.95019524905;9965.800683986316;9971.451570163637;9980.60077596899;9896.604135834594;9950.452725100116;9952.054554817318;9958.153556947633;9968.551415186297;9842.803143937117;9888.406695598287;9899.950500247525;9914.256430767686;9927.553260103312;9749.610015599368;9828.459434731087;9828.705138845846;9841.852372214422;9844.90155098447;40879.38680919789;41202;41225;41306.63013808216;41455.37816932749;3317.5189597508465;3319.2199833039217;3365.646607106243;3403.5745531808525;3782.2435512897255;31335;31421.37157256842;31769.14077732992;31928.75434926955;32273.870414535377;3183.6338821035647;3213.4537128687944;3220.806751594912;3245.9026229212927;3327.1515763480156;480053;481441;481523.59238203935;483339;483511.1648883501;61322.0801687975;61634;61675.691621541606;61678.69160654168;61793.69103154456;29480.557791633142;35389.93830185098;35721.64278357235;65393.019104713465;65571.50855863039;16516.091614961053;16672.83327166713;16677.166141692865;16691.165441727866;16711.164441777863;12684.873151268554;12801;12806.551770687982;12819.974402047814;12833;15681.294341754421;15717.607059823367;19334.613307733765;19337.4198774037;19363.934983575757;6797.388235058868;7917.406194535433;7931.730923052284;8052.188257642101;8211.876821847676;715.3168723868692;805.963731632078;895.8029233568604;977.3402953006693;1019.0879163148941;30115.849420753195;30785;31083.756649733758;31532;32576.859809907015;858.4634603372892;1064.7125276175389;1077.6820837852852;1091.3997301484153;1177.4289469607234;14804.259787010606;15337.923310383376;15457;15535.06789592628;15755;608420;611286;611977;618236;623761;58339.833203336544;58476.707616461645;58479.70760146172;58663.41336586665;59340.40659593435;1213.9635810925686;1237.4307818403636;1333.6065860571348;1360.9659758506116;1368.50733735855;27994.580081299206;28066.438671226868;28183.859080704464;30553.23616909506;31475.37049259048;50249.99500010053;51732;51748.482515174364;51755.741221293654;51768;569772.9068127963;574062;574532;574540;574853;26950.865245673645;26999.59500607453;27509.44981100407;27694.03070892509;28559.714402855705;47740.52259477361;48811.26783098328;48840.755796220794;49330.26004609862;49574.75212623914;33664.66335336615;34373.82813085918;35482.73554272178;35805.82097089498;39788.005299867735;39093.80453097716;39362.80318598389;39479.80260098681;40671.38992915049;41065.58934410737;33170.66829331676;38693.83918482337;38810.02974925649;39020.804895975336;39476;27551.724482754915;31959.201019974687;34780.82609586936;35389;35485.64514354926;22380.77619223787;25630.615540767278;25784.09755658618;25918.48163036691;26097.339208457313 03:29:18 INFO - PROCESS | 1657 | |2;jquery.html;285.23556867594846;350.5950627025786;361.2453613930093;371.7323527060517;401.63250625677506;14998.898651459907;15108.546743597695;16349.100799456026;21038.21175200106;21087.789122108774;19900.40298791035;20535.97320133991;20627.27804526842;21100.15599376027;21420.57158856822;5376.8118115865955;5651.8304450866435;5778.873119741652;5965.46310832025;5974.372690867462;4752.667313288071;5115.923261151086;5170;5965.522758179357;5973.253343332083;4873.48828373021;5640.943590564103;5936.43603857635;5945.04879219325;5946.851328716773;5689.943100569003;5835.79574714883;5838.270216222951;5846.736896839652;5847.912281315784;4885.658003939726;5099.36257967754;5118.283440318359;5118.948810511884;5220.4779522047875;24232.878835605887;24364.390890227707;26344.47311053768;26654.86672566625;26874.73125268742;2340.414896275931;2345.1674655497395;2351.188839850266;2378.2865140457793;2448.24104527597;14923.552293431214;14982.850171498254;15170.924145379202;15937.92031039849;16324.673506529922;1726.378503738653;1825.7443957845912;1826.7077267637114;1844.880082794614;1872.5505878589177;104538;114178.99999999917;119863;123944.38027809803;127220.99999999907;11012.724681882857;11245.269057511236;11494.195406321645;11757.176997610257;11989;14381.137131772128;14385.64035899111;14400.639984000483;14421.639459013399;14422.06256593318;8230.753077407688;8423.199796019368;8461.450005749606;8573.18554737299;8594.441361311494;8314.584270786436;8331.166883311742;8354.66581336752;8489.023762267418;8517.06312305646;4435.800388982503;4489.506154323022;4511.390962220124;4564.269716845289;4612.123696497655;1765.3203516646183;1875.1374367790968;1921.413968739548;1922.4521011511931;1925.5186203449139;739.6671497826;769.0118198115395;777.5723352156405;822.2640736540762;944.2398868910593;18095.638087238443;21032.894835525723;21162.83604401742;21171.576568468852;21244.36266911995;892.638481415028;949.9715008549755;950.5959967013991;978.0659470205936;983.8081574093127;8916.242119419921;10640.840387394195;11234.775304493864;11239.381833999045;11243.100551955824;530689.0396644054;570974;584874;590600;598365;82639;98834;128897;129268.3536582311;131142.34428827793;935.7192842147329;979.8040391921787;992.5235886774244;1012.9240306977006;1014.7767491151935;17817.287308508032;18326.541836454195;18564.907175464035;18580.88514689123;18779.530511736768;50746.746266268434;51708.48291517037;52537.68655783636;53582.464175357745;54126;73247.90128147976;73612.63193683997;76273.85589216277;76321;76434.08914777175;5950.4644581986095;8015.278624923785;8127.2279133482225;8195.95902020486;8213.507189568645;10276.588936442458;12789.42447589842;12979.221246725227;13355.933220333836;13380.264085475003;10838.891611083789;10906.61826836057;11456.656300311004;11461.025812806005;11593.710157246136;10594.735131621772;10724.839127412939;10970.067544258827;11186.720331991766;11401.771964560829;11166.218364714718;11337;11773.35246561413;11865.288082715064;11895.286282823059;7028.262032486719;7862.842743144991;7948.125706172201;8127.390445716476;8262.380321475794;9733;9754.122129008423;10440.63457778974;10844.783104337712;11102.444877756241 03:29:18 INFO - PROCESS | 1657 | |3;mootools.html;1361.5166615851372;1516.4010215964695;1720.9053502057382;1877.8216069473394;1941.2429152630477;8529.957350213248;9076.956150042746;9081.318901082412;9081.31890108242;9083.545822708862;7818.296353328214;8124.796880078001;8590.398672092955;8867;8878.467291962486;2343.5547246023284;2386.78518933296;2390.509945461185;2390.6653068570326;2392.7487613800563;2351.4709190432186;2355.893984770681;2391.4977854650456;2398.64020396941;2399.460121472672;2035.5318276796345;2370.3718514593647;2376.4415362389823;2389.223502361731;2393.664886915833;2338.75443078476;2352.5177338645535;2361.504084142332;2361.8937147828387;2370.8458950168183;2354.5754818335004;2354.658574506704;2356.4108972756812;2376.3940195250275;2376.560336337783;8768.956155219246;8967.865482017774;8974.955125224398;8981.416207946528;8989.595468203946;5264.815731449419;5435.483629055233;5435.809746658888;5449.1826226066405;5487.560995120421;3608.4948107265004;3609.747317687763;3703.3519134151684;3704.2591481703766;3717.423799311111;1058.7564860082225;1067.1089640150483;1072.131573425528;1079.9514021869036;1092.6995076353987;11999.220050696676;12095.879041209475;12101.878981210164;12104.87895121055;12114.333711645944;4452.577005184502;4539.137563862855;4828.493008234155;4873.366462359871;4878.36581244443;10131.341462804894;10133.08802207805;10149.797004060025;10163.847542286872;10176.440295783656;5403.459654034644;5414.512693857571;5415.566754659618;5423.322084739408;5428.430014848463;4239.130978149472;4402.801873915681;4448.754348782281;4899.995500922302;4956.355673762388;3854.1328201154515;3877.806109694504;4038.919221615551;4053.3717273822604;4072.6538244249573;1280.9743805123846;1286.3246795432285;1292.8965682745359;1300.0704496285202;1302.3813688497887;767.6545554500497;773.6905237904883;791.7228969860618;818.5579786915118;893.9317515568963;5847.181394604759;6004.6697431640805;6129.172561704202;6147.200863887666;6283.434490895841;882.2192359761657;913.2374467319862;959.6065613098596;972.4991629310911;975.1418751498636;3836.388716738976;3872.1287710264965;3940.6453419191835;3958.287508248485;3996.2606917720314;10658.413787241776;10822.621208257831;10913.618023369145;10916.07213386871;10936.343819370863;9155.404898681565;10891.237613366988;10900.781984360268;10904.727381815359;10934.289271197347;7743.728969486041;8237.217464340878;8351.707690230813;8362.038365587914;8391.916080839235;5856.560757943086;6112.258006468135;6119.969400152971;6167.167432396569;6174.073888916699;9653.131218190396;9916.801663966824;10219.1824654026;10228.846567301645;10287;10068.04353586408;10346.017128372792;10443.68668939933;10468.68593942183;10482.318649287772;6900.6204658744255;6975.372216500336;7204.387627051759;7204.89192662121;7288.67200975968;8214.917850821654;8477.74566762998;8488.193621605938;8584.442011269248;8591.6992905248;7507.211742766931;7632.236776322324;7656.157822639394;7726.845463090819;7769.8834517481155;7439.107307122963;7521.285477879593;7523.059617547807;7524.247575242432;7594.31651151399;6920.446364290945;7091.326323999212;7092.645367731696;7100.715971361295;7106.5736055836815;4468.02596835366;4711.623070154448;4900.402786523148;5031.195008798721;5123.3339665843205;4400.405945197357;4958.553730164303;5158.535731784157;5195.844124676266;5269.130593452038 03:29:18 INFO - PROCESS | 1657 | |4;prototype.html;298.6401386329473;298.8670041831383;305.1197295801612;311.79265788250814;313.2950860563732;4587.862364129077;4742.7946005107615;5138.792383789811;5206.739663016853;5263.34208223972;4451.910961780771;4891.5353041461;5071.518205770446;5156.716380599075;5197.53222210002;3393.9321213575713;4006.7579050494296;4006.9799651001704;4019.2765302245584;4049.777262250582;3352.2457447074335;3381.222318866662;3622.609078008515;3948.427478015686;3985.760854348734;3318.900432987014;3715.9442608360896;3882.669973052294;3960.940585891214;3967.6230758077936;3902.765834049938;3945.526536815586;3957.28768821612;3996.082859512572;4004.199160167949;3379.7465190110593;3392.2537041851;3393.3372647402844;3406.097384193191;3444.621091679913;4953.529414705597;5021.57316628087;5045.351190624914;5055.646104772667;5064.3163172971545;3443.674185438599;4458.732476051415;4476.865694029184;4483.910321793578;4558;5041.647084704072;5497.766719625905;5523.392426833045;5588.21764952907;5595.405309486777;3738.887833365003;3740.756850804689;3741.981290093532;3755.830987605564;3834.6357096075826;5448.346198456171;5626.015447296748;5650.63270887391;5657.510695294099;5804.970975145139;3503.386907291214;3551.2897420516;3791.976166435047;3822.7515211511163;3842.769433833979;3440.724742020632;3873.992761881931;3893.396523538856;3897.395903634941;3899.9610003900166;3970.50368703912;4257;4518.7573417310505;4570.200214962369;4570.794314255866;3887.2225554889137;5433.212184233343;5469.288992430958;5491.670499770026;5537.916931246034;1279.5969269680022;1292.6538332925709;1313.8584107904055;1324.3973991833693;1326.6683329167709;712.6329940080863;718.5868125827566;848.8514509960733;860.4192170285032;861.401326078382;4767.998720268713;4991.5258050485145;5233.241180028951;5244.344456942882;5253.3958594761325;5128.666636668605;5209.733765811809;5213.400458947193;5411.864703382368;5619.325680918316;4393.472783265965;4939.604831613462;5113.079645663816;5146.665466744725;5147.279380886679;2414.492956479159;2443.376938880574;2884.8990285339914;2890.3496713239774;2900.5939168516425;5661.207430959669;5677;5685.60200785941;5706.058500347479;5738.713064346766;5023.472535383805;5276.366835979707;5443.482869127427;5495.752691128909;5852.063669812809;4793.448753393404;5487.161800796698;5626.831195064155;5895.351511333664;5912.113183022495;4653.930191047205;5042.647014708935;5188.53544566343;5690.658560486218;5712.600117991867;4709.905801884011;5195;5562.443755624405;5668.744906479301;5717.514011309086;5388.73056347189;5523.7790488380015;5531.474509921551;5916.994111001226;5993.640381577119;3099.411111888734;3568.8750893718593;3598.928021439609;3693.6306369362846;3816.7709937403843;4701.247800351927;5679.138186033537;5680.886382272414;5694.4590263924865;5699.658020518782;5115;5482.259894914295;5521.757604538947;5572.331320241597;5626.11525139083;4633.397658304399;5304.867378315573;5406.432324605859;5548.389677135431;5601.831945041656;4558.726476411293;5060.51925067118;5400.270963419888;5405.648632838853;5419.945800541944;5337.866553336197;5370.275012873212;5374.811881584125;5379.65032272901;5380.811671591476;4603.608693261111;4765.594924431323;5429.267048948342;5430.484104010113;5517.97917385285 03:29:18 INFO - PROCESS | 1657 | |5;yui.html;601.8937875255533;747.2863415438259;767.8157242261859;776.491398134222;807.54373778815;15061.623459413506;15698.293576789043;15718;15720.449784257553;15735.055896646209;13177.616350283202;13253.271070091141;14119.294035298246;14156.71686566268;14182.503612373568;656.5830697507093;672.4183581202274;827.0365024746163;840.373921428535;854.1245223645751;856.6830272799073;860.7718954477068;862.211076864668;862.7541150772031;863.2575984653224;915.057490784493;915.1900567997337;916.1113719691888;916.2166347772659;918.5131880103556;702.9824254393656;715.5492040014809;880.9383343165981;891.6210610490516;911.4804561400005;675.8580698053414;676.4622125410261;677.4411110833582;678.2132726037772;678.6538865178794;14762.409503619841;14779;14788.482403115946;14800.925995370062;14808.777868331983;13668.17990920538;13937.303134843218;13983.23092229927;13994.720105597831;14116.717665646733;12658.62024139268;12825.23048617086;12853.67865803353;12899.93550032244;13330.533431329857;4491.550844915515;4531.501534831166;4557.635389168859;4568.406107206075;4766.713997160182;100434;101139.48290775644;107102;108437;111988.32017519744;10512.684619461428;10896.073833724064;10971.945140274247;11020.724481887857;11371.829422558669;19622.70565941512;22084.88957555202;22127.44681382978;22129.668054978865;22157.44606384821;7717.1896950819355;7826.726064587826;7913.010873640795;8050;8087.716929907425;4217.198732240864;4301.978490107529;4336;4390.956090439118;4395.7582332972;3471.4966329882027;3613.1689711366007;3662.359087159736;3665.138692407282;3675.61406052366;598.70081922228;666.4002397841981;666.5667316244383;669.9162604674416;678.8947713104477;604.4650484321407;614.4377894226822;638.9872202555923;639.424517933854;658.1707049118146;8764.123587641185;9160.770980725536;9188.8621670675;9286.90713092874;9374.437533747998;636.4399328590903;666.6034657392754;678.2674711311771;681.2234053179379;690.1132045321738;4306.80619372129;4492.438445194351;4502.257127573979;4518.887027824265;4555.863324100283;147745.26127369294;148067;148329;152465.23767381092;157364.21317893337;138864.08287194246;139243;141254.2937285307;143330.40017598978;145993;119714.80285197037;120174.79825201811;120560;121608.39195803965;125080.37459812642;8433.704820331259;8732.956335218283;8748.56257187151;8753.518556479201;8922.598483068406;66107.66946165239;66594.00108998465;68146.65926670335;68638.62722745417;69460.65269673619;38576;62266;80625;99879;102684.6241526172;11716.882831171579;11737.647870563897;11839.05287577009;11841.5855445059;11987.76024479523;12645.430955607204;12948.482060717843;13026;13121.606351809462;13203.273819939996;11000.834987475035;11243.887561124284;11995.580154694544;13145.934270328587;13662.521811736542;10908.61819836302;13620.250886201355;13640.522581709592;13644.863551364358;14036.22800745928;13006.674833129247;13167.473301068234;13169.907267437507;13323.533676321282;13578.79631805504;11267.943660281646;11427.657170284905;11492.597759078391;11619.941900290445;11733.823992639946;10380.896191037993;10641.94679026631;11117.27737697047;11335.716607084889;11350.716232094264 03:29:18 INFO - PROCESS | 1657 | __end_tp_report 03:29:18 INFO - PROCESS | 1657 | __start_cc_report 03:29:18 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1636 03:29:18 INFO - PROCESS | 1657 | __end_cc_report 03:29:18 INFO - PROCESS | 1657 | __startTimestamp1475576958292__endTimestamp 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | ------- Summary: start ------- 03:29:18 INFO - PROCESS | 1657 | Number of tests: 6 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5768.68 Median:6036.97 stddev:658.09 (10.9%) stddev-sans-first:631.52 03:29:18 INFO - PROCESS | 1657 | Values: 3322.8 3342.3 3356.4 3401.5 3412.1 5604.7 5654.9 5691.7 5745.4 5776.9 5629.9 5714.8 5717.6 5719.0 5748.9 3917.6 5054.3 5187.1 5209.5 5214.1 5304.5 5307.4 5328.1 5330.6 5331.1 5216.9 5258.0 5275.5 5288.5 5307.2 5324.4 5331.1 5334.0 5336.4 5357.1 5133.0 5153.3 5190.8 5259.6 5264.9 5646.3 5709.2 5763.3 5764.3 5811.8 6297.3 6299.9 6300.6 6313.5 6315.0 6177.2 6265.7 6307.7 6310.9 6347.8 5792.8 5867.5 5873.4 5874.9 5875.0 6263.3 6294.4 6307.7 6312.3 6318.1 6044.2 6138.5 6146.6 6205.4 6226.7 6208.7 6209.7 6286.9 6292.2 6301.4 6206.2 6283.3 6292.3 6297.6 6302.4 6074.5 6272.5 6277.8 6294.7 6297.3 4901.0 4922.0 4929.0 4929.1 4931.0 4462.2 4483.9 4499.8 4523.6 4531.3 5772.9 5773.8 5773.8 5790.0 5800.6 5247.6 6266.0 6272.6 6279.6 6279.7 5540.9 5772.1 5781.8 5782.3 5785.4 4882.2 5436.6 5436.8 5440.4 5442.8 6243.5 6290.0 6305.9 6340.9 6375.2 6329.3 6330.9 6332.8 6343.9 6349.0 5613.7 6230.0 6234.8 6298.5 6336.8 6243.3 6243.5 6257.2 6267.6 6277.8 6164.9 6189.8 6274.5 6286.1 6345.9 6049.1 6135.3 6201.1 6204.9 6217.6 6107.6 6127.3 6128.1 6130.4 6209.9 6033.3 6066.5 6067.0 6073.6 6099.8 6140.9 6157.8 6160.7 6206.5 6216.0 5887.0 5949.4 5955.5 5970.3 6037.0 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:68857.65 Median:39477.90 stddev:149415.93 (378.5%) stddev-sans-first:149746.87 03:29:18 INFO - PROCESS | 1657 | Values: 477.3 482.9 515.4 523.0 523.9 30766.7 31239.5 32222.5 40193.4 41889.8 42708.6 43211.0 43273.0 43307.4 43342.0 9860.6 10011.5 10040.0 10047.2 10054.4 9854.5 9961.0 9965.8 9971.5 9980.6 9896.6 9950.5 9952.1 9958.2 9968.6 9842.8 9888.4 9900.0 9914.3 9927.6 9749.6 9828.5 9828.7 9841.9 9844.9 40879.4 41202.0 41225.0 41306.6 41455.4 3317.5 3319.2 3365.6 3403.6 3782.2 31335.0 31421.4 31769.1 31928.8 32273.9 3183.6 3213.5 3220.8 3245.9 3327.2 480053.0 481441.0 481523.6 483339.0 483511.2 61322.1 61634.0 61675.7 61678.7 61793.7 29480.6 35389.9 35721.6 65393.0 65571.5 16516.1 16672.8 16677.2 16691.2 16711.2 12684.9 12801.0 12806.6 12820.0 12833.0 15681.3 15717.6 19334.6 19337.4 19363.9 6797.4 7917.4 7931.7 8052.2 8211.9 715.3 806.0 895.8 977.3 1019.1 30115.8 30785.0 31083.8 31532.0 32576.9 858.5 1064.7 1077.7 1091.4 1177.4 14804.3 15337.9 15457.0 15535.1 15755.0 608420.0 611286.0 611977.0 618236.0 623761.0 58339.8 58476.7 58479.7 58663.4 59340.4 1214.0 1237.4 1333.6 1361.0 1368.5 27994.6 28066.4 28183.9 30553.2 31475.4 50250.0 51732.0 51748.5 51755.7 51768.0 569772.9 574062.0 574532.0 574540.0 574853.0 26950.9 26999.6 27509.4 27694.0 28559.7 47740.5 48811.3 48840.8 49330.3 49574.8 33664.7 34373.8 35482.7 35805.8 39788.0 39093.8 39362.8 39479.8 40671.4 41065.6 33170.7 38693.8 38810.0 39020.8 39476.0 27551.7 31959.2 34780.8 35389.0 35485.6 22380.8 25630.6 25784.1 25918.5 26097.3 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33905.96 Median:26764.80 stddev:95968.35 (358.6%) stddev-sans-first:96204.37 03:29:18 INFO - PROCESS | 1657 | Values: 285.2 350.6 361.2 371.7 401.6 14998.9 15108.5 16349.1 21038.2 21087.8 19900.4 20536.0 20627.3 21100.2 21420.6 5376.8 5651.8 5778.9 5965.5 5974.4 4752.7 5115.9 5170.0 5965.5 5973.3 4873.5 5640.9 5936.4 5945.0 5946.9 5689.9 5835.8 5838.3 5846.7 5847.9 4885.7 5099.4 5118.3 5118.9 5220.5 24232.9 24364.4 26344.5 26654.9 26874.7 2340.4 2345.2 2351.2 2378.3 2448.2 14923.6 14982.9 15170.9 15937.9 16324.7 1726.4 1825.7 1826.7 1844.9 1872.6 104538.0 114179.0 119863.0 123944.4 127221.0 11012.7 11245.3 11494.2 11757.2 11989.0 14381.1 14385.6 14400.6 14421.6 14422.1 8230.8 8423.2 8461.5 8573.2 8594.4 8314.6 8331.2 8354.7 8489.0 8517.1 4435.8 4489.5 4511.4 4564.3 4612.1 1765.3 1875.1 1921.4 1922.5 1925.5 739.7 769.0 777.6 822.3 944.2 18095.6 21032.9 21162.8 21171.6 21244.4 892.6 950.0 950.6 978.1 983.8 8916.2 10640.8 11234.8 11239.4 11243.1 530689.0 570974.0 584874.0 590600.0 598365.0 82639.0 98834.0 128897.0 129268.4 131142.3 935.7 979.8 992.5 1012.9 1014.8 17817.3 18326.5 18564.9 18580.9 18779.5 50746.7 51708.5 52537.7 53582.5 54126.0 73247.9 73612.6 76273.9 76321.0 76434.1 5950.5 8015.3 8127.2 8196.0 8213.5 10276.6 12789.4 12979.2 13355.9 13380.3 10838.9 10906.6 11456.7 11461.0 11593.7 10594.7 10724.8 10970.1 11186.7 11401.8 11166.2 11337.0 11773.4 11865.3 11895.3 7028.3 7862.8 7948.1 8127.4 8262.4 9733.0 9754.1 10440.6 10844.8 11102.4 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5771.70 Median:4850.93 stddev:3257.30 (67.1%) stddev-sans-first:3249.58 03:29:18 INFO - PROCESS | 1657 | Values: 1361.5 1516.4 1720.9 1877.8 1941.2 8530.0 9077.0 9081.3 9081.3 9083.5 7818.3 8124.8 8590.4 8867.0 8878.5 2343.6 2386.8 2390.5 2390.7 2392.7 2351.5 2355.9 2391.5 2398.6 2399.5 2035.5 2370.4 2376.4 2389.2 2393.7 2338.8 2352.5 2361.5 2361.9 2370.8 2354.6 2354.7 2356.4 2376.4 2376.6 8769.0 8967.9 8975.0 8981.4 8989.6 5264.8 5435.5 5435.8 5449.2 5487.6 3608.5 3609.7 3703.4 3704.3 3717.4 1058.8 1067.1 1072.1 1080.0 1092.7 11999.2 12095.9 12101.9 12104.9 12114.3 4452.6 4539.1 4828.5 4873.4 4878.4 10131.3 10133.1 10149.8 10163.8 10176.4 5403.5 5414.5 5415.6 5423.3 5428.4 4239.1 4402.8 4448.8 4900.0 4956.4 3854.1 3877.8 4038.9 4053.4 4072.7 1281.0 1286.3 1292.9 1300.1 1302.4 767.7 773.7 791.7 818.6 893.9 5847.2 6004.7 6129.2 6147.2 6283.4 882.2 913.2 959.6 972.5 975.1 3836.4 3872.1 3940.6 3958.3 3996.3 10658.4 10822.6 10913.6 10916.1 10936.3 9155.4 10891.2 10900.8 10904.7 10934.3 7743.7 8237.2 8351.7 8362.0 8391.9 5856.6 6112.3 6120.0 6167.2 6174.1 9653.1 9916.8 10219.2 10228.8 10287.0 10068.0 10346.0 10443.7 10468.7 10482.3 6900.6 6975.4 7204.4 7204.9 7288.7 8214.9 8477.7 8488.2 8584.4 8591.7 7507.2 7632.2 7656.2 7726.8 7769.9 7439.1 7521.3 7523.1 7524.2 7594.3 6920.4 7091.3 7092.6 7100.7 7106.6 4468.0 4711.6 4900.4 5031.2 5123.3 4400.4 4958.6 5158.5 5195.8 5269.1 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4410.23 Median:5042.15 stddev:1372.89 (27.2%) stddev-sans-first:1341.60 03:29:18 INFO - PROCESS | 1657 | Values: 298.6 298.9 305.1 311.8 313.3 4587.9 4742.8 5138.8 5206.7 5263.3 4451.9 4891.5 5071.5 5156.7 5197.5 3393.9 4006.8 4007.0 4019.3 4049.8 3352.2 3381.2 3622.6 3948.4 3985.8 3318.9 3715.9 3882.7 3960.9 3967.6 3902.8 3945.5 3957.3 3996.1 4004.2 3379.7 3392.3 3393.3 3406.1 3444.6 4953.5 5021.6 5045.4 5055.6 5064.3 3443.7 4458.7 4476.9 4483.9 4558.0 5041.6 5497.8 5523.4 5588.2 5595.4 3738.9 3740.8 3742.0 3755.8 3834.6 5448.3 5626.0 5650.6 5657.5 5805.0 3503.4 3551.3 3792.0 3822.8 3842.8 3440.7 3874.0 3893.4 3897.4 3900.0 3970.5 4257.0 4518.8 4570.2 4570.8 3887.2 5433.2 5469.3 5491.7 5537.9 1279.6 1292.7 1313.9 1324.4 1326.7 712.6 718.6 848.9 860.4 861.4 4768.0 4991.5 5233.2 5244.3 5253.4 5128.7 5209.7 5213.4 5411.9 5619.3 4393.5 4939.6 5113.1 5146.7 5147.3 2414.5 2443.4 2884.9 2890.3 2900.6 5661.2 5677.0 5685.6 5706.1 5738.7 5023.5 5276.4 5443.5 5495.8 5852.1 4793.4 5487.2 5626.8 5895.4 5912.1 4653.9 5042.6 5188.5 5690.7 5712.6 4709.9 5195.0 5562.4 5668.7 5717.5 5388.7 5523.8 5531.5 5917.0 5993.6 3099.4 3568.9 3598.9 3693.6 3816.8 4701.2 5679.1 5680.9 5694.5 5699.7 5115.0 5482.3 5521.8 5572.3 5626.1 4633.4 5304.9 5406.4 5548.4 5601.8 4558.7 5060.5 5400.3 5405.6 5419.9 5337.9 5370.3 5374.8 5379.7 5380.8 4603.6 4765.6 5429.3 5430.5 5518.0 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25094.89 Median:12814.47 stddev:41000.76 (320.0%) stddev-sans-first:41074.53 03:29:18 INFO - PROCESS | 1657 | Values: 601.9 747.3 767.8 776.5 807.5 15061.6 15698.3 15718.0 15720.4 15735.1 13177.6 13253.3 14119.3 14156.7 14182.5 656.6 672.4 827.0 840.4 854.1 856.7 860.8 862.2 862.8 863.3 915.1 915.2 916.1 916.2 918.5 703.0 715.5 880.9 891.6 911.5 675.9 676.5 677.4 678.2 678.7 14762.4 14779.0 14788.5 14800.9 14808.8 13668.2 13937.3 13983.2 13994.7 14116.7 12658.6 12825.2 12853.7 12899.9 13330.5 4491.6 4531.5 4557.6 4568.4 4766.7 100434.0 101139.5 107102.0 108437.0 111988.3 10512.7 10896.1 10971.9 11020.7 11371.8 19622.7 22084.9 22127.4 22129.7 22157.4 7717.2 7826.7 7913.0 8050.0 8087.7 4217.2 4302.0 4336.0 4391.0 4395.8 3471.5 3613.2 3662.4 3665.1 3675.6 598.7 666.4 666.6 669.9 678.9 604.5 614.4 639.0 639.4 658.2 8764.1 9160.8 9188.9 9286.9 9374.4 636.4 666.6 678.3 681.2 690.1 4306.8 4492.4 4502.3 4518.9 4555.9 147745.3 148067.0 148329.0 152465.2 157364.2 138864.1 139243.0 141254.3 143330.4 145993.0 119714.8 120174.8 120560.0 121608.4 125080.4 8433.7 8733.0 8748.6 8753.5 8922.6 66107.7 66594.0 68146.7 68638.6 69460.7 38576.0 62266.0 80625.0 99879.0 102684.6 11716.9 11737.6 11839.1 11841.6 11987.8 12645.4 12948.5 13026.0 13121.6 13203.3 11000.8 11243.9 11995.6 13145.9 13662.5 10908.6 13620.3 13640.5 13644.9 14036.2 13006.7 13167.5 13169.9 13323.5 13578.8 11267.9 11427.7 11492.6 11619.9 11733.8 10380.9 10641.9 11117.3 11335.7 11350.7 03:29:18 INFO - PROCESS | 1657 | -------- Summary: end -------- 03:29:18 INFO - PROCESS | 1657 | 03:29:18 INFO - TEST-INFO | 1657: exit 0 03:29:18 INFO - TEST-OK | dromaeo_css | took 1119346ms 03:29:18 INFO - TEST-START | kraken 03:29:18 INFO - operating with platform_type : mac_ 03:29:18 INFO - Initialising browser for kraken test... 03:29:18 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpZ3tDUe/profile http://localhost:49278/getInfo.html) 03:29:21 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 03:29:21 INFO - PROCESS | 1667 | colorDepth:24 03:29:21 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 03:29:21 INFO - PROCESS | 1667 | __metrics 03:29:21 INFO - PROCESS | 1667 | 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" 03:29:21 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:29:22 INFO - TEST-INFO | 1667: exit 0 03:29:22 INFO - Browser initialized. 03:29:22 INFO - Running cycle 1/1 for kraken test... 03:29:22 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpZ3tDUe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:29:33 INFO - PROCESS | 1668 | RSS: Main: 235941888 03:29:33 INFO - PROCESS | 1668 | 03:29:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:29:37 INFO - PROCESS | 1668 | RSS: Main: 294789120 03:29:37 INFO - PROCESS | 1668 | 03:29:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:29:40 INFO - PROCESS | 1668 | RSS: Main: 293531648 03:29:40 INFO - PROCESS | 1668 | 03:29:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:29:44 INFO - PROCESS | 1668 | RSS: Main: 387907584 03:29:44 INFO - PROCESS | 1668 | 03:29:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:29:47 INFO - PROCESS | 1668 | RSS: Main: 285982720 03:29:47 INFO - PROCESS | 1668 | 03:29:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:29:50 INFO - PROCESS | 1668 | RSS: Main: 290279424 03:29:50 INFO - PROCESS | 1668 | 03:29:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:29:53 INFO - PROCESS | 1668 | RSS: Main: 328056832 03:29:53 INFO - PROCESS | 1668 | 03:29:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:29:57 INFO - PROCESS | 1668 | RSS: Main: 329220096 03:29:57 INFO - PROCESS | 1668 | 03:29:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:30:00 INFO - PROCESS | 1668 | RSS: Main: 326410240 03:30:00 INFO - PROCESS | 1668 | 03:30:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:30:02 INFO - PROCESS | 1668 | RSS: Main: 301391872 03:30:02 INFO - PROCESS | 1668 | 03:30:04 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:30:05 INFO - PROCESS | 1668 | RSS: Main: 304160768 03:30:05 INFO - PROCESS | 1668 | 03:30:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:30:08 INFO - PROCESS | 1668 | RSS: Main: 312070144 03:30:08 INFO - PROCESS | 1668 | 03:30:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:30:11 INFO - PROCESS | 1668 | RSS: Main: 317661184 03:30:11 INFO - PROCESS | 1668 | 03:30:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:30:15 INFO - PROCESS | 1668 | RSS: Main: 318758912 03:30:15 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:30:17 INFO - PROCESS | 1668 | RSS: Main: 363364352 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | __start_tp_report 03:30:17 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 03:30:17 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 03:30:17 INFO - PROCESS | 1668 | |i|pagename|runs| 03:30:17 INFO - PROCESS | 1668 | |0;ai-astar;112;113;113;113;113;114;114;114;113;113 03:30:17 INFO - PROCESS | 1668 | |1;audio-beat-detection;170;151;140;150;114;114;112;113;149;114 03:30:17 INFO - PROCESS | 1668 | |2;audio-dft;184;183;182;184;183;183;179;183;182;184 03:30:17 INFO - PROCESS | 1668 | |3;audio-fft;82;61;78;112;81;60;60;59;83;58 03:30:17 INFO - PROCESS | 1668 | |4;audio-oscillator;83;73;92;70;69;71;91;68;70;89 03:30:17 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;86;88;86;88;84;88;86;88;85 03:30:17 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;94;94;94;94;93;94;94;94;93 03:30:17 INFO - PROCESS | 1668 | |7;imaging-desaturate;83;86;101;133;142;85;85;85;87;87 03:30:17 INFO - PROCESS | 1668 | |8;json-parse-financial;55;54;54;54;54;54;56;55;54;54 03:30:17 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;63;75;63;75;72;68;69;70;68;72 03:30:17 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;87;86;89;88;90;89;88;91;90;99 03:30:17 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;148;146;164;146;161;157;154;139;151;143 03:30:17 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;172;172;172;184;151;229;228;185;173;171 03:30:17 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;70;70;70;72;71;67;68;71;69;67 03:30:17 INFO - PROCESS | 1668 | __end_tp_report 03:30:17 INFO - PROCESS | 1668 | __start_cc_report 03:30:17 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1972 03:30:17 INFO - PROCESS | 1668 | __end_cc_report 03:30:17 INFO - PROCESS | 1668 | __startTimestamp1475577017461__endTimestamp 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | ------- Summary: start ------- 03:30:17 INFO - PROCESS | 1668 | Number of tests: 14 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.00 stddev:0.63 (0.6%) stddev-sans-first:0.50 03:30:17 INFO - PROCESS | 1668 | Values: 112.0 113.0 113.0 113.0 113.0 114.0 114.0 114.0 113.0 113.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:144.50 stddev:21.63 (15.0%) stddev-sans-first:18.25 03:30:17 INFO - PROCESS | 1668 | Values: 170.0 151.0 140.0 150.0 114.0 114.0 112.0 113.0 149.0 114.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.49 (0.8%) stddev-sans-first:1.51 03:30:17 INFO - PROCESS | 1668 | Values: 184.0 183.0 182.0 184.0 183.0 183.0 179.0 183.0 182.0 184.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:73.40 Median:69.50 stddev:17.30 (24.9%) stddev-sans-first:18.06 03:30:17 INFO - PROCESS | 1668 | Values: 82.0 61.0 78.0 112.0 81.0 60.0 60.0 59.0 83.0 58.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.00 stddev:9.96 (12.8%) stddev-sans-first:10.37 03:30:17 INFO - PROCESS | 1668 | Values: 83.0 73.0 92.0 70.0 69.0 71.0 91.0 68.0 70.0 89.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.43 (1.6%) stddev-sans-first:1.51 03:30:17 INFO - PROCESS | 1668 | Values: 87.0 86.0 88.0 86.0 88.0 84.0 88.0 86.0 88.0 85.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 03:30:17 INFO - PROCESS | 1668 | Values: 95.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:85.00 stddev:21.82 (25.7%) stddev-sans-first:22.51 03:30:17 INFO - PROCESS | 1668 | Values: 83.0 86.0 101.0 133.0 142.0 85.0 85.0 85.0 87.0 87.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 03:30:17 INFO - PROCESS | 1668 | Values: 55.0 54.0 54.0 54.0 54.0 54.0 56.0 55.0 54.0 54.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:71.00 stddev:4.25 (6.0%) stddev-sans-first:3.80 03:30:17 INFO - PROCESS | 1668 | Values: 63.0 75.0 63.0 75.0 72.0 68.0 69.0 70.0 68.0 72.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:89.50 stddev:3.59 (4.0%) stddev-sans-first:3.67 03:30:17 INFO - PROCESS | 1668 | Values: 87.0 86.0 89.0 88.0 90.0 89.0 88.0 91.0 90.0 99.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:152.50 stddev:8.03 (5.3%) stddev-sans-first:8.45 03:30:17 INFO - PROCESS | 1668 | Values: 148.0 146.0 164.0 146.0 161.0 157.0 154.0 139.0 151.0 143.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:178.50 stddev:25.32 (14.2%) stddev-sans-first:26.50 03:30:17 INFO - PROCESS | 1668 | Values: 172.0 172.0 172.0 184.0 151.0 229.0 228.0 185.0 173.0 171.0 03:30:17 INFO - PROCESS | 1668 | 03:30:17 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.72 (2.5%) stddev-sans-first:1.81 03:30:17 INFO - PROCESS | 1668 | Values: 70.0 70.0 70.0 72.0 71.0 67.0 68.0 71.0 69.0 67.0 03:30:17 INFO - PROCESS | 1668 | -------- Summary: end -------- 03:30:17 INFO - PROCESS | 1668 | 03:30:18 INFO - TEST-INFO | 1668: exit 0 03:30:18 INFO - TEST-OK | kraken | took 59508ms 03:30:18 INFO - SUITE-END | took 1178s 03:30:18 INFO - Completed test suite (00:19:39) 03:30:18 INFO - javascript benchmark 03:30:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.76740628156, 3342.2647017656113, 3356.379069872074, 3401.5407919930803, 3412.0957946144263, 5604.691741954191, 5654.915176272354, 5691.658500489973, 5745.396733343002, 5776.942230577693, 5629.943700562993, 5714.771409143629, 5717.599768016241, 5719.0, 5748.942510574893, 3917.5886531914166, 5054.292399064134, 5187.1181899077055, 5209.479052094798, 5214.113600687892, 5304.549113325372, 5307.363116426015, 5328.0675881720545, 5330.600204984622, 5331.067063263913, 5216.8695782605355, 5258.0, 5275.498827611371, 5288.497592728685, 5307.150855863063, 5324.361076670824, 5331.093714068617, 5333.973330133324, 5336.38631557372, 5357.142857142839, 5133.0, 5153.330067091254, 5190.844274671766, 5259.631825772198, 5264.92102618461, 5646.265985421869, 5709.229254050733, 5763.308402991626, 5764.33710123337, 5811.767529298823, 6297.307296197415, 6299.937000630027, 6300.621962682208, 6313.526485513559, 6314.968425157891, 6177.227846519185, 6265.7493700251935, 6307.747690092437, 6310.87378252437, 6347.777827776052, 5792.82621521355, 5867.471927526501, 5873.353931067664, 5874.9118763218585, 5875.030619947746, 6263.275475168316, 6294.370562943669, 6307.653079080603, 6312.274088479791, 6318.052292156213, 6044.1840351552855, 6138.539609529304, 6146.588539080381, 6205.379462053849, 6226.688665566703, 6208.658523781145, 6209.658468784261, 6286.874262514815, 6292.1505596743855, 6301.40136687014, 6206.193194884727, 6283.340249273851, 6292.276388215414, 6297.590656607306, 6302.369763023753, 6074.4532992030045, 6272.529560282997, 6277.811665650038, 6294.716737746812, 6297.338779428185, 4900.975495122501, 4922.0, 4928.950710492921, 4929.088118698052, 4930.950690493121, 4462.196804575207, 4483.887902802391, 4499.775011249424, 4523.638108951276, 4531.252343363308, 5772.884542309214, 5773.826785196451, 5773.826785196451, 5789.9710501447225, 5800.622959507618, 5247.606429517725, 6266.028765541349, 6272.560920735505, 6279.560430769802, 6279.717412716439, 5540.86147846299, 5772.105323674838, 5781.82654520365, 5782.277215348082, 5785.363610002896, 4882.170031094723, 5436.565074794007, 5436.782528698887, 5440.42875498067, 5442.836714898559, 6243.469305109116, 6290.0, 6305.905411418832, 6340.9048864268, 6375.203099612549, 6329.272133704499, 6330.873382532415, 6332.841678958063, 6343.873122537431, 6348.968255158695, 5613.691246981455, 6229.96885015572, 6234.812955611339, 6298.527610429144, 6336.809895703136, 6243.313235543996, 6243.531735119794, 6257.217847769029, 6267.561270711007, 6277.780277690259, 6164.8767024658355, 6189.783357582463, 6274.46667033289, 6286.088517164894, 6345.873082538231, 6049.092636104443, 6135.294441139326, 6201.131841542097, 6204.875902482016, 6217.595856269328, 6107.633541987495, 6127.3259941407305, 6128.136482154469, 6130.428711073822, 6209.906851397142, 6033.311178787146, 6066.488474766911, 6066.998691470635, 6073.632117382563, 6099.84750381244, 6140.904102378134, 6157.784477543443, 6160.722767475564, 6206.534509911865, 6216.0, 5887.028640274307, 5949.405059494016, 5955.464008239279, 5970.253718285215, 6036.9698151508965], "value": 5726.065002764174, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.30075439481175, 482.8696252011959, 515.3738208077186, 523.0297797585479, 523.9364090895483, 30766.692333076662, 31239.531407028884, 32222.516662250055, 40193.39709904354, 41889.790551047205, 42708.57291427085, 43211.0, 43273.0, 43307.35038974418, 43342.0, 9860.60557577696, 10011.549480273368, 10040.0, 10047.196224302075, 10054.447005414699, 9854.507274636275, 9960.95019524905, 9965.800683986316, 9971.451570163637, 9980.60077596899, 9896.604135834594, 9950.452725100116, 9952.054554817318, 9958.153556947633, 9968.551415186297, 9842.803143937117, 9888.406695598287, 9899.950500247525, 9914.256430767686, 9927.553260103312, 9749.610015599368, 9828.459434731087, 9828.705138845846, 9841.852372214422, 9844.90155098447, 40879.38680919789, 41202.0, 41225.0, 41306.63013808216, 41455.37816932749, 3317.5189597508465, 3319.2199833039217, 3365.646607106243, 3403.5745531808525, 3782.2435512897255, 31335.0, 31421.37157256842, 31769.14077732992, 31928.75434926955, 32273.870414535377, 3183.6338821035647, 3213.4537128687944, 3220.806751594912, 3245.9026229212927, 3327.1515763480156, 480053.0, 481441.0, 481523.59238203935, 483339.0, 483511.1648883501, 61322.0801687975, 61634.0, 61675.691621541606, 61678.69160654168, 61793.69103154456, 29480.557791633142, 35389.93830185098, 35721.64278357235, 65393.019104713465, 65571.50855863039, 16516.091614961053, 16672.83327166713, 16677.166141692865, 16691.165441727866, 16711.164441777863, 12684.873151268554, 12801.0, 12806.551770687982, 12819.974402047814, 12833.0, 15681.294341754421, 15717.607059823367, 19334.613307733765, 19337.4198774037, 19363.934983575757, 6797.388235058868, 7917.406194535433, 7931.730923052284, 8052.188257642101, 8211.876821847676, 715.3168723868692, 805.963731632078, 895.8029233568604, 977.3402953006693, 1019.0879163148941, 30115.849420753195, 30785.0, 31083.756649733758, 31532.0, 32576.859809907015, 858.4634603372892, 1064.7125276175389, 1077.6820837852852, 1091.3997301484153, 1177.4289469607234, 14804.259787010606, 15337.923310383376, 15457.0, 15535.06789592628, 15755.0, 608420.0, 611286.0, 611977.0, 618236.0, 623761.0, 58339.833203336544, 58476.707616461645, 58479.70760146172, 58663.41336586665, 59340.40659593435, 1213.9635810925686, 1237.4307818403636, 1333.6065860571348, 1360.9659758506116, 1368.50733735855, 27994.580081299206, 28066.438671226868, 28183.859080704464, 30553.23616909506, 31475.37049259048, 50249.99500010053, 51732.0, 51748.482515174364, 51755.741221293654, 51768.0, 569772.9068127963, 574062.0, 574532.0, 574540.0, 574853.0, 26950.865245673645, 26999.59500607453, 27509.44981100407, 27694.03070892509, 28559.714402855705, 47740.52259477361, 48811.26783098328, 48840.755796220794, 49330.26004609862, 49574.75212623914, 33664.66335336615, 34373.82813085918, 35482.73554272178, 35805.82097089498, 39788.005299867735, 39093.80453097716, 39362.80318598389, 39479.80260098681, 40671.38992915049, 41065.58934410737, 33170.66829331676, 38693.83918482337, 38810.02974925649, 39020.804895975336, 39476.0, 27551.724482754915, 31959.201019974687, 34780.82609586936, 35389.0, 35485.64514354926, 22380.77619223787, 25630.615540767278, 25784.09755658618, 25918.48163036691, 26097.339208457313], "value": 19805.33932705579, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.23556867594846, 350.5950627025786, 361.2453613930093, 371.7323527060517, 401.63250625677506, 14998.898651459907, 15108.546743597695, 16349.100799456026, 21038.21175200106, 21087.789122108774, 19900.40298791035, 20535.97320133991, 20627.27804526842, 21100.15599376027, 21420.57158856822, 5376.8118115865955, 5651.8304450866435, 5778.873119741652, 5965.46310832025, 5974.372690867462, 4752.667313288071, 5115.923261151086, 5170.0, 5965.522758179357, 5973.253343332083, 4873.48828373021, 5640.943590564103, 5936.43603857635, 5945.04879219325, 5946.851328716773, 5689.943100569003, 5835.79574714883, 5838.270216222951, 5846.736896839652, 5847.912281315784, 4885.658003939726, 5099.36257967754, 5118.283440318359, 5118.948810511884, 5220.4779522047875, 24232.878835605887, 24364.390890227707, 26344.47311053768, 26654.86672566625, 26874.73125268742, 2340.414896275931, 2345.1674655497395, 2351.188839850266, 2378.2865140457793, 2448.24104527597, 14923.552293431214, 14982.850171498254, 15170.924145379202, 15937.92031039849, 16324.673506529922, 1726.378503738653, 1825.7443957845912, 1826.7077267637114, 1844.880082794614, 1872.5505878589177, 104538.0, 114178.99999999917, 119863.0, 123944.38027809803, 127220.99999999907, 11012.724681882857, 11245.269057511236, 11494.195406321645, 11757.176997610257, 11989.0, 14381.137131772128, 14385.64035899111, 14400.639984000483, 14421.639459013399, 14422.06256593318, 8230.753077407688, 8423.199796019368, 8461.450005749606, 8573.18554737299, 8594.441361311494, 8314.584270786436, 8331.166883311742, 8354.66581336752, 8489.023762267418, 8517.06312305646, 4435.800388982503, 4489.506154323022, 4511.390962220124, 4564.269716845289, 4612.123696497655, 1765.3203516646183, 1875.1374367790968, 1921.413968739548, 1922.4521011511931, 1925.5186203449139, 739.6671497826, 769.0118198115395, 777.5723352156405, 822.2640736540762, 944.2398868910593, 18095.638087238443, 21032.894835525723, 21162.83604401742, 21171.576568468852, 21244.36266911995, 892.638481415028, 949.9715008549755, 950.5959967013991, 978.0659470205936, 983.8081574093127, 8916.242119419921, 10640.840387394195, 11234.775304493864, 11239.381833999045, 11243.100551955824, 530689.0396644054, 570974.0, 584874.0, 590600.0, 598365.0, 82639.0, 98834.0, 128897.0, 129268.3536582311, 131142.34428827793, 935.7192842147329, 979.8040391921787, 992.5235886774244, 1012.9240306977006, 1014.7767491151935, 17817.287308508032, 18326.541836454195, 18564.907175464035, 18580.88514689123, 18779.530511736768, 50746.746266268434, 51708.48291517037, 52537.68655783636, 53582.464175357745, 54126.0, 73247.90128147976, 73612.63193683997, 76273.85589216277, 76321.0, 76434.08914777175, 5950.4644581986095, 8015.278624923785, 8127.2279133482225, 8195.95902020486, 8213.507189568645, 10276.588936442458, 12789.42447589842, 12979.221246725227, 13355.933220333836, 13380.264085475003, 10838.891611083789, 10906.61826836057, 11456.656300311004, 11461.025812806005, 11593.710157246136, 10594.735131621772, 10724.839127412939, 10970.067544258827, 11186.720331991766, 11401.771964560829, 11166.218364714718, 11337.0, 11773.35246561413, 11865.288082715064, 11895.286282823059, 7028.262032486719, 7862.842743144991, 7948.125706172201, 8127.390445716476, 8262.380321475794, 9733.0, 9754.122129008423, 10440.63457778974, 10844.783104337712, 11102.444877756241], "value": 9435.951509361757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.5166615851372, 1516.4010215964695, 1720.9053502057382, 1877.8216069473394, 1941.2429152630477, 8529.957350213248, 9076.956150042746, 9081.318901082412, 9081.31890108242, 9083.545822708862, 7818.296353328214, 8124.796880078001, 8590.398672092955, 8867.0, 8878.467291962486, 2343.5547246023284, 2386.78518933296, 2390.509945461185, 2390.6653068570326, 2392.7487613800563, 2351.4709190432186, 2355.893984770681, 2391.4977854650456, 2398.64020396941, 2399.460121472672, 2035.5318276796345, 2370.3718514593647, 2376.4415362389823, 2389.223502361731, 2393.664886915833, 2338.75443078476, 2352.5177338645535, 2361.504084142332, 2361.8937147828387, 2370.8458950168183, 2354.5754818335004, 2354.658574506704, 2356.4108972756812, 2376.3940195250275, 2376.560336337783, 8768.956155219246, 8967.865482017774, 8974.955125224398, 8981.416207946528, 8989.595468203946, 5264.815731449419, 5435.483629055233, 5435.809746658888, 5449.1826226066405, 5487.560995120421, 3608.4948107265004, 3609.747317687763, 3703.3519134151684, 3704.2591481703766, 3717.423799311111, 1058.7564860082225, 1067.1089640150483, 1072.131573425528, 1079.9514021869036, 1092.6995076353987, 11999.220050696676, 12095.879041209475, 12101.878981210164, 12104.87895121055, 12114.333711645944, 4452.577005184502, 4539.137563862855, 4828.493008234155, 4873.366462359871, 4878.36581244443, 10131.341462804894, 10133.08802207805, 10149.797004060025, 10163.847542286872, 10176.440295783656, 5403.459654034644, 5414.512693857571, 5415.566754659618, 5423.322084739408, 5428.430014848463, 4239.130978149472, 4402.801873915681, 4448.754348782281, 4899.995500922302, 4956.355673762388, 3854.1328201154515, 3877.806109694504, 4038.919221615551, 4053.3717273822604, 4072.6538244249573, 1280.9743805123846, 1286.3246795432285, 1292.8965682745359, 1300.0704496285202, 1302.3813688497887, 767.6545554500497, 773.6905237904883, 791.7228969860618, 818.5579786915118, 893.9317515568963, 5847.181394604759, 6004.6697431640805, 6129.172561704202, 6147.200863887666, 6283.434490895841, 882.2192359761657, 913.2374467319862, 959.6065613098596, 972.4991629310911, 975.1418751498636, 3836.388716738976, 3872.1287710264965, 3940.6453419191835, 3958.287508248485, 3996.2606917720314, 10658.413787241776, 10822.621208257831, 10913.618023369145, 10916.07213386871, 10936.343819370863, 9155.404898681565, 10891.237613366988, 10900.781984360268, 10904.727381815359, 10934.289271197347, 7743.728969486041, 8237.217464340878, 8351.707690230813, 8362.038365587914, 8391.916080839235, 5856.560757943086, 6112.258006468135, 6119.969400152971, 6167.167432396569, 6174.073888916699, 9653.131218190396, 9916.801663966824, 10219.1824654026, 10228.846567301645, 10287.0, 10068.04353586408, 10346.017128372792, 10443.68668939933, 10468.68593942183, 10482.318649287772, 6900.6204658744255, 6975.372216500336, 7204.387627051759, 7204.89192662121, 7288.67200975968, 8214.917850821654, 8477.74566762998, 8488.193621605938, 8584.442011269248, 8591.6992905248, 7507.211742766931, 7632.236776322324, 7656.157822639394, 7726.845463090819, 7769.8834517481155, 7439.107307122963, 7521.285477879593, 7523.059617547807, 7524.247575242432, 7594.31651151399, 6920.446364290945, 7091.326323999212, 7092.645367731696, 7100.715971361295, 7106.5736055836815, 4468.02596835366, 4711.623070154448, 4900.402786523148, 5031.195008798721, 5123.3339665843205, 4400.405945197357, 4958.553730164303, 5158.535731784157, 5195.844124676266, 5269.130593452038], "value": 4620.418021074111, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.6401386329473, 298.8670041831383, 305.1197295801612, 311.79265788250814, 313.2950860563732, 4587.862364129077, 4742.7946005107615, 5138.792383789811, 5206.739663016853, 5263.34208223972, 4451.910961780771, 4891.5353041461, 5071.518205770446, 5156.716380599075, 5197.53222210002, 3393.9321213575713, 4006.7579050494296, 4006.9799651001704, 4019.2765302245584, 4049.777262250582, 3352.2457447074335, 3381.222318866662, 3622.609078008515, 3948.427478015686, 3985.760854348734, 3318.900432987014, 3715.9442608360896, 3882.669973052294, 3960.940585891214, 3967.6230758077936, 3902.765834049938, 3945.526536815586, 3957.28768821612, 3996.082859512572, 4004.199160167949, 3379.7465190110593, 3392.2537041851, 3393.3372647402844, 3406.097384193191, 3444.621091679913, 4953.529414705597, 5021.57316628087, 5045.351190624914, 5055.646104772667, 5064.3163172971545, 3443.674185438599, 4458.732476051415, 4476.865694029184, 4483.910321793578, 4558.0, 5041.647084704072, 5497.766719625905, 5523.392426833045, 5588.21764952907, 5595.405309486777, 3738.887833365003, 3740.756850804689, 3741.981290093532, 3755.830987605564, 3834.6357096075826, 5448.346198456171, 5626.015447296748, 5650.63270887391, 5657.510695294099, 5804.970975145139, 3503.386907291214, 3551.2897420516, 3791.976166435047, 3822.7515211511163, 3842.769433833979, 3440.724742020632, 3873.992761881931, 3893.396523538856, 3897.395903634941, 3899.9610003900166, 3970.50368703912, 4257.0, 4518.7573417310505, 4570.200214962369, 4570.794314255866, 3887.2225554889137, 5433.212184233343, 5469.288992430958, 5491.670499770026, 5537.916931246034, 1279.5969269680022, 1292.6538332925709, 1313.8584107904055, 1324.3973991833693, 1326.6683329167709, 712.6329940080863, 718.5868125827566, 848.8514509960733, 860.4192170285032, 861.401326078382, 4767.998720268713, 4991.5258050485145, 5233.241180028951, 5244.344456942882, 5253.3958594761325, 5128.666636668605, 5209.733765811809, 5213.400458947193, 5411.864703382368, 5619.325680918316, 4393.472783265965, 4939.604831613462, 5113.079645663816, 5146.665466744725, 5147.279380886679, 2414.492956479159, 2443.376938880574, 2884.8990285339914, 2890.3496713239774, 2900.5939168516425, 5661.207430959669, 5677.0, 5685.60200785941, 5706.058500347479, 5738.713064346766, 5023.472535383805, 5276.366835979707, 5443.482869127427, 5495.752691128909, 5852.063669812809, 4793.448753393404, 5487.161800796698, 5626.831195064155, 5895.351511333664, 5912.113183022495, 4653.930191047205, 5042.647014708935, 5188.53544566343, 5690.658560486218, 5712.600117991867, 4709.905801884011, 5195.0, 5562.443755624405, 5668.744906479301, 5717.514011309086, 5388.73056347189, 5523.7790488380015, 5531.474509921551, 5916.994111001226, 5993.640381577119, 3099.411111888734, 3568.8750893718593, 3598.928021439609, 3693.6306369362846, 3816.7709937403843, 4701.247800351927, 5679.138186033537, 5680.886382272414, 5694.4590263924865, 5699.658020518782, 5115.0, 5482.259894914295, 5521.757604538947, 5572.331320241597, 5626.11525139083, 4633.397658304399, 5304.867378315573, 5406.432324605859, 5548.389677135431, 5601.831945041656, 4558.726476411293, 5060.51925067118, 5400.270963419888, 5405.648632838853, 5419.945800541944, 5337.866553336197, 5370.275012873212, 5374.811881584125, 5379.65032272901, 5380.811671591476, 4603.608693261111, 4765.594924431323, 5429.267048948342, 5430.484104010113, 5517.97917385285], "value": 3971.4867995458494, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.8937875255533, 747.2863415438259, 767.8157242261859, 776.491398134222, 807.54373778815, 15061.623459413506, 15698.293576789043, 15718.0, 15720.449784257553, 15735.055896646209, 13177.616350283202, 13253.271070091141, 14119.294035298246, 14156.71686566268, 14182.503612373568, 656.5830697507093, 672.4183581202274, 827.0365024746163, 840.373921428535, 854.1245223645751, 856.6830272799073, 860.7718954477068, 862.211076864668, 862.7541150772031, 863.2575984653224, 915.057490784493, 915.1900567997337, 916.1113719691888, 916.2166347772659, 918.5131880103556, 702.9824254393656, 715.5492040014809, 880.9383343165981, 891.6210610490516, 911.4804561400005, 675.8580698053414, 676.4622125410261, 677.4411110833582, 678.2132726037772, 678.6538865178794, 14762.409503619841, 14779.0, 14788.482403115946, 14800.925995370062, 14808.777868331983, 13668.17990920538, 13937.303134843218, 13983.23092229927, 13994.720105597831, 14116.717665646733, 12658.62024139268, 12825.23048617086, 12853.67865803353, 12899.93550032244, 13330.533431329857, 4491.550844915515, 4531.501534831166, 4557.635389168859, 4568.406107206075, 4766.713997160182, 100434.0, 101139.48290775644, 107102.0, 108437.0, 111988.32017519744, 10512.684619461428, 10896.073833724064, 10971.945140274247, 11020.724481887857, 11371.829422558669, 19622.70565941512, 22084.88957555202, 22127.44681382978, 22129.668054978865, 22157.44606384821, 7717.1896950819355, 7826.726064587826, 7913.010873640795, 8050.0, 8087.716929907425, 4217.198732240864, 4301.978490107529, 4336.0, 4390.956090439118, 4395.7582332972, 3471.4966329882027, 3613.1689711366007, 3662.359087159736, 3665.138692407282, 3675.61406052366, 598.70081922228, 666.4002397841981, 666.5667316244383, 669.9162604674416, 678.8947713104477, 604.4650484321407, 614.4377894226822, 638.9872202555923, 639.424517933854, 658.1707049118146, 8764.123587641185, 9160.770980725536, 9188.8621670675, 9286.90713092874, 9374.437533747998, 636.4399328590903, 666.6034657392754, 678.2674711311771, 681.2234053179379, 690.1132045321738, 4306.80619372129, 4492.438445194351, 4502.257127573979, 4518.887027824265, 4555.863324100283, 147745.26127369294, 148067.0, 148329.0, 152465.23767381092, 157364.21317893337, 138864.08287194246, 139243.0, 141254.2937285307, 143330.40017598978, 145993.0, 119714.80285197037, 120174.79825201811, 120560.0, 121608.39195803965, 125080.37459812642, 8433.704820331259, 8732.956335218283, 8748.56257187151, 8753.518556479201, 8922.598483068406, 66107.66946165239, 66594.00108998465, 68146.65926670335, 68638.62722745417, 69460.65269673619, 38576.0, 62266.0, 80625.0, 99879.0, 102684.6241526172, 11716.882831171579, 11737.647870563897, 11839.05287577009, 11841.5855445059, 11987.76024479523, 12645.430955607204, 12948.482060717843, 13026.0, 13121.606351809462, 13203.273819939996, 11000.834987475035, 11243.887561124284, 11995.580154694544, 13145.934270328587, 13662.521811736542, 10908.61819836302, 13620.250886201355, 13640.522581709592, 13644.863551364358, 14036.22800745928, 13006.674833129247, 13167.473301068234, 13169.907267437507, 13323.533676321282, 13578.79631805504, 11267.943660281646, 11427.657170284905, 11492.597759078391, 11619.941900290445, 11733.823992639946, 10380.896191037993, 10641.94679026631, 11117.27737697047, 11335.716607084889, 11350.716232094264], "value": 7770.44158889425, "unit": "score"}], "name": "dromaeo_css", "value": 7310.130890190854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 151.0, 140.0, 150.0, 114.0, 114.0, 112.0, 113.0, 149.0, 114.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 184.0, 183.0, 183.0, 179.0, 183.0, 182.0, 184.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 78.0, 112.0, 81.0, 60.0, 60.0, 59.0, 83.0, 58.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 73.0, 92.0, 70.0, 69.0, 71.0, 91.0, 68.0, 70.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 88.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 101.0, 133.0, 142.0, 85.0, 85.0, 85.0, 87.0, 87.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 56.0, 55.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 63.0, 75.0, 72.0, 68.0, 69.0, 70.0, 68.0, 72.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 88.0, 90.0, 89.0, 88.0, 91.0, 90.0, 99.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 164.0, 146.0, 161.0, 157.0, 154.0, 139.0, 151.0, 143.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 172.0, 184.0, 151.0, 229.0, 228.0, 185.0, 173.0, 171.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 72.0, 71.0, 67.0, 68.0, 71.0, 69.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1475.2, "alertThreshold": 2.0}]} 03:30:18 INFO - Return code: 0 03:30:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:30:18 INFO - # TBPL SUCCESS # 03:30:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:30:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:30:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:30:18 INFO - 2016-10-04 03:30:18.768 screenresolution[1673:9909] starting screenresolution argv=screenresolution get 03:30:18 INFO - 2016-10-04 03:30:18.771 screenresolution[1673:9909] Display 0: 1600x1200x32@60 03:30:18 INFO - 2016-10-04 03:30:18.777 screenresolution[1674:9912] starting screenresolution argv=screenresolution list 03:30:18 INFO - Available Modes on Display 0 03:30:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:30:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:30:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:30:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:30:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:30:19 INFO - Intel Iris: 03:30:19 INFO - Chipset Model: Intel Iris 03:30:19 INFO - Type: GPU 03:30:19 INFO - Bus: Built-In 03:30:19 INFO - VRAM (Dynamic, Max): 1536 MB 03:30:19 INFO - Vendor: Intel (0x8086) 03:30:19 INFO - Device ID: 0x0a2e 03:30:19 INFO - Revision ID: 0x0009 03:30:19 INFO - Displays: 03:30:19 INFO - Display: 03:30:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:30:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:30:19 INFO - Display Serial Number: 1600x1200 60 03:30:19 INFO - Main Display: Yes 03:30:19 INFO - Mirror: Off 03:30:19 INFO - Online: Yes 03:30:19 INFO - Rotation: Supported 03:30:19 INFO - Return code: 0 03:30:19 INFO - Running post-action listener: _resource_record_post_action 03:30:19 INFO - [mozharness: 2016-10-04 10:30:19.020614Z] Finished run-tests step (success) 03:30:19 INFO - Running post-run listener: _resource_record_post_run 03:30:19 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38675456; Write bytes: 707609600; Read time: 380; Write time: 1820 03:30:19 INFO - TinderboxPrint: CPU usage
53.4% 03:30:19 INFO - TinderboxPrint: I/O read bytes / time
38,675,456 / 380 03:30:19 INFO - TinderboxPrint: I/O write bytes / time
707,609,600 / 1,820 03:30:19 INFO - TinderboxPrint: CPU idle
2,264.0 (46.6%) 03:30:19 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 03:30:19 INFO - TinderboxPrint: CPU user
1,623.3 (33.4%) 03:30:19 INFO - TinderboxPrint: Swap in / out
446,111,744 / 0 03:30:19 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395614720; Write bytes: 390305792; Read time: 29556; Write time: 696 03:30:19 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36729856; Write bytes: 296328192; Read time: 360; Write time: 1079 03:30:19 INFO - Running post-run listener: _upload_blobber_files 03:30:19 INFO - Blob upload gear active. 03:30:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:30:19 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 03:30:19 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'] 03:30:19 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 03:30:19 INFO - (blobuploader) - INFO - Open directory for files ... 03:30:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:30:20 INFO - (blobuploader) - INFO - Done attempting. 03:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:30:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:30:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:30:21 INFO - (blobuploader) - INFO - Done attempting. 03:30:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:30:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:30:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:30:24 INFO - (blobuploader) - INFO - Done attempting. 03:30:24 INFO - (blobuploader) - INFO - Iteration through files over. 03:30:24 INFO - Return code: 0 03:30:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:30:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:30:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e977b17f14122ec3e957395d55bced0973aa8b1300d811d87692e67864f46f3eb95907ad6cfd36429b409ed9c0f3083eb5131687b13ad86b1254bc833bc99748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f36458d5e25010ad73f727b986f0104869de907f3e2354b3a53c916b817b2003af5642ded8917f8e128f08ecfb7ff57b8a79e1c513df509f72cb230b2d3a20e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c70e7702afa36f9104f4671ad51946898b66cd78b66de555b49200b71e7ba4bb34c33d2c64352f5dda18930afa8019ba24782e7431dae947c2e5990c393bc5ab"} 03:30:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:30:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:30:24 INFO - Contents: 03:30:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e977b17f14122ec3e957395d55bced0973aa8b1300d811d87692e67864f46f3eb95907ad6cfd36429b409ed9c0f3083eb5131687b13ad86b1254bc833bc99748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f36458d5e25010ad73f727b986f0104869de907f3e2354b3a53c916b817b2003af5642ded8917f8e128f08ecfb7ff57b8a79e1c513df509f72cb230b2d3a20e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c70e7702afa36f9104f4671ad51946898b66cd78b66de555b49200b71e7ba4bb34c33d2c64352f5dda18930afa8019ba24782e7431dae947c2e5990c393bc5ab"} 03:30:24 INFO - Running post-run listener: copy_logs_to_upload_dir 03:30:24 INFO - Copying logs to upload dir... 03:30:24 INFO - mkdir: /builds/slave/test/build/upload/logs 03:30:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.166549 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-04 03:30:24.133974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 03:30:24.137985) ========= 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.PFGC8vmuvo/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.CljYH41lqW/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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/e977b17f14122ec3e957395d55bced0973aa8b1300d811d87692e67864f46f3eb95907ad6cfd36429b409ed9c0f3083eb5131687b13ad86b1254bc833bc99748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f36458d5e25010ad73f727b986f0104869de907f3e2354b3a53c916b817b2003af5642ded8917f8e128f08ecfb7ff57b8a79e1c513df509f72cb230b2d3a20e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c70e7702afa36f9104f4671ad51946898b66cd78b66de555b49200b71e7ba4bb34c33d2c64352f5dda18930afa8019ba24782e7431dae947c2e5990c393bc5ab"} build_url:https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011071 build_url: 'https://queue.taskcluster.net/v1/task/b8NHgwt1Tu2rCJesq5kduw/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/e977b17f14122ec3e957395d55bced0973aa8b1300d811d87692e67864f46f3eb95907ad6cfd36429b409ed9c0f3083eb5131687b13ad86b1254bc833bc99748", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f36458d5e25010ad73f727b986f0104869de907f3e2354b3a53c916b817b2003af5642ded8917f8e128f08ecfb7ff57b8a79e1c513df509f72cb230b2d3a20e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c70e7702afa36f9104f4671ad51946898b66cd78b66de555b49200b71e7ba4bb34c33d2c64352f5dda18930afa8019ba24782e7431dae947c2e5990c393bc5ab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 03:30:24.172701) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 03:30:24.173143) ========= 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.PFGC8vmuvo/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.CljYH41lqW/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.005113 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 03:30:24.223331) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 03:30:24.223717) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 03:30:24.224141) ========= ========= Total master_lag: 1.49 =========