builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0243 starttime: 1472573198.55 results: success (0) buildid: 20160830065621 builduid: a730868a64b54429a50884c6ee4274ec revision: b18c8bcdc116eef8799880b7c50317bf54218474 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:38.547167) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:38.548284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:38.548782) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:38.588112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:38.588460) ========= 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.GzzwlpItcq/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.Idr2Smi1p5/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-08-30 09:06:38-- 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% 140M=0s 2016-08-30 09:06:39 (140 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.598530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:39.210988) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:39.214414) ========= 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.GzzwlpItcq/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.Idr2Smi1p5/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.019837 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:39.266422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 09:06:39.266853) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b18c8bcdc116eef8799880b7c50317bf54218474 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b18c8bcdc116eef8799880b7c50317bf54218474 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GzzwlpItcq/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.Idr2Smi1p5/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-08-30 09:06:39,350 truncating revision to first 12 chars 2016-08-30 09:06:39,350 Setting DEBUG logging. 2016-08-30 09:06:39,350 attempt 1/10 2016-08-30 09:06:39,350 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b18c8bcdc116?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 09:06:40,375 unpacking tar archive at: fx-team-b18c8bcdc116/testing/mozharness/ program finished with exit code 0 elapsedTime=1.284006 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 09:06:40.573591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:40.573896) ========= script_repo_revision: b18c8bcdc116eef8799880b7c50317bf54218474 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:40.574293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:40.574574) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 09:06:40.596864) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-30 09:06:40.597191) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GzzwlpItcq/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.Idr2Smi1p5/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:06:40 INFO - MultiFileLogger online at 20160830 09:06:40 in /builds/slave/test 09:06:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:06:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:40 INFO - {'append_to_log': False, 09:06:40 INFO - 'base_work_dir': '/builds/slave/test', 09:06:40 INFO - 'blob_upload_branch': 'Fx-Team', 09:06:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:40 INFO - 'branch': 'Fx-Team', 09:06:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:40 INFO - 'config_files': ('talos/mac_config.py',), 09:06:40 INFO - 'default_actions': ('clobber', 09:06:40 INFO - 'read-buildbot-config', 09:06:40 INFO - 'download-and-extract', 09:06:40 INFO - 'populate-webroot', 09:06:40 INFO - 'create-virtualenv', 09:06:40 INFO - 'install', 09:06:40 INFO - 'run-tests'), 09:06:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:40 INFO - 'download_minidump_stackwalk': True, 09:06:40 INFO - 'download_symbols': 'ondemand', 09:06:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:40 INFO - 'installer_path': 'installer.exe', 09:06:40 INFO - 'log_level': 'info', 09:06:40 INFO - 'log_name': 'talos', 09:06:40 INFO - 'log_to_console': True, 09:06:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:06:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:06:40 INFO - 'opt_config_files': (), 09:06:40 INFO - 'pip_index': False, 09:06:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:40 INFO - 'cmd': ('bash', 09:06:40 INFO - '-c', 09:06:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:40 INFO - 'enabled': True, 09:06:40 INFO - 'halt_on_failure': False, 09:06:40 INFO - 'name': 'check_screen_resolution'},), 09:06:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:40 INFO - 'cmd': ('bash', 09:06:40 INFO - '-c', 09:06:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:40 INFO - 'enabled': True, 09:06:40 INFO - 'halt_on_failure': False, 09:06:40 INFO - 'name': 'check_screen_resolution'},), 09:06:40 INFO - 'run_cmd_checks_enabled': True, 09:06:40 INFO - 'sps_profile': False, 09:06:40 INFO - 'sps_profile_interval': 0, 09:06:40 INFO - 'suite': 'dromaeojs', 09:06:40 INFO - 'system_bits': '32', 09:06:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:40 INFO - 'title': 't-yosemite-r7-0243', 09:06:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:40 INFO - 'use_talos_json': True, 09:06:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:40 INFO - 'work_dir': 'build'} 09:06:40 INFO - [mozharness: 2016-08-30 16:06:40.791701Z] Running clobber step. 09:06:40 INFO - Running pre-action listener: _resource_record_pre_action 09:06:40 INFO - Running main action method: clobber 09:06:40 INFO - rmtree: /builds/slave/test/build 09:06:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:42 INFO - Running post-action listener: _resource_record_post_action 09:06:42 INFO - [mozharness: 2016-08-30 16:06:42.555139Z] Finished clobber step (success) 09:06:42 INFO - [mozharness: 2016-08-30 16:06:42.555285Z] Running read-buildbot-config step. 09:06:42 INFO - Running pre-action listener: _resource_record_pre_action 09:06:42 INFO - Running main action method: read_buildbot_config 09:06:42 INFO - Using buildbot properties: 09:06:42 INFO - { 09:06:42 INFO - "project": "", 09:06:42 INFO - "product": "firefox", 09:06:42 INFO - "script_repo_revision": "production", 09:06:42 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:06:42 INFO - "repository": "", 09:06:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:06:42 INFO - "buildid": "20160830065621", 09:06:42 INFO - "pgo_build": "False", 09:06:42 INFO - "basedir": "/builds/slave/test", 09:06:42 INFO - "buildnumber": 827, 09:06:42 INFO - "slavename": "t-yosemite-r7-0243", 09:06:42 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:06:42 INFO - "platform": "yosemite_r7", 09:06:42 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:06:42 INFO - "branch": "fx-team", 09:06:42 INFO - "repo_path": "integration/fx-team", 09:06:42 INFO - "revision": "b18c8bcdc116eef8799880b7c50317bf54218474", 09:06:42 INFO - "stage_platform": "macosx64", 09:06:42 INFO - "builduid": "a730868a64b54429a50884c6ee4274ec", 09:06:42 INFO - "slavebuilddir": "test" 09:06:42 INFO - } 09:06:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:06:42 INFO - Running post-action listener: _resource_record_post_action 09:06:42 INFO - [mozharness: 2016-08-30 16:06:42.558621Z] Finished read-buildbot-config step (success) 09:06:42 INFO - [mozharness: 2016-08-30 16:06:42.558730Z] Running download-and-extract step. 09:06:42 INFO - Running pre-action listener: _resource_record_pre_action 09:06:42 INFO - Running main action method: download_and_extract 09:06:42 INFO - mkdir: /builds/slave/test/build/tests 09:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:42 INFO - trying https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:06:42 INFO - Downloading https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:06:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:06:43 INFO - Downloaded 1199 bytes. 09:06:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:06:43 INFO - Using the following test package requirements: 09:06:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:06:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:06:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:06:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'jsshell-mac.zip'], 09:06:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:06:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:06:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:06:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:06:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:06:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:06:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:06:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:43 INFO - trying https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:06:43 INFO - Downloading https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:44 INFO - Downloaded 16380227 bytes. 09:06:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:06:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:51 INFO - trying https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:06:51 INFO - Downloading https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:06:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:52 INFO - Downloaded 16380227 bytes. 09:06:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:59 INFO - trying https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:06:59 INFO - Downloading https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:07:01 INFO - Downloaded 14413998 bytes. 09:07:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:01 INFO - trying https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:07:01 INFO - Downloading https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:07:04 INFO - Downloaded 111564228 bytes. 09:07:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:07:04 INFO - mkdir: /builds/slave/test/properties 09:07:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:04 INFO - Contents: 09:07:04 INFO - build_url:https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:07:05 INFO - Running post-action listener: _resource_record_post_action 09:07:05 INFO - Running post-action listener: set_extra_try_arguments 09:07:05 INFO - [mozharness: 2016-08-30 16:07:05.293706Z] Finished download-and-extract step (success) 09:07:05 INFO - [mozharness: 2016-08-30 16:07:05.293867Z] Running populate-webroot step. 09:07:05 INFO - Running pre-action listener: _resource_record_pre_action 09:07:05 INFO - Running main action method: populate_webroot 09:07:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:05 INFO - '--tppagecycles', 09:07:05 INFO - '7'], 09:07:05 INFO - 'tests': ['tsvgm']}}, 09:07:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['damp', 'tps']}, 09:07:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'tests': ['damp', 'tps']}, 09:07:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['dromaeo_dom']}, 09:07:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['basic_compositor_video']}, 09:07:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['a11yr', 09:07:05 INFO - 'ts_paint', 09:07:05 INFO - 'tpaint', 09:07:05 INFO - 'sessionrestore', 09:07:05 INFO - 'sessionrestore_no_auto_restore', 09:07:05 INFO - 'tabpaint']}, 09:07:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:05 INFO - 'ts_paint', 09:07:05 INFO - 'tpaint', 09:07:05 INFO - 'sessionrestore', 09:07:05 INFO - 'sessionrestore_no_auto_restore', 09:07:05 INFO - 'tabpaint']}, 09:07:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:05 INFO - 'ts_paint', 09:07:05 INFO - 'tpaint', 09:07:05 INFO - 'sessionrestore', 09:07:05 INFO - 'sessionrestore_no_auto_restore', 09:07:05 INFO - 'tabpaint']}, 09:07:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:05 INFO - 'ts_paint', 09:07:05 INFO - 'tpaint', 09:07:05 INFO - 'sessionrestore', 09:07:05 INFO - 'sessionrestore_no_auto_restore', 09:07:05 INFO - 'tabpaint']}, 09:07:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['a11yr', 09:07:05 INFO - 'ts_paint', 09:07:05 INFO - 'tpaint', 09:07:05 INFO - 'sessionrestore', 09:07:05 INFO - 'sessionrestore_no_auto_restore', 09:07:05 INFO - 'tabpaint']}, 09:07:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['a11yr', 09:07:05 INFO - 'ts_paint', 09:07:05 INFO - 'tpaint', 09:07:05 INFO - 'sessionrestore', 09:07:05 INFO - 'sessionrestore_no_auto_restore', 09:07:05 INFO - 'tabpaint']}, 09:07:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['tsvgx', 09:07:05 INFO - 'tsvgr_opacity', 09:07:05 INFO - 'tart', 09:07:05 INFO - 'tscrollx', 09:07:05 INFO - 'cart']}, 09:07:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:05 INFO - 'tsvgr_opacity', 09:07:05 INFO - 'tart', 09:07:05 INFO - 'tscrollx', 09:07:05 INFO - 'cart']}, 09:07:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'talos_options': ['--disable-e10s'], 09:07:05 INFO - 'tests': ['tp5o']}, 09:07:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'tests': ['tp5o']}, 09:07:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'talos_options': ['--disable-e10s', 09:07:05 INFO - '--xperf_path', 09:07:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:05 INFO - 'tests': ['tp5n']}, 09:07:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:05 INFO - 'talos_options': ['--xperf_path', 09:07:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:05 INFO - 'tests': ['tp5n']}}, 09:07:05 INFO - 'talos.zip': {'path': '', 09:07:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:05 INFO - Running post-action listener: _resource_record_post_action 09:07:05 INFO - [mozharness: 2016-08-30 16:07:05.312750Z] Finished populate-webroot step (success) 09:07:05 INFO - [mozharness: 2016-08-30 16:07:05.312854Z] Running create-virtualenv step. 09:07:05 INFO - Running pre-action listener: _resource_record_pre_action 09:07:05 INFO - Running main action method: create_virtualenv 09:07:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:05 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 09:07:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:05 INFO - Using real prefix '/tools/python27' 09:07:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:08 INFO - Installing pip.................done. 09:07:08 INFO - Return code: 0 09:07:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07:08 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')]} 09:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:08 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')]} 09:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:08 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:08 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 09:07:08 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 09:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:08 INFO - 'HOME': '/Users/cltbld', 09:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:08 INFO - 'LOGNAME': 'cltbld', 09:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:08 INFO - 'MOZ_NO_REMOTE': '1', 09:07:08 INFO - 'NO_EM_RESTART': '1', 09:07:08 INFO - 'PAGER': '/bin/cat', 09:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:08 INFO - 'PWD': '/builds/slave/test', 09:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:08 INFO - 'SHELL': '/bin/bash', 09:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:08 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:08 INFO - 'USER': 'cltbld', 09:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:08 INFO - 'XPC_FLAGS': '0x0', 09:07:08 INFO - 'XPC_SERVICE_NAME': '0', 09:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:09 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 09:07:09 INFO - Cleaning up... 09:07:09 INFO - Return code: 0 09:07:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07:09 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')]} 09:07:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:09 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')]} 09:07:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:09 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:09 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 09:07:09 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 09:07:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:09 INFO - 'HOME': '/Users/cltbld', 09:07:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:09 INFO - 'LOGNAME': 'cltbld', 09:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:09 INFO - 'MOZ_NO_REMOTE': '1', 09:07:09 INFO - 'NO_EM_RESTART': '1', 09:07:09 INFO - 'PAGER': '/bin/cat', 09:07:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:09 INFO - 'PWD': '/builds/slave/test', 09:07:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:09 INFO - 'SHELL': '/bin/bash', 09:07:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:09 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:09 INFO - 'USER': 'cltbld', 09:07:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:09 INFO - 'XPC_FLAGS': '0x0', 09:07:09 INFO - 'XPC_SERVICE_NAME': '0', 09:07:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:09 INFO - Downloading/unpacking psutil>=3.1.1 09:07:09 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 09:07:09 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 09:07:09 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 09:07:09 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 09:07:09 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 09:07:09 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 09:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:13 INFO - Installing collected packages: psutil 09:07:13 INFO - Running setup.py install for psutil 09:07:13 INFO - building 'psutil._psutil_osx' extension 09:07:13 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 09:07:13 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 09:07:13 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 09:07:13 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 09:07:13 INFO - building 'psutil._psutil_posix' extension 09:07:13 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 09:07:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:07:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:07:13 INFO - ^ 09:07:13 INFO - 1 warning generated. 09:07:13 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 09:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:13 INFO - Successfully installed psutil 09:07:13 INFO - Cleaning up... 09:07:13 INFO - Return code: 0 09:07:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07:13 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')]} 09:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:13 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')]} 09:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:13 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:13 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 09:07:13 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 09:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:13 INFO - 'HOME': '/Users/cltbld', 09:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:13 INFO - 'LOGNAME': 'cltbld', 09:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:13 INFO - 'MOZ_NO_REMOTE': '1', 09:07:13 INFO - 'NO_EM_RESTART': '1', 09:07:13 INFO - 'PAGER': '/bin/cat', 09:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:13 INFO - 'PWD': '/builds/slave/test', 09:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:13 INFO - 'SHELL': '/bin/bash', 09:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:13 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:13 INFO - 'USER': 'cltbld', 09:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:13 INFO - 'XPC_FLAGS': '0x0', 09:07:13 INFO - 'XPC_SERVICE_NAME': '0', 09:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:14 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 09:07:14 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 09:07:14 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 09:07:14 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 09:07:14 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 09:07:14 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 09:07:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:16 INFO - Installing collected packages: mozsystemmonitor 09:07:16 INFO - Running setup.py install for mozsystemmonitor 09:07:16 INFO - Successfully installed mozsystemmonitor 09:07:16 INFO - Cleaning up... 09:07:16 INFO - Return code: 0 09:07:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:16 INFO - 'HOME': '/Users/cltbld', 09:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:16 INFO - 'LOGNAME': 'cltbld', 09:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:16 INFO - 'MOZ_NO_REMOTE': '1', 09:07:16 INFO - 'NO_EM_RESTART': '1', 09:07:16 INFO - 'PAGER': '/bin/cat', 09:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:16 INFO - 'PWD': '/builds/slave/test', 09:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:16 INFO - 'SHELL': '/bin/bash', 09:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:16 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:16 INFO - 'USER': 'cltbld', 09:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:16 INFO - 'XPC_FLAGS': '0x0', 09:07:16 INFO - 'XPC_SERVICE_NAME': '0', 09:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:17 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:17 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 09:07:17 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 09:07:17 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 09:07:17 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 09:07:17 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 09:07:17 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 09:07:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:07:19 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 09:07:19 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 09:07:19 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 09:07:19 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 09:07:19 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 09:07:19 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 09:07:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:20 INFO - warning: no files found matching '*.txt' 09:07:20 INFO - no previously-included directories found matching 'build' 09:07:20 INFO - no previously-included directories found matching 'dist' 09:07:20 INFO - no previously-included directories found matching '.git*' 09:07:20 INFO - Installing collected packages: jsonschema, functools32 09:07:20 INFO - Running setup.py install for functools32 09:07:20 INFO - warning: no files found matching '*.txt' 09:07:20 INFO - no previously-included directories found matching 'build' 09:07:20 INFO - no previously-included directories found matching 'dist' 09:07:20 INFO - no previously-included directories found matching '.git*' 09:07:20 INFO - Successfully installed jsonschema functools32 09:07:20 INFO - Cleaning up... 09:07:20 INFO - Return code: 0 09:07:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:20 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:20 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 09:07:20 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 09:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:20 INFO - 'HOME': '/Users/cltbld', 09:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:20 INFO - 'LOGNAME': 'cltbld', 09:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:20 INFO - 'MOZ_NO_REMOTE': '1', 09:07:20 INFO - 'NO_EM_RESTART': '1', 09:07:20 INFO - 'PAGER': '/bin/cat', 09:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:20 INFO - 'PWD': '/builds/slave/test', 09:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:20 INFO - 'SHELL': '/bin/bash', 09:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:20 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:20 INFO - 'USER': 'cltbld', 09:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:20 INFO - 'XPC_FLAGS': '0x0', 09:07:20 INFO - 'XPC_SERVICE_NAME': '0', 09:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:20 INFO - Cleaning up... 09:07:20 INFO - Return code: 0 09:07:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:20 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:20 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 09:07:20 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 09:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:20 INFO - 'HOME': '/Users/cltbld', 09:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:20 INFO - 'LOGNAME': 'cltbld', 09:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:20 INFO - 'MOZ_NO_REMOTE': '1', 09:07:20 INFO - 'NO_EM_RESTART': '1', 09:07:20 INFO - 'PAGER': '/bin/cat', 09:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:20 INFO - 'PWD': '/builds/slave/test', 09:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:20 INFO - 'SHELL': '/bin/bash', 09:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:20 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:20 INFO - 'USER': 'cltbld', 09:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:20 INFO - 'XPC_FLAGS': '0x0', 09:07:20 INFO - 'XPC_SERVICE_NAME': '0', 09:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:20 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 09:07:20 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 09:07:20 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 09:07:20 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 09:07:20 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 09:07:20 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 09:07:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:23 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 09:07:23 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 09:07:23 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 09:07:23 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 09:07:23 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 09:07:23 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 09:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:24 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 09:07:24 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 09:07:24 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 09:07:24 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 09:07:24 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 09:07:24 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 09:07:24 INFO - Downloading docopt-0.6.1.tar.gz 09:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:24 INFO - Running setup.py install for blobuploader 09:07:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:24 INFO - Running setup.py install for requests 09:07:25 INFO - Running setup.py install for docopt 09:07:25 INFO - Successfully installed blobuploader requests docopt 09:07:25 INFO - Cleaning up... 09:07:25 INFO - Return code: 0 09:07:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:25 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:25 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 09:07:25 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 09:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:25 INFO - 'HOME': '/Users/cltbld', 09:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:25 INFO - 'LOGNAME': 'cltbld', 09:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:25 INFO - 'MOZ_NO_REMOTE': '1', 09:07:25 INFO - 'NO_EM_RESTART': '1', 09:07:25 INFO - 'PAGER': '/bin/cat', 09:07:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:25 INFO - 'PWD': '/builds/slave/test', 09:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:25 INFO - 'SHELL': '/bin/bash', 09:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:25 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:25 INFO - 'USER': 'cltbld', 09:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:25 INFO - 'XPC_FLAGS': '0x0', 09:07:25 INFO - 'XPC_SERVICE_NAME': '0', 09:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-p_PO9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-oV5gtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:25 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-KhAw5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-blMMUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kcm2wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-v_gA4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Z1LRnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4cEfgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OD1jMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-gMM_WQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kGi4Yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Dgw4co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:26 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-QE1Kvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:27 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-l_DbHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:27 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-x05GPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:27 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-_C_4r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:27 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HhvWLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:27 INFO - Running setup.py install for manifestparser 09:07:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:27 INFO - Running setup.py install for mozcrash 09:07:27 INFO - Running setup.py install for mozdebug 09:07:27 INFO - Running setup.py install for mozdevice 09:07:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Running setup.py install for mozfile 09:07:28 INFO - Running setup.py install for mozhttpd 09:07:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Running setup.py install for mozinfo 09:07:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Running setup.py install for mozInstall 09:07:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Running setup.py install for mozleak 09:07:28 INFO - Running setup.py install for mozlog 09:07:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:28 INFO - Running setup.py install for moznetwork 09:07:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:29 INFO - Running setup.py install for mozprocess 09:07:29 INFO - Running setup.py install for mozprofile 09:07:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:29 INFO - Running setup.py install for mozrunner 09:07:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:29 INFO - Running setup.py install for mozscreenshot 09:07:29 INFO - Running setup.py install for moztest 09:07:29 INFO - Running setup.py install for mozversion 09:07:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:30 INFO - Cleaning up... 09:07:30 INFO - Return code: 0 09:07:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:30 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:30 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 09:07:30 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 09:07:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:30 INFO - 'HOME': '/Users/cltbld', 09:07:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:30 INFO - 'LOGNAME': 'cltbld', 09:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:30 INFO - 'MOZ_NO_REMOTE': '1', 09:07:30 INFO - 'NO_EM_RESTART': '1', 09:07:30 INFO - 'PAGER': '/bin/cat', 09:07:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:30 INFO - 'PWD': '/builds/slave/test', 09:07:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:30 INFO - 'SHELL': '/bin/bash', 09:07:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:30 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:30 INFO - 'USER': 'cltbld', 09:07:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:30 INFO - 'XPC_FLAGS': '0x0', 09:07:30 INFO - 'XPC_SERVICE_NAME': '0', 09:07:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-kF_tmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:30 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)) 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-OJ5o1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:30 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)) 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-63DNOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:30 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)) 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-QADCAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:30 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)) 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-d3aS_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:30 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)) 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-X3kRrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:30 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)) 09:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:30 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XxJMEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0KTgmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-qinMIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-JaJEPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-I_3yiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-p66p8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-EWsGcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-s0Ysbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hWLGXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:31 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-dUm1o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:31 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)) 09:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:32 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-79_H9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:32 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)) 09:07:32 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)) 09:07:32 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)) 09:07:32 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)) 09:07:32 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)) 09:07:32 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)) 09:07:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:34 INFO - Downloading blessings-1.6.tar.gz 09:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:34 INFO - Installing collected packages: blessings 09:07:34 INFO - Running setup.py install for blessings 09:07:34 INFO - Successfully installed blessings 09:07:34 INFO - Cleaning up... 09:07:34 INFO - Return code: 0 09:07:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:35 INFO - Reading from file tmpfile_stdout 09:07:35 INFO - Current package versions: 09:07:35 INFO - blessings == 1.6 09:07:35 INFO - blobuploader == 1.2.4 09:07:35 INFO - docopt == 0.6.1 09:07:35 INFO - functools32 == 3.2.3-2 09:07:35 INFO - jsonschema == 2.5.1 09:07:35 INFO - manifestparser == 1.1 09:07:35 INFO - mozInstall == 1.12 09:07:35 INFO - mozcrash == 0.17 09:07:35 INFO - mozdebug == 0.1 09:07:35 INFO - mozdevice == 0.48 09:07:35 INFO - mozfile == 1.2 09:07:35 INFO - mozhttpd == 0.7 09:07:35 INFO - mozinfo == 0.9 09:07:35 INFO - mozleak == 0.1 09:07:35 INFO - mozlog == 3.3 09:07:35 INFO - moznetwork == 0.27 09:07:35 INFO - mozprocess == 0.23 09:07:35 INFO - mozprofile == 0.28 09:07:35 INFO - mozrunner == 6.12 09:07:35 INFO - mozscreenshot == 0.1 09:07:35 INFO - mozsystemmonitor == 0.3 09:07:35 INFO - moztest == 0.7 09:07:35 INFO - mozversion == 1.4 09:07:35 INFO - psutil == 3.1.1 09:07:35 INFO - requests == 1.2.3 09:07:35 INFO - wsgiref == 0.1.2 09:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:35 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')]} 09:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 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')]} 09:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:35 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 09:07:35 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 09:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:35 INFO - 'HOME': '/Users/cltbld', 09:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:35 INFO - 'LOGNAME': 'cltbld', 09:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:35 INFO - 'MOZ_NO_REMOTE': '1', 09:07:35 INFO - 'NO_EM_RESTART': '1', 09:07:35 INFO - 'PAGER': '/bin/cat', 09:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:35 INFO - 'PWD': '/builds/slave/test', 09:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:35 INFO - 'SHELL': '/bin/bash', 09:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:35 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:35 INFO - 'USER': 'cltbld', 09:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:35 INFO - 'XPC_FLAGS': '0x0', 09:07:35 INFO - 'XPC_SERVICE_NAME': '0', 09:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 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)) 09:07:35 INFO - Cleaning up... 09:07:35 INFO - Return code: 0 09:07:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07:35 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')]} 09:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 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')]} 09:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 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 0x10977ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109810618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab73ccea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, '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.GzzwlpItcq/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.Idr2Smi1p5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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 09:07:35 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 09:07:35 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 09:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:35 INFO - 'HOME': '/Users/cltbld', 09:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:35 INFO - 'LOGNAME': 'cltbld', 09:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:35 INFO - 'MOZ_NO_REMOTE': '1', 09:07:35 INFO - 'NO_EM_RESTART': '1', 09:07:35 INFO - 'PAGER': '/bin/cat', 09:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:35 INFO - 'PWD': '/builds/slave/test', 09:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:35 INFO - 'SHELL': '/bin/bash', 09:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:07:35 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:35 INFO - 'USER': 'cltbld', 09:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:35 INFO - 'XPC_FLAGS': '0x0', 09:07:35 INFO - 'XPC_SERVICE_NAME': '0', 09:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:07:35 INFO - Cleaning up... 09:07:35 INFO - Return code: 0 09:07:35 INFO - Running post-action listener: _resource_record_post_action 09:07:35 INFO - Running post-action listener: _start_resource_monitoring 09:07:35 INFO - Starting resource monitoring. 09:07:35 INFO - [mozharness: 2016-08-30 16:07:35.765382Z] Finished create-virtualenv step (success) 09:07:35 INFO - [mozharness: 2016-08-30 16:07:35.766385Z] Running install step. 09:07:35 INFO - Running pre-action listener: _resource_record_pre_action 09:07:35 INFO - Running main action method: install 09:07:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:36 INFO - Reading from file tmpfile_stdout 09:07:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:36 INFO - Reading from file tmpfile_stdout 09:07:36 INFO - Output received: 09:07:36 INFO - Usage: mozinstall [options] installer 09:07:36 INFO - Options: 09:07:36 INFO - -h, --help show this help message and exit 09:07:36 INFO - -d DEST, --destination=DEST 09:07:36 INFO - Directory to install application into. [default: 09:07:36 INFO - "/builds/slave/test"] 09:07:36 INFO - --app=APP Application being installed. [default: firefox] 09:07:36 INFO - mkdir: /builds/slave/test/build/application 09:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:08:10 INFO - Reading from file tmpfile_stdout 09:08:10 INFO - Output received: 09:08:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:08:10 INFO - Running post-action listener: _resource_record_post_action 09:08:10 INFO - [mozharness: 2016-08-30 16:08:10.677329Z] Finished install step (success) 09:08:10 INFO - [mozharness: 2016-08-30 16:08:10.677474Z] Running run-tests step. 09:08:10 INFO - Running pre-action listener: _resource_record_pre_action 09:08:10 INFO - Running main action method: run_tests 09:08:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:08:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:08:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:08:10 INFO - 2016-08-30 09:08:10.691 screenresolution[1917:17426] starting screenresolution argv=screenresolution get 09:08:10 INFO - 2016-08-30 09:08:10.694 screenresolution[1917:17426] Display 0: 1600x1200x32@60 09:08:10 INFO - 2016-08-30 09:08:10.700 screenresolution[1918:17428] starting screenresolution argv=screenresolution list 09:08:10 INFO - Available Modes on Display 0 09:08:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:08:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:08:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:08:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:08:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:08:10 INFO - Intel Iris: 09:08:10 INFO - Chipset Model: Intel Iris 09:08:10 INFO - Type: GPU 09:08:10 INFO - Bus: Built-In 09:08:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:08:10 INFO - Vendor: Intel (0x8086) 09:08:10 INFO - Device ID: 0x0a2e 09:08:10 INFO - Revision ID: 0x0009 09:08:10 INFO - Displays: 09:08:10 INFO - Display: 09:08:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:08:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:08:10 INFO - Display Serial Number: 1600x1200 60 09:08:10 INFO - Main Display: Yes 09:08:10 INFO - Mirror: Off 09:08:10 INFO - Online: Yes 09:08:10 INFO - Rotation: Supported 09:08:10 INFO - Return code: 0 09:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:10 INFO - Python 2.7.3 09:08:10 INFO - Return code: 0 09:08:10 INFO - grabbing minidump binary from tooltool 09:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:10 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 0x1096dfd30>, '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 0x109444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:10 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 09:08:10 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 09:08:10 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 09:08:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:11 INFO - Return code: 0 09:08:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:08:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:08:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 09:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --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 09:08:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GzzwlpItcq/Render', 09:08:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:11 INFO - 'HOME': '/Users/cltbld', 09:08:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:11 INFO - 'LOGNAME': 'cltbld', 09:08:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:11 INFO - 'MOZ_NO_REMOTE': '1', 09:08:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:11 INFO - 'NO_EM_RESTART': '1', 09:08:11 INFO - 'PAGER': '/bin/cat', 09:08:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:11 INFO - 'PWD': '/builds/slave/test', 09:08:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:11 INFO - 'SHELL': '/bin/bash', 09:08:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Idr2Smi1p5/Listeners', 09:08:11 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:08:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:11 INFO - 'USER': 'cltbld', 09:08:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:11 INFO - 'XPC_FLAGS': '0x0', 09:08:11 INFO - 'XPC_SERVICE_NAME': '0', 09:08:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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 09:08:11 INFO - mozversion application_buildid: 20160830065621 09:08:11 INFO - mozversion application_changeset: b18c8bcdc116eef8799880b7c50317bf54218474 09:08:11 INFO - mozversion application_display_name: Nightly 09:08:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:11 INFO - mozversion application_name: Firefox 09:08:11 INFO - mozversion application_remotingname: firefox 09:08:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:08:11 INFO - mozversion application_vendor: Mozilla 09:08:11 INFO - mozversion application_version: 51.0a1 09:08:11 INFO - mozversion platform_buildid: 20160830065621 09:08:11 INFO - mozversion platform_changeset: b18c8bcdc116eef8799880b7c50317bf54218474 09:08:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:08:11 INFO - mozversion platform_version: 51.0a1 09:08:11 INFO - using testdate: 1472573291 09:08:11 INFO - actual date: 1472573291 09:08:11 INFO - starting webserver on 'localhost:52588' 09:08:11 INFO - SUITE-START | Running 2 tests 09:08:11 INFO - TEST-START | dromaeo_css 09:08:11 INFO - operating with platform_type : mac_ 09:08:11 INFO - Initialising browser for dromaeo_css test... 09:08:11 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpnxyYb7/profile http://localhost:52588/getInfo.html) 09:08:13 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 09:08:13 INFO - PROCESS | 1928 | colorDepth:24 09:08:13 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 09:08:13 INFO - PROCESS | 1928 | __metrics 09:08:13 INFO - PROCESS | 1928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:13 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:14 INFO - TEST-INFO | 1928: exit 0 09:08:14 INFO - Browser initialized. 09:08:14 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:14 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpnxyYb7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:26 INFO - PROCESS | 1930 | RSS: Main: 234819584 09:08:26 INFO - PROCESS | 1930 | 09:09:17 INFO - PROCESS | 1930 | 1472573357894 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] 09:11:29 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52588/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52588/tests/dromaeo/cssquery-ext.html) 09:11:31 INFO - PROCESS | 1930 | RSS: Main: 343003136 09:11:31 INFO - PROCESS | 1930 | 09:14:34 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52588/tests/dromaeo/cssquery-ext.html (next: http://localhost:52588/tests/dromaeo/cssquery-jquery.html) 09:14:34 INFO - PROCESS | 1930 | RSS: Main: 367550464 09:14:34 INFO - PROCESS | 1930 | 09:17:37 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52588/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52588/tests/dromaeo/cssquery-mootools.html) 09:17:37 INFO - PROCESS | 1930 | RSS: Main: 335826944 09:17:37 INFO - PROCESS | 1930 | 09:20:40 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52588/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52588/tests/dromaeo/cssquery-prototype.html) 09:20:40 INFO - PROCESS | 1930 | RSS: Main: 333914112 09:20:40 INFO - PROCESS | 1930 | 09:23:44 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52588/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52588/tests/dromaeo/cssquery-yui.html) 09:23:44 INFO - PROCESS | 1930 | RSS: Main: 323395584 09:23:44 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52588/tests/dromaeo/cssquery-yui.html (next: http://localhost:52588/tests/dromaeo/cssquery-dojo.html) 09:26:48 INFO - PROCESS | 1930 | RSS: Main: 421388288 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | __start_tp_report 09:26:48 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 09:26:48 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 09:26:48 INFO - PROCESS | 1930 | |i|pagename|runs| 09:26:48 INFO - PROCESS | 1930 | |0;dojo.html;3339.4823802310643;3363.394588973985;3382.746294027947;3399.592048954126;3444.328355970587;5602;5724.427557244274;5782.653040817554;5866.911996320054;5890.941090589098;5810.651360918338;5813.389594092623;5899.174115623816;5915.467607915287;5955.047192449209;4382.605565499104;5182.119039763249;5202.089634313998;5279.260903473517;5385.78456861727;5339.22581225722;5397.379301380332;5412.4587541245955;5426.511613954744;5447.101228297326;5186.740662966874;5261.105612045923;5278.287431196778;5325.307709997675;5334.493223143814;5248.448912864171;5292.470752924715;5297.867553311198;5324.92012619811;5380.838574842722;5195.298634684307;5240.213967904807;5255.1329030709885;5287.973560132214;5312.442193569658;5850.707464626752;5851.473367396955;5873.148393482921;5879.67661778602;5881.85295367615;6132.967444181115;6160.322364539897;6234.345393733638;6252.093446450284;6304.7793327233785;6224.9688751556405;6246.812595622138;6263.655498947556;6284.025975973731;6307.621542707452;6010.068439391901;6017.939820601781;6018.909716354258;6030.608010479349;6042.969785151046;6173.506119510428;6237.4698150656795;6290.55966082379;6292.559520833589;6396.584222025554;6202.472789812917;6251.812445626639;6255.655938923403;6265.248170219512;6273.62358258506;6303.338149494329;6309.305976342599;6317.557770955989;6331.430171284606;6332.208473940757;6114.753609057711;6196.256449226122;6225.470834978987;6229.0967809667345;6291.213598300213;6224.968875155686;6250.687465626791;6276.560640755103;6293.496520278367;6301.6219026858535;5042.722650254272;5048.7728052237735;5049.747512624354;5055.8988820223385;5086.262491938648;4467.955320446819;4486.68593198473;4496.280595104768;4550.544945505423;4610.631149508032;5875.647461152259;5898.852528686817;5902.173695682608;5942.346341902387;5965.3139888912465;6233.844153896098;6258.874822503615;6270.905936410957;6297.779577714758;6308.936910630927;5647.407022262603;5725.59920805548;5766.452187042224;5888.499477544371;5897.734601942923;5470.781168753285;5497.45025497455;5529.944700552943;5577.972110139423;5596.552275817925;6398.2322121344805;6402.519811014286;6406.711697973509;6409.455196308366;6430.324815894263;5086.64393492452;6175.011998080286;6255.249370075438;6262.749490020348;6278.434940855345;6324.335944725737;6326.4622507087415;6341.334159913327;6416.743330266737;6419.390157934989;6244.812655620339;6277.372262773686;6278.0269058296035;6287.874242515216;6288.182536270256;5079.796808127633;5463.781448742165;6220.253569571681;6278;6301.243850737941;6131.080337949344;6136.243373036893;6158.261008678987;6186.783462578788;6222.408871157233;6016;6198.969005154945;6207.379262073757;6243.094751260951;6252.406021427957;5880.882382352414;6093.817185484442;6101.206843110367;6122;6123.969380153071;6133.570650054453;6134.509239260759;6135.171422439487;6175.938240617536;6213.316535181217;6024.638521688713;6051.152838602688;6069.393060693896;6098.790072337538;6134.2332208473945 09:26:48 INFO - PROCESS | 1930 | |1;ext.html;457.5401721270123;462.6437643014878;478.07492502008614;480.1836877308575;507.1960941907077;24962.625560616583;31810.20474488139;31855.04434866952;32548.349033019356;35378.11554711127;42155.52455664052;43013.7849310753;43053.138937221236;43105.35341969873;43134;9761.219102471803;9945.254105942084;9952.950235248814;9955.701328960107;9975.551100200471;8566.229039386453;8811.73564793054;9077;9844.163246124052;9980.95009524955;12018.819717704242;12316.753664926697;12323.322217278046;12437;12491.562795302167;12236.204646697981;12243.571474998376;12289.938550307192;12316.445759940823;12339.753204935942;12039.879601204051;12116.878831211663;12120.636380908587;12123.090768192333;12128.393580321037;40194.39708404376;40478.79760601208;40654.9836254096;40657;40745.592544074476;3433.776804507699;3439.208981934169;3450.2064525159353;3626.546681664792;3654.1778099927305;30789.230269243224;31178.532322015188;31188;31629.36741265185;31719.841400792848;2731.576605626131;3120.188750924753;3239.174010627275;3245.7241134503597;3304.1244070321386;369676;372393;372519;375816;381541;61092.08361874575;61412;61546.76906461935;61548.692256539325;61630.69184654048;30046.699533004827;63975.04037439442;64132.35867641357;64221.67889160525;64381.03428448577;15702.136382499073;16414.753778693328;16490.422835200712;16502.83497165037;16570.254338554794;12614.180078294881;12706.364681765876;12726.618201453972;12727.618171454871;12740.936295318465;16976.830231697524;17363.739543906853;18958.905205473886;19135.330263440712;19338;7800.726974555977;7961.780256463968;7971.242731940572;8002.9599852000365;8121.390895682847;830.9958450207711;879.6613303878053;962.8603852441497;1031.85038169465;1063.7712891728315;28785.56821647677;29924.700752992627;30051.248718781768;30698.232544186572;30763.077107686804;1105.2926127278547;1132.705496570881;1144.399190425017;1168.6260396672983;1204.2473454091194;14964.251787410585;15275.61810954735;15540.456084037005;15839.762403563956;16044.278007489691;705088;709576;709808;711471;712370;56813.03463136394;57347.71326143426;57564.71217643885;57625.423745762004;57685;1223.3638507975625;1307.3593938970027;1365.8019587159806;1380.2615600653603;1384.9099808512415;26471.73528264693;26484;29465.85267073651;30187.396252074395;30239.395212096075;50338;54085.18872216841;54802.72598636981;54806.79335463125;58558.414415857;641650;649037;649751;649966;650022;33821.83089084539;34360.82819585886;42459.787701061294;42658.78670606627;42941;45811;46317;46600.06799863916;46886.82782930454;47030;30900.536491953088;37765.97872170275;38420.6157938417;38752.6124738749;39002.804985974886;36563;37476.43785343276;37731.24537509289;38151;39300.606993930836;36953.07617309589;37430;37889.62110378861;37915.62084379231;37941.62058379381;25889.482210355312;32160.51759223567;32838;33164;33610.99167024993;24446.14438494644;24625.507489849744;24693.259202223962;25491.107811226517;25634.309219207975 09:26:48 INFO - PROCESS | 1930 | |2;jquery.html;350.2626970227672;373.719710217337;377.0855674988153;387.19849910684883;404.2823987422325;15109.471168509104;15691.372345106196;19984.400467985946;20645.896770516163;20732.585348293025;19485.41543753686;19506.609867802636;20139.899300503515;20156.39530814074;20209.898950505227;5140.614453915952;5317.016351974897;5848.210491583635;6008.1888944992525;6110.2973158086725;4154.916901661965;4378.824847006132;4380.605745482906;5976.073708575156;6067.211262535886;4343.826246950134;4367.628751556105;4368.279233926399;4369.890752731176;4512.16524942885;6541.28045914949;6555.5083368747055;6601.108850305243;6601.93398066018;6605.108310378134;5446.591505637093;5611.438856114397;5669.886602267931;5670.319561652587;5688.857778555528;21861.890690546603;22007.339779806633;23968.880155599112;25395.984160633554;25573.721313934228;1796.2006906926422;2077.772596025049;2266.3767463947547;2536.4293034067373;2643.70919198888;14542.927285363612;14877.7768333474;15038.02252853561;15192.772108418383;15193.83253308817;1575.771513130601;1796.8832025918273;1838.4208974173093;1854.7125195594704;1904.9047547622563;101913.98086019194;104614.47692761487;104617;105202.47398763034;105879.99999999923;8813.471191728517;10241.795164096826;10442.791144177227;10582.57669693219;10748.570057197625;13005;13008.349582520836;13029.47882084706;13040.478380864848;13044.869551304555;8155.79610509742;8301.8339633207;8353.248207661218;8374.539400332918;8525.616347264264;8436.746897593082;8467.788305292293;8621.784455388666;8635.827283454295;8656;6209.130721698965;6322.241331040304;6333.68331583419;6345.33373995733;6351.364863513611;1806.1962426720247;1836.4215272189217;1836.8806027608164;1838.3565751986698;1840.4570651657793;819.610684924656;898.4878619187132;898.5282726568473;947.6637940503856;1020;20142.194312227348;20698.58602827935;21312.893435533035;21444.78555214428;21452;944.1927152284835;944.3955868244329;950.092661508258;951.2580187453797;971.3928794503436;8717.166920750782;10143.492825358702;11201.935816097457;11219.495122719498;11290.0967922566;564270;581280;604768;607397;611941;106055.46972265089;114711;115400.42299788447;117995;119222.40388798;962.3023308101571;993.9950300248453;1027.4708525109575;1027.4759872465097;1029.9948500257601;17782.288708452033;17908.01505917187;18127.36554220596;18201.362952296608;18521.35175268913;52269.16901843965;53087.46912530825;53481.66295842635;53701.19448208202;53962;73659.63170184329;75070;75962.24037759552;76067.43859405338;76327;7655.043119610049;7744.92255077442;7760.961195193988;7845.29392354668;8107.22981316774;10550.894491055298;12403.193792403465;12879.291638959947;13061.21632702041;13085.738285234052;10968.616098436516;11055.668329950115;11346.546138154712;11425.542978280775;11437.656870293904;9572.76068098303;11031;11121.610743623934;11311.773764524827;11328.433578321215;9711.883133439746;10662.093722033404;11100.722481937693;11350.772984540428;11474.885251147716;6615.470762339098;7037.944308353788;7582.658780354787;7731.845363092819;7830.295273425191;10889.455527223765;11047.281926674741;11268.830967535658;11324.32054076758;11350.205485615928 09:26:48 INFO - PROCESS | 1930 | |3;mootools.html;1368.7194125204335;1548.5122186511248;1555.0747305353314;1787.5173703100163;1936.8256856882879;7903.130655627883;8230.629621667025;8237.217464340893;8243.340532757387;8666.91333086669;7557.659905304261;7610.771676849689;8758.868616970734;8807.559622018889;8815.294776417888;2371.5968285391436;2425.5997760369605;2426.4783071639595;2430.0644251962945;2431.112643884987;2367.4318163640687;2410.385351735305;2419.6854408926815;2420.092465325503;2425.0784701813286;2382.1781485387514;2406.4465173010217;2410.80713542917;2417.8186636002283;2425.63615457681;2005.729226554411;2349.201271567675;2381.9285421437385;2384.332386931662;2386.7135943686694;2324.6629238760456;2369.8104151667826;2372.608519594265;2372.810175185981;2378.1676413255395;7545.471816972814;8869.157430044135;8960.775980600538;8989.37074404792;8995.460272383678;5250.291210686547;5250.8687282817855;5423.945760542383;5427.375851777057;5434.64674796137;3649.872254471081;3703.4259689748133;3704.759190652626;3705.018170184876;3706.3884459064493;1056.498163372392;1084.815581351172;1084.8806631270554;1106.0322218059198;1114.331401159306;11449.427528623535;11531.884681153166;11537.5961841336;11542.249753765978;11586.478608462723;4481.641468682563;4492.641363284237;4615.146197953322;4688.671792974459;4693.694909830919;9028.187463128219;9046.276297896153;9047.547622618975;9061.637534498546;9063.728088157366;5257.237700533401;5261.736913154327;5263.157894736823;5263.4473380295285;5266.868328291824;4452.977735111369;4490.034642551867;4803.231482962779;4930.802767889316;4992.825251116194;3264.8530816112857;3710;3765.2281282337053;3857.3828187490426;3872.361060425055;994.6568433890296;1163.7207070303236;1187.7884557751217;1189.6371606660052;1191.862935762381;848.3849209323191;863.3999370437612;903.6882275614902;911.2755359489222;933.9626414943326;5648.717564121777;5673.59584249447;5878.588498805128;5938.138969849348;6002;949.9192568631605;961.3414810854587;970.1414248390084;971.6016433262329;1018.2566726289701;3863.4976857708234;3936.3505021671103;3988.3618621020496;4028.879133625996;4039.3537034074407;10316.43259620728;10746.24776265654;10749.032587067202;10797.946010270056;11129.443527823738;10303;10694.786104277871;10697.572097116185;10775.515101820436;10838.512266947848;6622.569532980341;7304.488685791944;8254.215849494289;8260.256576908107;8313.210245026712;5772.624779389326;5875.970620146872;5892;6071.969640151771;6245.750169993149;9696.95151524238;9783.36408133469;10021.599136034769;10022.298439109483;10110.292279540361;9906.603735850485;10124.34191777532;10217.897821021694;10302.793944121226;10316.226283028653;6332.841678958063;6869.141357330333;6989.056477375692;7212.639368031682;7706.152323244327;7153.4992550520965;8545.658173672984;8613.612387442705;8621.69824056155;8627.827443451222;7311.41508679315;7504.699812007459;7566.735164269224;7637.198094200029;7720.227977202235;7279.672414741243;7289.744858929912;7320.524165929198;7516.736914207976;7525.6989720410575;6847.760328388483;6998.405135563534;6998.720051197895;7011.018457416068;7013.894791578028;3576.477591210079;4905.5094490550655;4986.47641997585;5044.268581055653;5082.2122571001555;4074.633283004544;4985.4266759323145;5074.441811400817;5099.413567439792;5189.584833213409 09:26:48 INFO - PROCESS | 1930 | |4;prototype.html;302.9333546619743;306.33830925201556;312.72480217408685;316.9286910445149;317.9634342050665;4530.773461326937;4655.928111990166;5186.74066296685;5493.917591236134;5580.441955804417;4471.8658440246845;4525.275955847065;5260.158374660055;5421.457854214576;5450.727463626813;3421.9220945402244;3910.081130934228;3941.9014524636827;4039.151778126597;4072.2059198456313;3835.884923452301;4078.938815917764;4082.9795851020704;4105.856295029675;4122.2579935611575;3380.442227032549;4072.3077076897;4072.574598890394;4082.9588454943946;4102.558977408437;3121.5161649944293;3632.3098611263777;3865.16898866745;4045.271851066807;4120.198265179677;2896.6089577906923;3021.425929073491;3556.3065202285566;3559.2703495783303;3583.086312990197;4600.7929643166135;5213.191955246943;5225.137852254412;5228.320318358589;5294.973525132388;3903.394973779069;4273.316269396882;4466.977665111654;4696.882577935545;4731.716097034189;4150.709450338478;5109.923351149736;5632.211490391349;5638.74625641843;5646.717664116819;3383.6447173046968;3925.3130702127014;3985.2228815381013;3988;3993.9600603993877;5558.555315574785;5909.852253693692;5945.64326140433;5994.910076348815;5999.32019034671;3027.1221345809467;3802.125511132455;3861.691064714816;3864.8840534784003;3868.497095377639;3618.3306088373733;3937.370020796659;3954.387070004154;3957.920841583152;3958.722889397772;3843.577206507282;4586.931196032062;4615.376924115201;4624.5144259852905;4626.167289887785;5427.294451721251;5551.944480555224;5611.663300202001;5630.4369563043365;5658.97170514153;1113.4766659669942;1283.530357740382;1312.5078095714107;1312.6849556106656;1319.8416190057255;658.8978708300222;703.6341102626708;813.9511629302261;828;832.675256649907;4505.36924830524;4508.278675411918;4575.611073058761;4581.587657110876;4902.386767151592;5294.967481341178;5465.562754979592;5503;5670.15719891037;5680.6591604503865;4117.505899292104;4885.828995985124;5022.221555658879;5203.022851316476;5368.543673787772;2981.7316441519934;3059.7858149929293;3067.3251884585356;3080.9537856931715;3089.5520149578633;5275.808136077694;5585.158181227963;6035.895590998076;6045.637261764308;6124.724387402578;5305.549028332635;5313.0247061645605;5664.6034777566;5780.364159942403;6033.818985430444;5179.792808287626;5286.735663216824;5520.641158324776;5728.713564321684;6010.767792602506;5352.304200453916;5557.861053473695;5884.029135192645;5886.381929897299;5900.5217808519665;5282.283257941108;5700.714964251688;5733.369329373849;5773.546242846689;5792.82621521355;5187.210412407713;5646.70392512354;5988.6706231157705;5992.64044157352;6026.306974697965;3149.8582563785144;3194.4090343286566;3408;3418.4872269159823;3635.4001589738455;5299.887023725061;5815.76736930518;5816;5826.533877289722;5840.357560668408;5266.131088370376;5692.203091567101;5724.3703192647945;5779.190913272061;5780;5295.841124766263;5625;5677.630953987978;5748.223989761329;5785.82642520725;5592.608517403743;5596.328440586993;5597.188407680782;5602.187682786055;5613.635113717595;5496.862578435571;5579.860503487283;5688.374278829243;5700.657960522216;5735.684537350486;4778.880527986689;5140.897182056413;5376.946230537801;5578.888422231614;5585.497305242548 09:26:48 INFO - PROCESS | 1930 | |5;yui.html;719.588693028426;720.6144712578771;764.9863930292364;789.8657228271194;816.6325153680843;15164.090154590718;15786.684266314667;15790.289436975336;15813.683726325467;15840.603984900368;14264.215468149274;14288.357023933922;14309;14311.78532322016;14833.777493337608;678.8404724889647;697.3967518096861;819.0867183090841;839.4669384940561;841.6633346661323;847.9703210387606;859.256742917375;859.4843094143495;860.6643409070467;861.9008813986378;895.2882458445519;897.5108565831638;897.9910200897973;901.2294488212582;905.2622112977901;709.6770969208998;871.9651213951435;894.8255090257435;896.7668406214366;900.779309069279;673.5150691502159;675.5001299038726;675.8918573028292;677.4986509982577;677.8203775999315;13825.79261311071;14866.256687165598;14886.553403397915;14918.328675209534;14922.328495217742;13785.172889626654;13967.720645587031;14059.297035148202;14135.434582616683;14259.215743134228;10296.588136474533;11351.26216795914;11772.529098836036;11884.821727674092;12217.083718721131;4465.531119232499;4511.661625378109;4575.496695363508;4622.8150873965005;4688.062387522509;92898.21305360917;114733;114746;114853;116149.41925290404;9891.554880030271;11039.116870650489;11063.944680276547;11092;11418;22284.220052298093;22349.77650223477;22385.328440146823;22386.44033899133;22713.977870995845;7812.531248125017;7823.178566250575;7848.372130229568;7862.488938218998;7936.484128531627;3598.676119149289;4233.428487154257;4283.164782867311;4331.501877284152;4429.911401771947;3494.965050349515;3610.3681855675677;3610.458431235336;3610.765300255475;3611.1513794258326;572.5848759649305;668.6823758714571;675.9831004224835;693.2928413018793;749.456643933155;579.2932622200908;636.38270877249;639.1212083385345;678.8540463800208;690.1132045321838;7355.8161045974275;9302.95348523253;9361.344705870524;9537.666181683608;9616.807663846685;534.2973989204185;626.5332327416013;654.1561385812249;679.1578442730978;699.3530983839928;4350.282203436461;4467.575580319929;4497.8200871964755;4501.167284052461;4522;122497;155320;156657;157458;159678;112271.43864280626;112936;114048;117013;134495.32752336373;101343.98656013346;105600.94399056064;110866;112263;113153.43423282831;9296.535173241446;9881.160101391175;9895.851562226717;9900.603975840886;9936.950315248378;47550;59405.40594594172;61426.69286653717;63413.71402475899;64752.676236618514;84558;87330.69003965073;90332.54833725789;93080.53459732658;98984;12886.066492342174;12973.286469243905;12997;13314;13618.93190534041;14616.415343386145;14676.486322979072;14729.410823566937;14776.852231477547;15281;11648.94175529117;13412.865871341162;13542.729145417234;13629.727405452033;14165.858341416866;13458;13574.994638430668;13618.319084045559;13645.79531307051;13787.965902557147;13170.789771262125;13320.315224213544;13483.460661573426;13548.661283467993;13872.514461993782;11052.11583073336;11149.048916439753;11187.32876027441;11273.71815704614;11390.829137562778;8419.200175983271;10099.899001009895;10737.946310268711;10951.561937522729;11451.427428628702 09:26:48 INFO - PROCESS | 1930 | __end_tp_report 09:26:48 INFO - PROCESS | 1930 | __start_cc_report 09:26:48 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1426 09:26:48 INFO - PROCESS | 1930 | __end_cc_report 09:26:48 INFO - PROCESS | 1930 | __startTimestamp1472574408638__endTimestamp 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | ------- Summary: start ------- 09:26:48 INFO - PROCESS | 1930 | Number of tests: 6 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5810.42 Median:6069.39 stddev:642.26 (10.6%) stddev-sans-first:614.28 09:26:48 INFO - PROCESS | 1930 | Values: 3339.5 3363.4 3382.7 3399.6 3444.3 5602.0 5724.4 5782.7 5866.9 5890.9 5810.7 5813.4 5899.2 5915.5 5955.0 4382.6 5182.1 5202.1 5279.3 5385.8 5339.2 5397.4 5412.5 5426.5 5447.1 5186.7 5261.1 5278.3 5325.3 5334.5 5248.4 5292.5 5297.9 5324.9 5380.8 5195.3 5240.2 5255.1 5288.0 5312.4 5850.7 5851.5 5873.1 5879.7 5881.9 6133.0 6160.3 6234.3 6252.1 6304.8 6225.0 6246.8 6263.7 6284.0 6307.6 6010.1 6017.9 6018.9 6030.6 6043.0 6173.5 6237.5 6290.6 6292.6 6396.6 6202.5 6251.8 6255.7 6265.2 6273.6 6303.3 6309.3 6317.6 6331.4 6332.2 6114.8 6196.3 6225.5 6229.1 6291.2 6225.0 6250.7 6276.6 6293.5 6301.6 5042.7 5048.8 5049.7 5055.9 5086.3 4468.0 4486.7 4496.3 4550.5 4610.6 5875.6 5898.9 5902.2 5942.3 5965.3 6233.8 6258.9 6270.9 6297.8 6308.9 5647.4 5725.6 5766.5 5888.5 5897.7 5470.8 5497.5 5529.9 5578.0 5596.6 6398.2 6402.5 6406.7 6409.5 6430.3 5086.6 6175.0 6255.2 6262.7 6278.4 6324.3 6326.5 6341.3 6416.7 6419.4 6244.8 6277.4 6278.0 6287.9 6288.2 5079.8 5463.8 6220.3 6278.0 6301.2 6131.1 6136.2 6158.3 6186.8 6222.4 6016.0 6199.0 6207.4 6243.1 6252.4 5880.9 6093.8 6101.2 6122.0 6124.0 6133.6 6134.5 6135.2 6175.9 6213.3 6024.6 6051.2 6069.4 6098.8 6134.2 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:71099.87 Median:20108.59 stddev:159771.09 (794.5%) stddev-sans-first:160131.25 09:26:48 INFO - PROCESS | 1930 | Values: 457.5 462.6 478.1 480.2 507.2 24962.6 31810.2 31855.0 32548.3 35378.1 42155.5 43013.8 43053.1 43105.4 43134.0 9761.2 9945.3 9953.0 9955.7 9975.6 8566.2 8811.7 9077.0 9844.2 9981.0 12018.8 12316.8 12323.3 12437.0 12491.6 12236.2 12243.6 12289.9 12316.4 12339.8 12039.9 12116.9 12120.6 12123.1 12128.4 40194.4 40478.8 40655.0 40657.0 40745.6 3433.8 3439.2 3450.2 3626.5 3654.2 30789.2 31178.5 31188.0 31629.4 31719.8 2731.6 3120.2 3239.2 3245.7 3304.1 369676.0 372393.0 372519.0 375816.0 381541.0 61092.1 61412.0 61546.8 61548.7 61630.7 30046.7 63975.0 64132.4 64221.7 64381.0 15702.1 16414.8 16490.4 16502.8 16570.3 12614.2 12706.4 12726.6 12727.6 12740.9 16976.8 17363.7 18958.9 19135.3 19338.0 7800.7 7961.8 7971.2 8003.0 8121.4 831.0 879.7 962.9 1031.9 1063.8 28785.6 29924.7 30051.2 30698.2 30763.1 1105.3 1132.7 1144.4 1168.6 1204.2 14964.3 15275.6 15540.5 15839.8 16044.3 705088.0 709576.0 709808.0 711471.0 712370.0 56813.0 57347.7 57564.7 57625.4 57685.0 1223.4 1307.4 1365.8 1380.3 1384.9 26471.7 26484.0 29465.9 30187.4 30239.4 50338.0 54085.2 54802.7 54806.8 58558.4 641650.0 649037.0 649751.0 649966.0 650022.0 33821.8 34360.8 42459.8 42658.8 42941.0 45811.0 46317.0 46600.1 46886.8 47030.0 30900.5 37766.0 38420.6 38752.6 39002.8 36563.0 37476.4 37731.2 38151.0 39300.6 36953.1 37430.0 37889.6 37915.6 37941.6 25889.5 32160.5 32838.0 33164.0 33611.0 24446.1 24625.5 24693.3 25491.1 25634.3 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:33972.88 Median:13252.65 stddev:98585.88 (743.9%) stddev-sans-first:98830.11 09:26:48 INFO - PROCESS | 1930 | Values: 350.3 373.7 377.1 387.2 404.3 15109.5 15691.4 19984.4 20645.9 20732.6 19485.4 19506.6 20139.9 20156.4 20209.9 5140.6 5317.0 5848.2 6008.2 6110.3 4154.9 4378.8 4380.6 5976.1 6067.2 4343.8 4367.6 4368.3 4369.9 4512.2 6541.3 6555.5 6601.1 6601.9 6605.1 5446.6 5611.4 5669.9 5670.3 5688.9 21861.9 22007.3 23968.9 25396.0 25573.7 1796.2 2077.8 2266.4 2536.4 2643.7 14542.9 14877.8 15038.0 15192.8 15193.8 1575.8 1796.9 1838.4 1854.7 1904.9 101914.0 104614.5 104617.0 105202.5 105880.0 8813.5 10241.8 10442.8 10582.6 10748.6 13005.0 13008.3 13029.5 13040.5 13044.9 8155.8 8301.8 8353.2 8374.5 8525.6 8436.7 8467.8 8621.8 8635.8 8656.0 6209.1 6322.2 6333.7 6345.3 6351.4 1806.2 1836.4 1836.9 1838.4 1840.5 819.6 898.5 898.5 947.7 1020.0 20142.2 20698.6 21312.9 21444.8 21452.0 944.2 944.4 950.1 951.3 971.4 8717.2 10143.5 11201.9 11219.5 11290.1 564270.0 581280.0 604768.0 607397.0 611941.0 106055.5 114711.0 115400.4 117995.0 119222.4 962.3 994.0 1027.5 1027.5 1030.0 17782.3 17908.0 18127.4 18201.4 18521.4 52269.2 53087.5 53481.7 53701.2 53962.0 73659.6 75070.0 75962.2 76067.4 76327.0 7655.0 7744.9 7761.0 7845.3 8107.2 10550.9 12403.2 12879.3 13061.2 13085.7 10968.6 11055.7 11346.5 11425.5 11437.7 9572.8 11031.0 11121.6 11311.8 11328.4 9711.9 10662.1 11100.7 11350.8 11474.9 6615.5 7037.9 7582.7 7731.8 7830.3 10889.5 11047.3 11268.8 11324.3 11350.2 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5629.78 Median:4989.65 stddev:3139.15 (62.9%) stddev-sans-first:3131.62 09:26:48 INFO - PROCESS | 1930 | Values: 1368.7 1548.5 1555.1 1787.5 1936.8 7903.1 8230.6 8237.2 8243.3 8666.9 7557.7 7610.8 8758.9 8807.6 8815.3 2371.6 2425.6 2426.5 2430.1 2431.1 2367.4 2410.4 2419.7 2420.1 2425.1 2382.2 2406.4 2410.8 2417.8 2425.6 2005.7 2349.2 2381.9 2384.3 2386.7 2324.7 2369.8 2372.6 2372.8 2378.2 7545.5 8869.2 8960.8 8989.4 8995.5 5250.3 5250.9 5423.9 5427.4 5434.6 3649.9 3703.4 3704.8 3705.0 3706.4 1056.5 1084.8 1084.9 1106.0 1114.3 11449.4 11531.9 11537.6 11542.2 11586.5 4481.6 4492.6 4615.1 4688.7 4693.7 9028.2 9046.3 9047.5 9061.6 9063.7 5257.2 5261.7 5263.2 5263.4 5266.9 4453.0 4490.0 4803.2 4930.8 4992.8 3264.9 3710.0 3765.2 3857.4 3872.4 994.7 1163.7 1187.8 1189.6 1191.9 848.4 863.4 903.7 911.3 934.0 5648.7 5673.6 5878.6 5938.1 6002.0 949.9 961.3 970.1 971.6 1018.3 3863.5 3936.4 3988.4 4028.9 4039.4 10316.4 10746.2 10749.0 10797.9 11129.4 10303.0 10694.8 10697.6 10775.5 10838.5 6622.6 7304.5 8254.2 8260.3 8313.2 5772.6 5876.0 5892.0 6072.0 6245.8 9697.0 9783.4 10021.6 10022.3 10110.3 9906.6 10124.3 10217.9 10302.8 10316.2 6332.8 6869.1 6989.1 7212.6 7706.2 7153.5 8545.7 8613.6 8621.7 8627.8 7311.4 7504.7 7566.7 7637.2 7720.2 7279.7 7289.7 7320.5 7516.7 7525.7 6847.8 6998.4 6998.7 7011.0 7013.9 3576.5 4905.5 4986.5 5044.3 5082.2 4074.6 4985.4 5074.4 5099.4 5189.6 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4517.59 Median:5208.11 stddev:1434.32 (27.5%) stddev-sans-first:1403.03 09:26:48 INFO - PROCESS | 1930 | Values: 302.9 306.3 312.7 316.9 318.0 4530.8 4655.9 5186.7 5493.9 5580.4 4471.9 4525.3 5260.2 5421.5 5450.7 3421.9 3910.1 3941.9 4039.2 4072.2 3835.9 4078.9 4083.0 4105.9 4122.3 3380.4 4072.3 4072.6 4083.0 4102.6 3121.5 3632.3 3865.2 4045.3 4120.2 2896.6 3021.4 3556.3 3559.3 3583.1 4600.8 5213.2 5225.1 5228.3 5295.0 3903.4 4273.3 4467.0 4696.9 4731.7 4150.7 5109.9 5632.2 5638.7 5646.7 3383.6 3925.3 3985.2 3988.0 3994.0 5558.6 5909.9 5945.6 5994.9 5999.3 3027.1 3802.1 3861.7 3864.9 3868.5 3618.3 3937.4 3954.4 3957.9 3958.7 3843.6 4586.9 4615.4 4624.5 4626.2 5427.3 5551.9 5611.7 5630.4 5659.0 1113.5 1283.5 1312.5 1312.7 1319.8 658.9 703.6 814.0 828.0 832.7 4505.4 4508.3 4575.6 4581.6 4902.4 5295.0 5465.6 5503.0 5670.2 5680.7 4117.5 4885.8 5022.2 5203.0 5368.5 2981.7 3059.8 3067.3 3081.0 3089.6 5275.8 5585.2 6035.9 6045.6 6124.7 5305.5 5313.0 5664.6 5780.4 6033.8 5179.8 5286.7 5520.6 5728.7 6010.8 5352.3 5557.9 5884.0 5886.4 5900.5 5282.3 5700.7 5733.4 5773.5 5792.8 5187.2 5646.7 5988.7 5992.6 6026.3 3149.9 3194.4 3408.0 3418.5 3635.4 5299.9 5815.8 5816.0 5826.5 5840.4 5266.1 5692.2 5724.4 5779.2 5780.0 5295.8 5625.0 5677.6 5748.2 5785.8 5592.6 5596.3 5597.2 5602.2 5613.6 5496.9 5579.9 5688.4 5700.7 5735.7 4778.9 5140.9 5376.9 5578.9 5585.5 09:26:48 INFO - PROCESS | 1930 | 09:26:48 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:24493.03 Median:3610.61 stddev:38953.02 (1078.8%) stddev-sans-first:39021.39 09:26:48 INFO - PROCESS | 1930 | Values: 719.6 720.6 765.0 789.9 816.6 15164.1 15786.7 15790.3 15813.7 15840.6 14264.2 14288.4 14309.0 14311.8 14833.8 678.8 697.4 819.1 839.5 841.7 848.0 859.3 859.5 860.7 861.9 895.3 897.5 898.0 901.2 905.3 709.7 872.0 894.8 896.8 900.8 673.5 675.5 675.9 677.5 677.8 13825.8 14866.3 14886.6 14918.3 14922.3 13785.2 13967.7 14059.3 14135.4 14259.2 10296.6 11351.3 11772.5 11884.8 12217.1 4465.5 4511.7 4575.5 4622.8 4688.1 92898.2 114733.0 114746.0 114853.0 116149.4 9891.6 11039.1 11063.9 11092.0 11418.0 22284.2 22349.8 22385.3 22386.4 22714.0 7812.5 7823.2 7848.4 7862.5 7936.5 3598.7 4233.4 4283.2 4331.5 4429.9 3495.0 3610.4 3610.5 3610.8 3611.2 572.6 668.7 676.0 693.3 749.5 579.3 636.4 639.1 678.9 690.1 7355.8 9303.0 9361.3 9537.7 9616.8 534.3 626.5 654.2 679.2 699.4 4350.3 4467.6 4497.8 4501.2 4522.0 122497.0 155320.0 156657.0 157458.0 159678.0 112271.4 112936.0 114048.0 117013.0 134495.3 101344.0 105600.9 110866.0 112263.0 113153.4 9296.5 9881.2 9895.9 9900.6 9937.0 47550.0 59405.4 61426.7 63413.7 64752.7 84558.0 87330.7 90332.5 93080.5 98984.0 12886.1 12973.3 12997.0 13314.0 13618.9 14616.4 14676.5 14729.4 14776.9 15281.0 11648.9 13412.9 13542.7 13629.7 14165.9 13458.0 13575.0 13618.3 13645.8 13788.0 13170.8 13320.3 13483.5 13548.7 13872.5 11052.1 11149.0 11187.3 11273.7 11390.8 8419.2 10099.9 10737.9 10951.6 11451.4 09:26:48 INFO - PROCESS | 1930 | -------- Summary: end -------- 09:26:48 INFO - PROCESS | 1930 | 09:26:49 INFO - TEST-INFO | 1930: exit 0 09:26:49 INFO - TEST-OK | dromaeo_css | took 1117948ms 09:26:49 INFO - TEST-START | kraken 09:26:49 INFO - operating with platform_type : mac_ 09:26:49 INFO - Initialising browser for kraken test... 09:26:49 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp4Ldnin/profile http://localhost:52588/getInfo.html) 09:26:51 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 09:26:51 INFO - PROCESS | 1934 | colorDepth:24 09:26:51 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 09:26:51 INFO - PROCESS | 1934 | __metrics 09:26:51 INFO - PROCESS | 1934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:26:51 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:52 INFO - TEST-INFO | 1934: exit 0 09:26:52 INFO - Browser initialized. 09:26:52 INFO - Running cycle 1/1 for kraken test... 09:26:52 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp4Ldnin/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:04 INFO - PROCESS | 1935 | RSS: Main: 234577920 09:27:04 INFO - PROCESS | 1935 | 09:27:07 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52588/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:08 INFO - PROCESS | 1935 | RSS: Main: 292917248 09:27:08 INFO - PROCESS | 1935 | 09:27:10 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52588/tests/kraken/driver.html?testName=audio-dft) 09:27:11 INFO - PROCESS | 1935 | RSS: Main: 288481280 09:27:11 INFO - PROCESS | 1935 | 09:27:14 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52588/tests/kraken/driver.html?testName=audio-fft) 09:27:15 INFO - PROCESS | 1935 | RSS: Main: 379539456 09:27:15 INFO - PROCESS | 1935 | 09:27:17 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52588/tests/kraken/driver.html?testName=audio-oscillator) 09:27:18 INFO - PROCESS | 1935 | RSS: Main: 281321472 09:27:18 INFO - PROCESS | 1935 | 09:27:20 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52588/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:20 INFO - PROCESS | 1935 | RSS: Main: 284643328 09:27:20 INFO - PROCESS | 1935 | 09:27:23 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52588/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:24 INFO - PROCESS | 1935 | RSS: Main: 322748416 09:27:24 INFO - PROCESS | 1935 | 09:27:27 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52588/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:27 INFO - PROCESS | 1935 | RSS: Main: 325009408 09:27:27 INFO - PROCESS | 1935 | 09:27:30 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52588/tests/kraken/driver.html?testName=json-parse-financial) 09:27:31 INFO - PROCESS | 1935 | RSS: Main: 322654208 09:27:31 INFO - PROCESS | 1935 | 09:27:32 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52588/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:33 INFO - PROCESS | 1935 | RSS: Main: 299442176 09:27:33 INFO - PROCESS | 1935 | 09:27:35 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:35 INFO - PROCESS | 1935 | RSS: Main: 305934336 09:27:35 INFO - PROCESS | 1935 | 09:27:38 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:38 INFO - PROCESS | 1935 | RSS: Main: 305332224 09:27:38 INFO - PROCESS | 1935 | 09:27:41 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:42 INFO - PROCESS | 1935 | RSS: Main: 323665920 09:27:42 INFO - PROCESS | 1935 | 09:27:45 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:46 INFO - PROCESS | 1935 | RSS: Main: 319778816 09:27:46 INFO - PROCESS | 1935 | 09:27:47 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52588/tests/kraken/driver.html?testName=ai-astar) 09:27:48 INFO - PROCESS | 1935 | RSS: Main: 362799104 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | __start_tp_report 09:27:48 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 09:27:48 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 09:27:48 INFO - PROCESS | 1935 | |i|pagename|runs| 09:27:48 INFO - PROCESS | 1935 | |0;ai-astar;113;113;132;113;113;120;163;162;111;111 09:27:48 INFO - PROCESS | 1935 | |1;audio-beat-detection;148;130;129;162;109;131;127;108;162;110 09:27:48 INFO - PROCESS | 1935 | |2;audio-dft;182;184;183;181;183;182;183;185;181;179 09:27:48 INFO - PROCESS | 1935 | |3;audio-fft;77;78;76;158;58;58;58;71;142;57 09:27:48 INFO - PROCESS | 1935 | |4;audio-oscillator;81;82;136;86;102;114;131;69;69;120 09:27:48 INFO - PROCESS | 1935 | |5;imaging-gaussian-blur;87;84;87;84;85;84;92;84;86;84 09:27:48 INFO - PROCESS | 1935 | |6;imaging-darkroom;92;93;92;92;92;93;92;93;92;92 09:27:48 INFO - PROCESS | 1935 | |7;imaging-desaturate;84;84;83;86;100;141;142;83;84;86 09:27:48 INFO - PROCESS | 1935 | |8;json-parse-financial;55;53;56;54;55;56;53;54;54;55 09:27:48 INFO - PROCESS | 1935 | |9;json-stringify-tinderbox;67;62;70;81;73;74;73;71;71;73 09:27:48 INFO - PROCESS | 1935 | |10;stanford-crypto-aes;84;85;85;86;84;88;86;87;89;88 09:27:48 INFO - PROCESS | 1935 | |11;stanford-crypto-ccm;141;124;146;151;151;150;154;152;181;167 09:27:48 INFO - PROCESS | 1935 | |12;stanford-crypto-pbkdf2;166;172;232;202;169;174;170;233;206;170 09:27:48 INFO - PROCESS | 1935 | |13;stanford-crypto-sha256-iterative;68;68;67;67;75;71;66;66;66;68 09:27:48 INFO - PROCESS | 1935 | __end_tp_report 09:27:48 INFO - PROCESS | 1935 | __start_cc_report 09:27:48 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,2097 09:27:48 INFO - PROCESS | 1935 | __end_cc_report 09:27:48 INFO - PROCESS | 1935 | __startTimestamp1472574468173__endTimestamp 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | ------- Summary: start ------- 09:27:48 INFO - PROCESS | 1935 | Number of tests: 14 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#0] ai-astar Cycles:10 Average:125.10 Median:116.50 stddev:20.70 (17.8%) stddev-sans-first:21.48 09:27:48 INFO - PROCESS | 1935 | Values: 113.0 113.0 132.0 113.0 113.0 120.0 163.0 162.0 111.0 111.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:130.50 stddev:20.23 (15.5%) stddev-sans-first:20.57 09:27:48 INFO - PROCESS | 1935 | Values: 148.0 130.0 129.0 162.0 109.0 131.0 127.0 108.0 162.0 110.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#2] audio-dft Cycles:10 Average:182.30 Median:183.00 stddev:1.70 (0.9%) stddev-sans-first:1.80 09:27:48 INFO - PROCESS | 1935 | Values: 182.0 184.0 183.0 181.0 183.0 182.0 183.0 185.0 181.0 179.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#3] audio-fft Cycles:10 Average:83.30 Median:64.50 stddev:36.38 (56.4%) stddev-sans-first:38.51 09:27:48 INFO - PROCESS | 1935 | Values: 77.0 78.0 76.0 158.0 58.0 58.0 58.0 71.0 142.0 57.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#4] audio-oscillator Cycles:10 Average:99.00 Median:69.00 stddev:25.06 (36.3%) stddev-sans-first:25.71 09:27:48 INFO - PROCESS | 1935 | Values: 81.0 82.0 136.0 86.0 102.0 114.0 131.0 69.0 69.0 120.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:85.50 stddev:2.54 (3.0%) stddev-sans-first:2.65 09:27:48 INFO - PROCESS | 1935 | Values: 87.0 84.0 87.0 84.0 85.0 84.0 92.0 84.0 86.0 84.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:27:48 INFO - PROCESS | 1935 | Values: 92.0 93.0 92.0 92.0 92.0 93.0 92.0 93.0 92.0 92.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:84.00 stddev:23.83 (28.4%) stddev-sans-first:24.78 09:27:48 INFO - PROCESS | 1935 | Values: 84.0 84.0 83.0 86.0 100.0 141.0 142.0 83.0 84.0 86.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.08 (2.0%) stddev-sans-first:1.13 09:27:48 INFO - PROCESS | 1935 | Values: 55.0 53.0 56.0 54.0 55.0 56.0 53.0 54.0 54.0 55.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:73.00 stddev:4.90 (6.7%) stddev-sans-first:4.92 09:27:48 INFO - PROCESS | 1935 | Values: 67.0 62.0 70.0 81.0 73.0 74.0 73.0 71.0 71.0 73.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.50 stddev:1.75 (2.0%) stddev-sans-first:1.67 09:27:48 INFO - PROCESS | 1935 | Values: 84.0 85.0 85.0 86.0 84.0 88.0 86.0 87.0 89.0 88.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:151.50 stddev:14.97 (9.9%) stddev-sans-first:15.37 09:27:48 INFO - PROCESS | 1935 | Values: 141.0 124.0 146.0 151.0 151.0 150.0 154.0 152.0 181.0 167.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.40 Median:188.00 stddev:26.68 (14.2%) stddev-sans-first:26.92 09:27:48 INFO - PROCESS | 1935 | Values: 166.0 172.0 232.0 202.0 169.0 174.0 170.0 233.0 206.0 170.0 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - PROCESS | 1935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:2.82 (4.1%) stddev-sans-first:2.99 09:27:48 INFO - PROCESS | 1935 | Values: 68.0 68.0 67.0 67.0 75.0 71.0 66.0 66.0 66.0 68.0 09:27:48 INFO - PROCESS | 1935 | -------- Summary: end -------- 09:27:48 INFO - PROCESS | 1935 | 09:27:48 INFO - TEST-INFO | 1935: exit 0 09:27:49 INFO - TEST-OK | kraken | took 59799ms 09:27:49 INFO - SUITE-END | took 1177s 09:27:49 INFO - Completed test suite (00:19:38) 09:27:49 INFO - javascript benchmark 09:27:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.4823802310643, 3363.394588973985, 3382.746294027947, 3399.592048954126, 3444.328355970587, 5602.0, 5724.427557244274, 5782.653040817554, 5866.911996320054, 5890.941090589098, 5810.651360918338, 5813.389594092623, 5899.174115623816, 5915.467607915287, 5955.047192449209, 4382.605565499104, 5182.119039763249, 5202.089634313998, 5279.260903473517, 5385.78456861727, 5339.22581225722, 5397.379301380332, 5412.4587541245955, 5426.511613954744, 5447.101228297326, 5186.740662966874, 5261.105612045923, 5278.287431196778, 5325.307709997675, 5334.493223143814, 5248.448912864171, 5292.470752924715, 5297.867553311198, 5324.92012619811, 5380.838574842722, 5195.298634684307, 5240.213967904807, 5255.1329030709885, 5287.973560132214, 5312.442193569658, 5850.707464626752, 5851.473367396955, 5873.148393482921, 5879.67661778602, 5881.85295367615, 6132.967444181115, 6160.322364539897, 6234.345393733638, 6252.093446450284, 6304.7793327233785, 6224.9688751556405, 6246.812595622138, 6263.655498947556, 6284.025975973731, 6307.621542707452, 6010.068439391901, 6017.939820601781, 6018.909716354258, 6030.608010479349, 6042.969785151046, 6173.506119510428, 6237.4698150656795, 6290.55966082379, 6292.559520833589, 6396.584222025554, 6202.472789812917, 6251.812445626639, 6255.655938923403, 6265.248170219512, 6273.62358258506, 6303.338149494329, 6309.305976342599, 6317.557770955989, 6331.430171284606, 6332.208473940757, 6114.753609057711, 6196.256449226122, 6225.470834978987, 6229.0967809667345, 6291.213598300213, 6224.968875155686, 6250.687465626791, 6276.560640755103, 6293.496520278367, 6301.6219026858535, 5042.722650254272, 5048.7728052237735, 5049.747512624354, 5055.8988820223385, 5086.262491938648, 4467.955320446819, 4486.68593198473, 4496.280595104768, 4550.544945505423, 4610.631149508032, 5875.647461152259, 5898.852528686817, 5902.173695682608, 5942.346341902387, 5965.3139888912465, 6233.844153896098, 6258.874822503615, 6270.905936410957, 6297.779577714758, 6308.936910630927, 5647.407022262603, 5725.59920805548, 5766.452187042224, 5888.499477544371, 5897.734601942923, 5470.781168753285, 5497.45025497455, 5529.944700552943, 5577.972110139423, 5596.552275817925, 6398.2322121344805, 6402.519811014286, 6406.711697973509, 6409.455196308366, 6430.324815894263, 5086.64393492452, 6175.011998080286, 6255.249370075438, 6262.749490020348, 6278.434940855345, 6324.335944725737, 6326.4622507087415, 6341.334159913327, 6416.743330266737, 6419.390157934989, 6244.812655620339, 6277.372262773686, 6278.0269058296035, 6287.874242515216, 6288.182536270256, 5079.796808127633, 5463.781448742165, 6220.253569571681, 6278.0, 6301.243850737941, 6131.080337949344, 6136.243373036893, 6158.261008678987, 6186.783462578788, 6222.408871157233, 6016.0, 6198.969005154945, 6207.379262073757, 6243.094751260951, 6252.406021427957, 5880.882382352414, 6093.817185484442, 6101.206843110367, 6122.0, 6123.969380153071, 6133.570650054453, 6134.509239260759, 6135.171422439487, 6175.938240617536, 6213.316535181217, 6024.638521688713, 6051.152838602688, 6069.393060693896, 6098.790072337538, 6134.2332208473945], "value": 5769.953394824503, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.5401721270123, 462.6437643014878, 478.07492502008614, 480.1836877308575, 507.1960941907077, 24962.625560616583, 31810.20474488139, 31855.04434866952, 32548.349033019356, 35378.11554711127, 42155.52455664052, 43013.7849310753, 43053.138937221236, 43105.35341969873, 43134.0, 9761.219102471803, 9945.254105942084, 9952.950235248814, 9955.701328960107, 9975.551100200471, 8566.229039386453, 8811.73564793054, 9077.0, 9844.163246124052, 9980.95009524955, 12018.819717704242, 12316.753664926697, 12323.322217278046, 12437.0, 12491.562795302167, 12236.204646697981, 12243.571474998376, 12289.938550307192, 12316.445759940823, 12339.753204935942, 12039.879601204051, 12116.878831211663, 12120.636380908587, 12123.090768192333, 12128.393580321037, 40194.39708404376, 40478.79760601208, 40654.9836254096, 40657.0, 40745.592544074476, 3433.776804507699, 3439.208981934169, 3450.2064525159353, 3626.546681664792, 3654.1778099927305, 30789.230269243224, 31178.532322015188, 31188.0, 31629.36741265185, 31719.841400792848, 2731.576605626131, 3120.188750924753, 3239.174010627275, 3245.7241134503597, 3304.1244070321386, 369676.0, 372393.0, 372519.0, 375816.0, 381541.0, 61092.08361874575, 61412.0, 61546.76906461935, 61548.692256539325, 61630.69184654048, 30046.699533004827, 63975.04037439442, 64132.35867641357, 64221.67889160525, 64381.03428448577, 15702.136382499073, 16414.753778693328, 16490.422835200712, 16502.83497165037, 16570.254338554794, 12614.180078294881, 12706.364681765876, 12726.618201453972, 12727.618171454871, 12740.936295318465, 16976.830231697524, 17363.739543906853, 18958.905205473886, 19135.330263440712, 19338.0, 7800.726974555977, 7961.780256463968, 7971.242731940572, 8002.9599852000365, 8121.390895682847, 830.9958450207711, 879.6613303878053, 962.8603852441497, 1031.85038169465, 1063.7712891728315, 28785.56821647677, 29924.700752992627, 30051.248718781768, 30698.232544186572, 30763.077107686804, 1105.2926127278547, 1132.705496570881, 1144.399190425017, 1168.6260396672983, 1204.2473454091194, 14964.251787410585, 15275.61810954735, 15540.456084037005, 15839.762403563956, 16044.278007489691, 705088.0, 709576.0, 709808.0, 711471.0, 712370.0, 56813.03463136394, 57347.71326143426, 57564.71217643885, 57625.423745762004, 57685.0, 1223.3638507975625, 1307.3593938970027, 1365.8019587159806, 1380.2615600653603, 1384.9099808512415, 26471.73528264693, 26484.0, 29465.85267073651, 30187.396252074395, 30239.395212096075, 50338.0, 54085.18872216841, 54802.72598636981, 54806.79335463125, 58558.414415857, 641650.0, 649037.0, 649751.0, 649966.0, 650022.0, 33821.83089084539, 34360.82819585886, 42459.787701061294, 42658.78670606627, 42941.0, 45811.0, 46317.0, 46600.06799863916, 46886.82782930454, 47030.0, 30900.536491953088, 37765.97872170275, 38420.6157938417, 38752.6124738749, 39002.804985974886, 36563.0, 37476.43785343276, 37731.24537509289, 38151.0, 39300.606993930836, 36953.07617309589, 37430.0, 37889.62110378861, 37915.62084379231, 37941.62058379381, 25889.482210355312, 32160.51759223567, 32838.0, 33164.0, 33610.99167024993, 24446.14438494644, 24625.507489849744, 24693.259202223962, 25491.107811226517, 25634.309219207975], "value": 20352.463291381002, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.2626970227672, 373.719710217337, 377.0855674988153, 387.19849910684883, 404.2823987422325, 15109.471168509104, 15691.372345106196, 19984.400467985946, 20645.896770516163, 20732.585348293025, 19485.41543753686, 19506.609867802636, 20139.899300503515, 20156.39530814074, 20209.898950505227, 5140.614453915952, 5317.016351974897, 5848.210491583635, 6008.1888944992525, 6110.2973158086725, 4154.916901661965, 4378.824847006132, 4380.605745482906, 5976.073708575156, 6067.211262535886, 4343.826246950134, 4367.628751556105, 4368.279233926399, 4369.890752731176, 4512.16524942885, 6541.28045914949, 6555.5083368747055, 6601.108850305243, 6601.93398066018, 6605.108310378134, 5446.591505637093, 5611.438856114397, 5669.886602267931, 5670.319561652587, 5688.857778555528, 21861.890690546603, 22007.339779806633, 23968.880155599112, 25395.984160633554, 25573.721313934228, 1796.2006906926422, 2077.772596025049, 2266.3767463947547, 2536.4293034067373, 2643.70919198888, 14542.927285363612, 14877.7768333474, 15038.02252853561, 15192.772108418383, 15193.83253308817, 1575.771513130601, 1796.8832025918273, 1838.4208974173093, 1854.7125195594704, 1904.9047547622563, 101913.98086019194, 104614.47692761487, 104617.0, 105202.47398763034, 105879.99999999923, 8813.471191728517, 10241.795164096826, 10442.791144177227, 10582.57669693219, 10748.570057197625, 13005.0, 13008.349582520836, 13029.47882084706, 13040.478380864848, 13044.869551304555, 8155.79610509742, 8301.8339633207, 8353.248207661218, 8374.539400332918, 8525.616347264264, 8436.746897593082, 8467.788305292293, 8621.784455388666, 8635.827283454295, 8656.0, 6209.130721698965, 6322.241331040304, 6333.68331583419, 6345.33373995733, 6351.364863513611, 1806.1962426720247, 1836.4215272189217, 1836.8806027608164, 1838.3565751986698, 1840.4570651657793, 819.610684924656, 898.4878619187132, 898.5282726568473, 947.6637940503856, 1020.0, 20142.194312227348, 20698.58602827935, 21312.893435533035, 21444.78555214428, 21452.0, 944.1927152284835, 944.3955868244329, 950.092661508258, 951.2580187453797, 971.3928794503436, 8717.166920750782, 10143.492825358702, 11201.935816097457, 11219.495122719498, 11290.0967922566, 564270.0, 581280.0, 604768.0, 607397.0, 611941.0, 106055.46972265089, 114711.0, 115400.42299788447, 117995.0, 119222.40388798, 962.3023308101571, 993.9950300248453, 1027.4708525109575, 1027.4759872465097, 1029.9948500257601, 17782.288708452033, 17908.01505917187, 18127.36554220596, 18201.362952296608, 18521.35175268913, 52269.16901843965, 53087.46912530825, 53481.66295842635, 53701.19448208202, 53962.0, 73659.63170184329, 75070.0, 75962.24037759552, 76067.43859405338, 76327.0, 7655.043119610049, 7744.92255077442, 7760.961195193988, 7845.29392354668, 8107.22981316774, 10550.894491055298, 12403.193792403465, 12879.291638959947, 13061.21632702041, 13085.738285234052, 10968.616098436516, 11055.668329950115, 11346.546138154712, 11425.542978280775, 11437.656870293904, 9572.76068098303, 11031.0, 11121.610743623934, 11311.773764524827, 11328.433578321215, 9711.883133439746, 10662.093722033404, 11100.722481937693, 11350.772984540428, 11474.885251147716, 6615.470762339098, 7037.944308353788, 7582.658780354787, 7731.845363092819, 7830.295273425191, 10889.455527223765, 11047.281926674741, 11268.830967535658, 11324.32054076758, 11350.205485615928], "value": 9401.694851004959, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.7194125204335, 1548.5122186511248, 1555.0747305353314, 1787.5173703100163, 1936.8256856882879, 7903.130655627883, 8230.629621667025, 8237.217464340893, 8243.340532757387, 8666.91333086669, 7557.659905304261, 7610.771676849689, 8758.868616970734, 8807.559622018889, 8815.294776417888, 2371.5968285391436, 2425.5997760369605, 2426.4783071639595, 2430.0644251962945, 2431.112643884987, 2367.4318163640687, 2410.385351735305, 2419.6854408926815, 2420.092465325503, 2425.0784701813286, 2382.1781485387514, 2406.4465173010217, 2410.80713542917, 2417.8186636002283, 2425.63615457681, 2005.729226554411, 2349.201271567675, 2381.9285421437385, 2384.332386931662, 2386.7135943686694, 2324.6629238760456, 2369.8104151667826, 2372.608519594265, 2372.810175185981, 2378.1676413255395, 7545.471816972814, 8869.157430044135, 8960.775980600538, 8989.37074404792, 8995.460272383678, 5250.291210686547, 5250.8687282817855, 5423.945760542383, 5427.375851777057, 5434.64674796137, 3649.872254471081, 3703.4259689748133, 3704.759190652626, 3705.018170184876, 3706.3884459064493, 1056.498163372392, 1084.815581351172, 1084.8806631270554, 1106.0322218059198, 1114.331401159306, 11449.427528623535, 11531.884681153166, 11537.5961841336, 11542.249753765978, 11586.478608462723, 4481.641468682563, 4492.641363284237, 4615.146197953322, 4688.671792974459, 4693.694909830919, 9028.187463128219, 9046.276297896153, 9047.547622618975, 9061.637534498546, 9063.728088157366, 5257.237700533401, 5261.736913154327, 5263.157894736823, 5263.4473380295285, 5266.868328291824, 4452.977735111369, 4490.034642551867, 4803.231482962779, 4930.802767889316, 4992.825251116194, 3264.8530816112857, 3710.0, 3765.2281282337053, 3857.3828187490426, 3872.361060425055, 994.6568433890296, 1163.7207070303236, 1187.7884557751217, 1189.6371606660052, 1191.862935762381, 848.3849209323191, 863.3999370437612, 903.6882275614902, 911.2755359489222, 933.9626414943326, 5648.717564121777, 5673.59584249447, 5878.588498805128, 5938.138969849348, 6002.0, 949.9192568631605, 961.3414810854587, 970.1414248390084, 971.6016433262329, 1018.2566726289701, 3863.4976857708234, 3936.3505021671103, 3988.3618621020496, 4028.879133625996, 4039.3537034074407, 10316.43259620728, 10746.24776265654, 10749.032587067202, 10797.946010270056, 11129.443527823738, 10303.0, 10694.786104277871, 10697.572097116185, 10775.515101820436, 10838.512266947848, 6622.569532980341, 7304.488685791944, 8254.215849494289, 8260.256576908107, 8313.210245026712, 5772.624779389326, 5875.970620146872, 5892.0, 6071.969640151771, 6245.750169993149, 9696.95151524238, 9783.36408133469, 10021.599136034769, 10022.298439109483, 10110.292279540361, 9906.603735850485, 10124.34191777532, 10217.897821021694, 10302.793944121226, 10316.226283028653, 6332.841678958063, 6869.141357330333, 6989.056477375692, 7212.639368031682, 7706.152323244327, 7153.4992550520965, 8545.658173672984, 8613.612387442705, 8621.69824056155, 8627.827443451222, 7311.41508679315, 7504.699812007459, 7566.735164269224, 7637.198094200029, 7720.227977202235, 7279.672414741243, 7289.744858929912, 7320.524165929198, 7516.736914207976, 7525.6989720410575, 6847.760328388483, 6998.405135563534, 6998.720051197895, 7011.018457416068, 7013.894791578028, 3576.477591210079, 4905.5094490550655, 4986.47641997585, 5044.268581055653, 5082.2122571001555, 4074.633283004544, 4985.4266759323145, 5074.441811400817, 5099.413567439792, 5189.584833213409], "value": 4542.043154420721, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.9333546619743, 306.33830925201556, 312.72480217408685, 316.9286910445149, 317.9634342050665, 4530.773461326937, 4655.928111990166, 5186.74066296685, 5493.917591236134, 5580.441955804417, 4471.8658440246845, 4525.275955847065, 5260.158374660055, 5421.457854214576, 5450.727463626813, 3421.9220945402244, 3910.081130934228, 3941.9014524636827, 4039.151778126597, 4072.2059198456313, 3835.884923452301, 4078.938815917764, 4082.9795851020704, 4105.856295029675, 4122.2579935611575, 3380.442227032549, 4072.3077076897, 4072.574598890394, 4082.9588454943946, 4102.558977408437, 3121.5161649944293, 3632.3098611263777, 3865.16898866745, 4045.271851066807, 4120.198265179677, 2896.6089577906923, 3021.425929073491, 3556.3065202285566, 3559.2703495783303, 3583.086312990197, 4600.7929643166135, 5213.191955246943, 5225.137852254412, 5228.320318358589, 5294.973525132388, 3903.394973779069, 4273.316269396882, 4466.977665111654, 4696.882577935545, 4731.716097034189, 4150.709450338478, 5109.923351149736, 5632.211490391349, 5638.74625641843, 5646.717664116819, 3383.6447173046968, 3925.3130702127014, 3985.2228815381013, 3988.0, 3993.9600603993877, 5558.555315574785, 5909.852253693692, 5945.64326140433, 5994.910076348815, 5999.32019034671, 3027.1221345809467, 3802.125511132455, 3861.691064714816, 3864.8840534784003, 3868.497095377639, 3618.3306088373733, 3937.370020796659, 3954.387070004154, 3957.920841583152, 3958.722889397772, 3843.577206507282, 4586.931196032062, 4615.376924115201, 4624.5144259852905, 4626.167289887785, 5427.294451721251, 5551.944480555224, 5611.663300202001, 5630.4369563043365, 5658.97170514153, 1113.4766659669942, 1283.530357740382, 1312.5078095714107, 1312.6849556106656, 1319.8416190057255, 658.8978708300222, 703.6341102626708, 813.9511629302261, 828.0, 832.675256649907, 4505.36924830524, 4508.278675411918, 4575.611073058761, 4581.587657110876, 4902.386767151592, 5294.967481341178, 5465.562754979592, 5503.0, 5670.15719891037, 5680.6591604503865, 4117.505899292104, 4885.828995985124, 5022.221555658879, 5203.022851316476, 5368.543673787772, 2981.7316441519934, 3059.7858149929293, 3067.3251884585356, 3080.9537856931715, 3089.5520149578633, 5275.808136077694, 5585.158181227963, 6035.895590998076, 6045.637261764308, 6124.724387402578, 5305.549028332635, 5313.0247061645605, 5664.6034777566, 5780.364159942403, 6033.818985430444, 5179.792808287626, 5286.735663216824, 5520.641158324776, 5728.713564321684, 6010.767792602506, 5352.304200453916, 5557.861053473695, 5884.029135192645, 5886.381929897299, 5900.5217808519665, 5282.283257941108, 5700.714964251688, 5733.369329373849, 5773.546242846689, 5792.82621521355, 5187.210412407713, 5646.70392512354, 5988.6706231157705, 5992.64044157352, 6026.306974697965, 3149.8582563785144, 3194.4090343286566, 3408.0, 3418.4872269159823, 3635.4001589738455, 5299.887023725061, 5815.76736930518, 5816.0, 5826.533877289722, 5840.357560668408, 5266.131088370376, 5692.203091567101, 5724.3703192647945, 5779.190913272061, 5780.0, 5295.841124766263, 5625.0, 5677.630953987978, 5748.223989761329, 5785.82642520725, 5592.608517403743, 5596.328440586993, 5597.188407680782, 5602.187682786055, 5613.635113717595, 5496.862578435571, 5579.860503487283, 5688.374278829243, 5700.657960522216, 5735.684537350486, 4778.880527986689, 5140.897182056413, 5376.946230537801, 5578.888422231614, 5585.497305242548], "value": 4052.84282276324, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.588693028426, 720.6144712578771, 764.9863930292364, 789.8657228271194, 816.6325153680843, 15164.090154590718, 15786.684266314667, 15790.289436975336, 15813.683726325467, 15840.603984900368, 14264.215468149274, 14288.357023933922, 14309.0, 14311.78532322016, 14833.777493337608, 678.8404724889647, 697.3967518096861, 819.0867183090841, 839.4669384940561, 841.6633346661323, 847.9703210387606, 859.256742917375, 859.4843094143495, 860.6643409070467, 861.9008813986378, 895.2882458445519, 897.5108565831638, 897.9910200897973, 901.2294488212582, 905.2622112977901, 709.6770969208998, 871.9651213951435, 894.8255090257435, 896.7668406214366, 900.779309069279, 673.5150691502159, 675.5001299038726, 675.8918573028292, 677.4986509982577, 677.8203775999315, 13825.79261311071, 14866.256687165598, 14886.553403397915, 14918.328675209534, 14922.328495217742, 13785.172889626654, 13967.720645587031, 14059.297035148202, 14135.434582616683, 14259.215743134228, 10296.588136474533, 11351.26216795914, 11772.529098836036, 11884.821727674092, 12217.083718721131, 4465.531119232499, 4511.661625378109, 4575.496695363508, 4622.8150873965005, 4688.062387522509, 92898.21305360917, 114733.0, 114746.0, 114853.0, 116149.41925290404, 9891.554880030271, 11039.116870650489, 11063.944680276547, 11092.0, 11418.0, 22284.220052298093, 22349.77650223477, 22385.328440146823, 22386.44033899133, 22713.977870995845, 7812.531248125017, 7823.178566250575, 7848.372130229568, 7862.488938218998, 7936.484128531627, 3598.676119149289, 4233.428487154257, 4283.164782867311, 4331.501877284152, 4429.911401771947, 3494.965050349515, 3610.3681855675677, 3610.458431235336, 3610.765300255475, 3611.1513794258326, 572.5848759649305, 668.6823758714571, 675.9831004224835, 693.2928413018793, 749.456643933155, 579.2932622200908, 636.38270877249, 639.1212083385345, 678.8540463800208, 690.1132045321838, 7355.8161045974275, 9302.95348523253, 9361.344705870524, 9537.666181683608, 9616.807663846685, 534.2973989204185, 626.5332327416013, 654.1561385812249, 679.1578442730978, 699.3530983839928, 4350.282203436461, 4467.575580319929, 4497.8200871964755, 4501.167284052461, 4522.0, 122497.0, 155320.0, 156657.0, 157458.0, 159678.0, 112271.43864280626, 112936.0, 114048.0, 117013.0, 134495.32752336373, 101343.98656013346, 105600.94399056064, 110866.0, 112263.0, 113153.43423282831, 9296.535173241446, 9881.160101391175, 9895.851562226717, 9900.603975840886, 9936.950315248378, 47550.0, 59405.40594594172, 61426.69286653717, 63413.71402475899, 64752.676236618514, 84558.0, 87330.69003965073, 90332.54833725789, 93080.53459732658, 98984.0, 12886.066492342174, 12973.286469243905, 12997.0, 13314.0, 13618.93190534041, 14616.415343386145, 14676.486322979072, 14729.410823566937, 14776.852231477547, 15281.0, 11648.94175529117, 13412.865871341162, 13542.729145417234, 13629.727405452033, 14165.858341416866, 13458.0, 13574.994638430668, 13618.319084045559, 13645.79531307051, 13787.965902557147, 13170.789771262125, 13320.315224213544, 13483.460661573426, 13548.661283467993, 13872.514461993782, 11052.11583073336, 11149.048916439753, 11187.32876027441, 11273.71815704614, 11390.829137562778, 8419.200175983271, 10099.899001009895, 10737.946310268711, 10951.561937522729, 11451.427428628702], "value": 7798.9354581774405, "unit": "score"}], "name": "dromaeo_css", "value": 7356.673311205871, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 132.0, 113.0, 113.0, 120.0, 163.0, 162.0, 111.0, 111.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 130.0, 129.0, 162.0, 109.0, 131.0, 127.0, 108.0, 162.0, 110.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 181.0, 183.0, 182.0, 183.0, 185.0, 181.0, 179.0], "value": 182.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 158.0, 58.0, 58.0, 58.0, 71.0, 142.0, 57.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 136.0, 86.0, 102.0, 114.0, 131.0, 69.0, 69.0, 120.0], "value": 99.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 85.0, 84.0, 92.0, 84.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 92.0, 92.0, 93.0, 92.0, 93.0, 92.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 86.0, 100.0, 141.0, 142.0, 83.0, 84.0, 86.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 56.0, 54.0, 55.0, 56.0, 53.0, 54.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 62.0, 70.0, 81.0, 73.0, 74.0, 73.0, 71.0, 71.0, 73.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 86.0, 84.0, 88.0, 86.0, 87.0, 89.0, 88.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 124.0, 146.0, 151.0, 151.0, 150.0, 154.0, 152.0, 181.0, 167.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 172.0, 232.0, 202.0, 169.0, 174.0, 170.0, 233.0, 206.0, 170.0], "value": 189.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 75.0, 71.0, 66.0, 66.0, 66.0, 68.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1518.1000000000001, "alertThreshold": 2.0}]} 09:27:49 INFO - Return code: 0 09:27:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:49 INFO - # TBPL SUCCESS # 09:27:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:49 INFO - 2016-08-30 09:27:49.486 screenresolution[1939:19674] starting screenresolution argv=screenresolution get 09:27:49 INFO - 2016-08-30 09:27:49.490 screenresolution[1939:19674] Display 0: 1600x1200x32@60 09:27:49 INFO - 2016-08-30 09:27:49.495 screenresolution[1940:19677] starting screenresolution argv=screenresolution list 09:27:49 INFO - Available Modes on Display 0 09:27:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:49 INFO - Intel Iris: 09:27:49 INFO - Chipset Model: Intel Iris 09:27:49 INFO - Type: GPU 09:27:49 INFO - Bus: Built-In 09:27:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:49 INFO - Vendor: Intel (0x8086) 09:27:49 INFO - Device ID: 0x0a2e 09:27:49 INFO - Revision ID: 0x0009 09:27:49 INFO - Displays: 09:27:49 INFO - Display: 09:27:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:49 INFO - Display Serial Number: 1600x1200 60 09:27:49 INFO - Main Display: Yes 09:27:49 INFO - Mirror: Off 09:27:49 INFO - Online: Yes 09:27:49 INFO - Rotation: Supported 09:27:49 INFO - Return code: 0 09:27:49 INFO - Running post-action listener: _resource_record_post_action 09:27:49 INFO - [mozharness: 2016-08-30 16:27:49.729249Z] Finished run-tests step (success) 09:27:49 INFO - Running post-run listener: _resource_record_post_run 09:27:49 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 995328; Write bytes: 707227136; Read time: 8; Write time: 1982 09:27:49 INFO - TinderboxPrint: CPU usage
53.4% 09:27:49 INFO - TinderboxPrint: I/O read bytes / time
995,328 / 8 09:27:49 INFO - TinderboxPrint: I/O write bytes / time
707,227,136 / 1,982 09:27:49 INFO - TinderboxPrint: CPU idle
2,261.9 (46.6%) 09:27:49 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 09:27:49 INFO - TinderboxPrint: CPU user
1,622.0 (33.4%) 09:27:49 INFO - TinderboxPrint: Swap in / out
406,646,784 / 0 09:27:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385909248; Write bytes: 381749248; Read time: 29126; Write time: 785 09:27:49 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 995328; Write bytes: 297154048; Read time: 8; Write time: 1132 09:27:50 INFO - Running post-run listener: _upload_blobber_files 09:27:50 INFO - Blob upload gear active. 09:27:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:27:50 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:50 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:50 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:27:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:27:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:51 INFO - (blobuploader) - INFO - Done attempting. 09:27:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:27:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:27:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:52 INFO - (blobuploader) - INFO - Done attempting. 09:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:55 INFO - (blobuploader) - INFO - Done attempting. 09:27:55 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:55 INFO - Return code: 0 09:27:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/904c435b5b4a426191d5e8c7cef4d3dead940c9ea9d221116353668e3224f1c054796692e174f5a4a9781a05a1375841819489d360e51e01ed988f4d834257ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55eb2330de8529382762f0f4139c0cb8eee4c805ebb531e05409df7a30bd294844e2551b3a7ddc04ff1903196b6ff59a8050e70581c332b58a0750e6fe41ed04", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1335695d63938c63c0d87a5f8f04bb8b01baa462500e0dd995bf373a884f046bcaeaeae84c9f962940c78560bac59532ef1a232a3ad248bee2588c2273ebb088"} 09:27:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:55 INFO - Contents: 09:27:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/904c435b5b4a426191d5e8c7cef4d3dead940c9ea9d221116353668e3224f1c054796692e174f5a4a9781a05a1375841819489d360e51e01ed988f4d834257ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55eb2330de8529382762f0f4139c0cb8eee4c805ebb531e05409df7a30bd294844e2551b3a7ddc04ff1903196b6ff59a8050e70581c332b58a0750e6fe41ed04", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1335695d63938c63c0d87a5f8f04bb8b01baa462500e0dd995bf373a884f046bcaeaeae84c9f962940c78560bac59532ef1a232a3ad248bee2588c2273ebb088"} 09:27:55 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:55 INFO - Copying logs to upload dir... 09:27:55 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.689115 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-30 09:27:55.322475) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 09:27:55.325823) ========= 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.GzzwlpItcq/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.Idr2Smi1p5/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/Fx-Team/sha512/904c435b5b4a426191d5e8c7cef4d3dead940c9ea9d221116353668e3224f1c054796692e174f5a4a9781a05a1375841819489d360e51e01ed988f4d834257ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55eb2330de8529382762f0f4139c0cb8eee4c805ebb531e05409df7a30bd294844e2551b3a7ddc04ff1903196b6ff59a8050e70581c332b58a0750e6fe41ed04", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1335695d63938c63c0d87a5f8f04bb8b01baa462500e0dd995bf373a884f046bcaeaeae84c9f962940c78560bac59532ef1a232a3ad248bee2588c2273ebb088"} build_url:https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010786 build_url: 'https://queue.taskcluster.net/v1/task/S9-GOfJ8T6WhF1K9cv6dnQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/904c435b5b4a426191d5e8c7cef4d3dead940c9ea9d221116353668e3224f1c054796692e174f5a4a9781a05a1375841819489d360e51e01ed988f4d834257ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/55eb2330de8529382762f0f4139c0cb8eee4c805ebb531e05409df7a30bd294844e2551b3a7ddc04ff1903196b6ff59a8050e70581c332b58a0750e6fe41ed04", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1335695d63938c63c0d87a5f8f04bb8b01baa462500e0dd995bf373a884f046bcaeaeae84c9f962940c78560bac59532ef1a232a3ad248bee2588c2273ebb088"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 09:27:55.358196) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:27:55.358528) ========= 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.GzzwlpItcq/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.Idr2Smi1p5/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:27:55.407280) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 09:27:55.407595) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 09:27:55.407909) ========= ========= Total master_lag: 0.18 =========