builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1467758338.55 results: success (0) buildid: 20160705133123 builduid: 5ccbb53556874053bd4877040b848536 revision: dd8ef51f4920c5f4370afd0aebf43d1113363fc3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 15:38:58.546644) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 15:38:58.547087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-07-05 15:38:58.547470) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-07-05 15:38:59.674963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 15:38:59.676547) ========= 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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-07-05 15:38:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-07-05 15:39:00 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.608938 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:00.324520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:00.328493) ========= 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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.025178 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:00.396183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 15:39:00.396575) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev dd8ef51f4920c5f4370afd0aebf43d1113363fc3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev dd8ef51f4920c5f4370afd0aebf43d1113363fc3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.32H2J3Pf06/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.sSrsXiu1Vw/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-07-05 15:39:00,475 truncating revision to first 12 chars 2016-07-05 15:39:00,475 Setting DEBUG logging. 2016-07-05 15:39:00,475 attempt 1/10 2016-07-05 15:39:00,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/dd8ef51f4920?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 15:39:01,508 unpacking tar archive at: autoland-dd8ef51f4920/testing/mozharness/ program finished with exit code 0 elapsedTime=1.562792 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 15:39:01.984428) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:01.984769) ========= script_repo_revision: dd8ef51f4920c5f4370afd0aebf43d1113363fc3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:01.985221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:01.985507) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 15:39:02.003686) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-05 15:39:02.004021) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.32H2J3Pf06/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.sSrsXiu1Vw/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:02 INFO - MultiFileLogger online at 20160705 15:39:02 in /builds/slave/test 15:39:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:39:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:02 INFO - {'append_to_log': False, 15:39:02 INFO - 'base_work_dir': '/builds/slave/test', 15:39:02 INFO - 'blob_upload_branch': 'Autoland', 15:39:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:02 INFO - 'branch': 'Autoland', 15:39:02 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:02 INFO - 'config_files': ('talos/mac_config.py',), 15:39:02 INFO - 'default_actions': ('clobber', 15:39:02 INFO - 'read-buildbot-config', 15:39:02 INFO - 'download-and-extract', 15:39:02 INFO - 'populate-webroot', 15:39:02 INFO - 'create-virtualenv', 15:39:02 INFO - 'install', 15:39:02 INFO - 'run-tests'), 15:39:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:02 INFO - 'download_minidump_stackwalk': True, 15:39:02 INFO - 'download_symbols': 'ondemand', 15:39:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:02 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:02 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:02 INFO - 'installer_path': 'installer.exe', 15:39:02 INFO - 'log_level': 'info', 15:39:02 INFO - 'log_name': 'talos', 15:39:02 INFO - 'log_to_console': True, 15:39:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:39:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:39:02 INFO - 'opt_config_files': (), 15:39:02 INFO - 'pip_index': False, 15:39:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:02 INFO - 'cmd': ('bash', 15:39:02 INFO - '-c', 15:39:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:02 INFO - 'enabled': True, 15:39:02 INFO - 'halt_on_failure': False, 15:39:02 INFO - 'name': 'check_screen_resolution'},), 15:39:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:39:02 INFO - 'cmd': ('bash', 15:39:02 INFO - '-c', 15:39:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:39:02 INFO - 'enabled': True, 15:39:02 INFO - 'halt_on_failure': False, 15:39:02 INFO - 'name': 'check_screen_resolution'},), 15:39:02 INFO - 'run_cmd_checks_enabled': True, 15:39:02 INFO - 'sps_profile': False, 15:39:02 INFO - 'sps_profile_interval': 0, 15:39:02 INFO - 'suite': 'dromaeojs', 15:39:02 INFO - 'system_bits': '32', 15:39:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:02 INFO - 'title': 't-yosemite-r7-0296', 15:39:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:02 INFO - 'use_talos_json': True, 15:39:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:02 INFO - 'work_dir': 'build'} 15:39:02 INFO - ##### 15:39:02 INFO - ##### Running clobber step. 15:39:02 INFO - ##### 15:39:02 INFO - Running pre-action listener: _resource_record_pre_action 15:39:02 INFO - Running main action method: clobber 15:39:02 INFO - rmtree: /builds/slave/test/build 15:39:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:04 INFO - Running post-action listener: _resource_record_post_action 15:39:04 INFO - ##### 15:39:04 INFO - ##### Finished clobber step (success) 15:39:04 INFO - ##### 15:39:04 INFO - ##### 15:39:04 INFO - ##### Running read-buildbot-config step. 15:39:04 INFO - ##### 15:39:04 INFO - Running pre-action listener: _resource_record_pre_action 15:39:04 INFO - Running main action method: read_buildbot_config 15:39:04 INFO - Using buildbot properties: 15:39:04 INFO - { 15:39:04 INFO - "project": "", 15:39:04 INFO - "product": "firefox", 15:39:04 INFO - "script_repo_revision": "production", 15:39:04 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:39:04 INFO - "repository": "", 15:39:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:39:04 INFO - "buildid": "20160705133123", 15:39:04 INFO - "pgo_build": "False", 15:39:04 INFO - "basedir": "/builds/slave/test", 15:39:04 INFO - "buildnumber": 12, 15:39:04 INFO - "slavename": "t-yosemite-r7-0296", 15:39:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:39:04 INFO - "platform": "yosemite_r7", 15:39:04 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:39:04 INFO - "branch": "autoland", 15:39:04 INFO - "repo_path": "integration/autoland", 15:39:04 INFO - "revision": "dd8ef51f4920c5f4370afd0aebf43d1113363fc3", 15:39:04 INFO - "stage_platform": "macosx64", 15:39:04 INFO - "builduid": "5ccbb53556874053bd4877040b848536", 15:39:04 INFO - "slavebuilddir": "test" 15:39:04 INFO - } 15:39:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 15:39:04 INFO - Running post-action listener: _resource_record_post_action 15:39:04 INFO - ##### 15:39:04 INFO - ##### Finished read-buildbot-config step (success) 15:39:04 INFO - ##### 15:39:04 INFO - ##### 15:39:04 INFO - ##### Running download-and-extract step. 15:39:04 INFO - ##### 15:39:04 INFO - Running pre-action listener: _resource_record_pre_action 15:39:04 INFO - Running main action method: download_and_extract 15:39:04 INFO - mkdir: /builds/slave/test/build/tests 15:39:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:04 INFO - https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:39:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 15:39:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 15:39:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:39:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 15:39:17 INFO - Downloaded 1199 bytes. 15:39:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:39:17 INFO - Using the following test package requirements: 15:39:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:39:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 15:39:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 15:39:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'jsshell-mac.zip'], 15:39:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 15:39:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:39:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 15:39:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 15:39:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 15:39:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:39:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 15:39:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:17 INFO - https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:39:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:39:18 INFO - Downloaded 18803995 bytes. 15:39:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:39:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:39:21 INFO - Return code: 0 15:39:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:21 INFO - https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:39:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:39:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:39:24 INFO - Downloaded 18803995 bytes. 15:39:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:39:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:39:28 INFO - Return code: 0 15:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:28 INFO - https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:39:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:39:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:39:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:39:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:39:29 INFO - Downloaded 14403333 bytes. 15:39:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:39:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:39:29 INFO - Return code: 0 15:39:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:29 INFO - https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:39:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:39:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:39:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 15:39:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 15:39:33 INFO - Downloaded 112544297 bytes. 15:39:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:39:33 INFO - mkdir: /builds/slave/test/properties 15:39:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:33 INFO - Contents: 15:39:33 INFO - build_url:https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:39:34 INFO - Running post-action listener: _resource_record_post_action 15:39:34 INFO - Running post-action listener: set_extra_try_arguments 15:39:34 INFO - ##### 15:39:34 INFO - ##### Finished download-and-extract step (success) 15:39:34 INFO - ##### 15:39:34 INFO - ##### 15:39:34 INFO - ##### Running populate-webroot step. 15:39:34 INFO - ##### 15:39:34 INFO - Running pre-action listener: _resource_record_pre_action 15:39:34 INFO - Running main action method: populate_webroot 15:39:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:39:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:39:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:39:34 INFO - '--tppagecycles', 15:39:34 INFO - '7'], 15:39:34 INFO - 'tests': ['tsvgm']}}, 15:39:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:39:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:39:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['damp', 'tps']}, 15:39:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'tests': ['damp', 'tps']}, 15:39:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['dromaeo_dom']}, 15:39:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['basic_compositor_video']}, 15:39:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:39:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['a11yr', 15:39:34 INFO - 'ts_paint', 15:39:34 INFO - 'tpaint', 15:39:34 INFO - 'sessionrestore', 15:39:34 INFO - 'sessionrestore_no_auto_restore', 15:39:34 INFO - 'tabpaint']}, 15:39:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:34 INFO - 'ts_paint', 15:39:34 INFO - 'tpaint', 15:39:34 INFO - 'sessionrestore', 15:39:34 INFO - 'sessionrestore_no_auto_restore', 15:39:34 INFO - 'tabpaint']}, 15:39:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:39:34 INFO - 'ts_paint', 15:39:34 INFO - 'tpaint', 15:39:34 INFO - 'sessionrestore', 15:39:34 INFO - 'sessionrestore_no_auto_restore', 15:39:34 INFO - 'tabpaint']}, 15:39:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:39:34 INFO - 'ts_paint', 15:39:34 INFO - 'tpaint', 15:39:34 INFO - 'sessionrestore', 15:39:34 INFO - 'sessionrestore_no_auto_restore', 15:39:34 INFO - 'tabpaint']}, 15:39:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['a11yr', 15:39:34 INFO - 'ts_paint', 15:39:34 INFO - 'tpaint', 15:39:34 INFO - 'sessionrestore', 15:39:34 INFO - 'sessionrestore_no_auto_restore', 15:39:34 INFO - 'tabpaint']}, 15:39:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['a11yr', 15:39:34 INFO - 'ts_paint', 15:39:34 INFO - 'tpaint', 15:39:34 INFO - 'sessionrestore', 15:39:34 INFO - 'sessionrestore_no_auto_restore', 15:39:34 INFO - 'tabpaint']}, 15:39:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['tsvgx', 15:39:34 INFO - 'tsvgr_opacity', 15:39:34 INFO - 'tart', 15:39:34 INFO - 'tscrollx', 15:39:34 INFO - 'cart']}, 15:39:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:34 INFO - 'tsvgr_opacity', 15:39:34 INFO - 'tart', 15:39:34 INFO - 'tscrollx', 15:39:34 INFO - 'cart']}, 15:39:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'talos_options': ['--disable-e10s'], 15:39:34 INFO - 'tests': ['tp5o']}, 15:39:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'tests': ['tp5o']}, 15:39:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'talos_options': ['--disable-e10s', 15:39:34 INFO - '--xperf_path', 15:39:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:34 INFO - 'tests': ['tp5n']}, 15:39:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:39:34 INFO - 'talos_options': ['--xperf_path', 15:39:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:34 INFO - 'tests': ['tp5n']}}, 15:39:34 INFO - 'talos.zip': {'path': '', 15:39:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:39:34 INFO - Running post-action listener: _resource_record_post_action 15:39:34 INFO - ##### 15:39:34 INFO - ##### Finished populate-webroot step (success) 15:39:34 INFO - ##### 15:39:34 INFO - ##### 15:39:34 INFO - ##### Running create-virtualenv step. 15:39:34 INFO - ##### 15:39:34 INFO - Running pre-action listener: _resource_record_pre_action 15:39:34 INFO - Running main action method: create_virtualenv 15:39:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:34 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 15:39:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:39:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:34 INFO - Using real prefix '/tools/python27' 15:39:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:35 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:38 INFO - Installing pip.................done. 15:39:38 INFO - Return code: 0 15:39:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:38 INFO - 'HOME': '/Users/cltbld', 15:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:38 INFO - 'LOGNAME': 'cltbld', 15:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:38 INFO - 'MOZ_NO_REMOTE': '1', 15:39:38 INFO - 'NO_EM_RESTART': '1', 15:39:38 INFO - 'PAGER': '/bin/cat', 15:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:38 INFO - 'PWD': '/builds/slave/test', 15:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:38 INFO - 'SHELL': '/bin/bash', 15:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:38 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:38 INFO - 'USER': 'cltbld', 15:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:38 INFO - 'XPC_FLAGS': '0x0', 15:39:38 INFO - 'XPC_SERVICE_NAME': '0', 15:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:38 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 15:39:38 INFO - Cleaning up... 15:39:38 INFO - Return code: 0 15:39:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:38 INFO - 'HOME': '/Users/cltbld', 15:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:38 INFO - 'LOGNAME': 'cltbld', 15:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:38 INFO - 'MOZ_NO_REMOTE': '1', 15:39:38 INFO - 'NO_EM_RESTART': '1', 15:39:38 INFO - 'PAGER': '/bin/cat', 15:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:38 INFO - 'PWD': '/builds/slave/test', 15:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:38 INFO - 'SHELL': '/bin/bash', 15:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:38 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:38 INFO - 'USER': 'cltbld', 15:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:38 INFO - 'XPC_FLAGS': '0x0', 15:39:38 INFO - 'XPC_SERVICE_NAME': '0', 15:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:38 INFO - Downloading/unpacking psutil>=3.1.1 15:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:42 INFO - Installing collected packages: psutil 15:39:42 INFO - Running setup.py install for psutil 15:39:42 INFO - building 'psutil._psutil_osx' extension 15:39:42 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 15:39:42 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 15:39:42 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 15:39:42 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 15:39:43 INFO - building 'psutil._psutil_posix' extension 15:39:43 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 15:39:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:39:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:39:43 INFO - ^ 15:39:43 INFO - 1 warning generated. 15:39:43 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 15:39:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:43 INFO - Successfully installed psutil 15:39:43 INFO - Cleaning up... 15:39:43 INFO - Return code: 0 15:39:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:43 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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:43 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 15:39:43 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 15:39:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:43 INFO - 'HOME': '/Users/cltbld', 15:39:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:43 INFO - 'LOGNAME': 'cltbld', 15:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:43 INFO - 'MOZ_NO_REMOTE': '1', 15:39:43 INFO - 'NO_EM_RESTART': '1', 15:39:43 INFO - 'PAGER': '/bin/cat', 15:39:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:43 INFO - 'PWD': '/builds/slave/test', 15:39:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:43 INFO - 'SHELL': '/bin/bash', 15:39:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:43 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:43 INFO - 'USER': 'cltbld', 15:39:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:43 INFO - 'XPC_FLAGS': '0x0', 15:39:43 INFO - 'XPC_SERVICE_NAME': '0', 15:39:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:43 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 15:39:43 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 15:39:43 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 15:39:43 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 15:39:43 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 15:39:43 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 15:39:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:46 INFO - Installing collected packages: mozsystemmonitor 15:39:46 INFO - Running setup.py install for mozsystemmonitor 15:39:46 INFO - Successfully installed mozsystemmonitor 15:39:46 INFO - Cleaning up... 15:39:46 INFO - Return code: 0 15:39:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:46 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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:46 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 15:39:46 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 15:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:46 INFO - 'HOME': '/Users/cltbld', 15:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:46 INFO - 'LOGNAME': 'cltbld', 15:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:46 INFO - 'MOZ_NO_REMOTE': '1', 15:39:46 INFO - 'NO_EM_RESTART': '1', 15:39:46 INFO - 'PAGER': '/bin/cat', 15:39:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:46 INFO - 'PWD': '/builds/slave/test', 15:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:46 INFO - 'SHELL': '/bin/bash', 15:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:46 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:46 INFO - 'USER': 'cltbld', 15:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:46 INFO - 'XPC_FLAGS': '0x0', 15:39:46 INFO - 'XPC_SERVICE_NAME': '0', 15:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:46 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:49 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:49 INFO - warning: no files found matching '*.txt' 15:39:49 INFO - no previously-included directories found matching 'build' 15:39:49 INFO - no previously-included directories found matching 'dist' 15:39:49 INFO - no previously-included directories found matching '.git*' 15:39:49 INFO - Installing collected packages: jsonschema, functools32 15:39:49 INFO - Running setup.py install for functools32 15:39:49 INFO - warning: no files found matching '*.txt' 15:39:49 INFO - no previously-included directories found matching 'build' 15:39:49 INFO - no previously-included directories found matching 'dist' 15:39:49 INFO - no previously-included directories found matching '.git*' 15:39:49 INFO - Successfully installed jsonschema functools32 15:39:49 INFO - Cleaning up... 15:39:49 INFO - Return code: 0 15:39:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:49 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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:49 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 15:39:49 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 15:39:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:49 INFO - 'HOME': '/Users/cltbld', 15:39:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:49 INFO - 'LOGNAME': 'cltbld', 15:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:49 INFO - 'MOZ_NO_REMOTE': '1', 15:39:49 INFO - 'NO_EM_RESTART': '1', 15:39:49 INFO - 'PAGER': '/bin/cat', 15:39:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:49 INFO - 'PWD': '/builds/slave/test', 15:39:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:49 INFO - 'SHELL': '/bin/bash', 15:39:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:49 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:49 INFO - 'USER': 'cltbld', 15:39:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:49 INFO - 'XPC_FLAGS': '0x0', 15:39:49 INFO - 'XPC_SERVICE_NAME': '0', 15:39:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:49 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:52 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 15:39:52 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 15:39:52 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 15:39:52 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 15:39:52 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 15:39:52 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 15:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 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 15:39:53 INFO - Downloading docopt-0.6.1.tar.gz 15:39:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:53 INFO - Running setup.py install for blobuploader 15:39:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:53 INFO - Running setup.py install for requests 15:39:54 INFO - Running setup.py install for docopt 15:39:54 INFO - Successfully installed blobuploader requests docopt 15:39:54 INFO - Cleaning up... 15:39:54 INFO - Return code: 0 15:39:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:54 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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:54 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 15:39:54 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 15:39:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:54 INFO - 'HOME': '/Users/cltbld', 15:39:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:54 INFO - 'LOGNAME': 'cltbld', 15:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:54 INFO - 'MOZ_NO_REMOTE': '1', 15:39:54 INFO - 'NO_EM_RESTART': '1', 15:39:54 INFO - 'PAGER': '/bin/cat', 15:39:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:54 INFO - 'PWD': '/builds/slave/test', 15:39:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:54 INFO - 'SHELL': '/bin/bash', 15:39:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:54 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:54 INFO - 'USER': 'cltbld', 15:39:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:54 INFO - 'XPC_FLAGS': '0x0', 15:39:54 INFO - 'XPC_SERVICE_NAME': '0', 15:39:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nxeOt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-JfgvOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-cJGAIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nGRs4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-cLeW8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Iog5uK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-URV50b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-CZkyIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wfttx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-l8k1ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ymalAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-AON72G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Gk4pAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-vsl_v3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-aCpYwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-U1A9_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-znUhzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:56 INFO - Running setup.py install for manifestparser 15:39:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:56 INFO - Running setup.py install for mozcrash 15:39:56 INFO - Running setup.py install for mozdebug 15:39:56 INFO - Running setup.py install for mozdevice 15:39:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Running setup.py install for mozfile 15:39:57 INFO - Running setup.py install for mozhttpd 15:39:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Running setup.py install for mozinfo 15:39:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Running setup.py install for mozInstall 15:39:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Running setup.py install for mozleak 15:39:57 INFO - Running setup.py install for mozlog 15:39:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:57 INFO - Running setup.py install for moznetwork 15:39:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:58 INFO - Running setup.py install for mozprocess 15:39:58 INFO - Running setup.py install for mozprofile 15:39:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:58 INFO - Running setup.py install for mozrunner 15:39:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:58 INFO - Running setup.py install for mozscreenshot 15:39:58 INFO - Running setup.py install for moztest 15:39:58 INFO - Running setup.py install for mozversion 15:39:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:59 INFO - Cleaning up... 15:39:59 INFO - Return code: 0 15:39:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:59 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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:39:59 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 15:39:59 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 15:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:59 INFO - 'HOME': '/Users/cltbld', 15:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:59 INFO - 'LOGNAME': 'cltbld', 15:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:59 INFO - 'MOZ_NO_REMOTE': '1', 15:39:59 INFO - 'NO_EM_RESTART': '1', 15:39:59 INFO - 'PAGER': '/bin/cat', 15:39:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:59 INFO - 'PWD': '/builds/slave/test', 15:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:59 INFO - 'SHELL': '/bin/bash', 15:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:39:59 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:59 INFO - 'USER': 'cltbld', 15:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:59 INFO - 'XPC_FLAGS': '0x0', 15:39:59 INFO - 'XPC_SERVICE_NAME': '0', 15:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-m5Qpgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:59 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)) 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8O8jjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:59 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)) 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-d0NyCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:59 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)) 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8paSWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:59 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)) 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-X2nD8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:59 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)) 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-1MYBTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:59 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)) 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-gux6Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:00 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pCh57H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:00 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-cU1HUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:00 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-dpxVLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-sKoxbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:00 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-4A96tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pf3eiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:00 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-VIQ4Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-9bYb4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:00 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)) 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:00 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Ba34o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:01 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)) 15:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-xn9VFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:01 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)) 15:40:01 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)) 15:40:01 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)) 15:40:01 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)) 15:40:01 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)) 15:40:01 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)) 15:40:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:03 INFO - Downloading blessings-1.6.tar.gz 15:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:03 INFO - Installing collected packages: blessings 15:40:03 INFO - Running setup.py install for blessings 15:40:03 INFO - Successfully installed blessings 15:40:03 INFO - Cleaning up... 15:40:04 INFO - Return code: 0 15:40:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:04 INFO - Reading from file tmpfile_stdout 15:40:04 INFO - Current package versions: 15:40:04 INFO - blessings == 1.6 15:40:04 INFO - blobuploader == 1.2.4 15:40:04 INFO - docopt == 0.6.1 15:40:04 INFO - functools32 == 3.2.3-2 15:40:04 INFO - jsonschema == 2.5.1 15:40:04 INFO - manifestparser == 1.1 15:40:04 INFO - mozInstall == 1.12 15:40:04 INFO - mozcrash == 0.17 15:40:04 INFO - mozdebug == 0.1 15:40:04 INFO - mozdevice == 0.48 15:40:04 INFO - mozfile == 1.2 15:40:04 INFO - mozhttpd == 0.7 15:40:04 INFO - mozinfo == 0.9 15:40:04 INFO - mozleak == 0.1 15:40:04 INFO - mozlog == 3.2 15:40:04 INFO - moznetwork == 0.27 15:40:04 INFO - mozprocess == 0.22 15:40:04 INFO - mozprofile == 0.28 15:40:04 INFO - mozrunner == 6.11 15:40:04 INFO - mozscreenshot == 0.1 15:40:04 INFO - mozsystemmonitor == 0.3 15:40:04 INFO - moztest == 0.7 15:40:04 INFO - mozversion == 1.4 15:40:04 INFO - psutil == 3.1.1 15:40:04 INFO - requests == 1.2.3 15:40:04 INFO - wsgiref == 0.1.2 15:40:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:04 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 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:40:04 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 15:40:04 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 15:40:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:40:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:04 INFO - 'HOME': '/Users/cltbld', 15:40:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:04 INFO - 'LOGNAME': 'cltbld', 15:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:04 INFO - 'MOZ_NO_REMOTE': '1', 15:40:04 INFO - 'NO_EM_RESTART': '1', 15:40:04 INFO - 'PAGER': '/bin/cat', 15:40:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:04 INFO - 'PWD': '/builds/slave/test', 15:40:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:04 INFO - 'SHELL': '/bin/bash', 15:40:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:40:04 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:40:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:04 INFO - 'USER': 'cltbld', 15:40:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:04 INFO - 'XPC_FLAGS': '0x0', 15:40:04 INFO - 'XPC_SERVICE_NAME': '0', 15:40:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 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)) 15:40:04 INFO - Cleaning up... 15:40:04 INFO - Return code: 0 15:40:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ef7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe02d84400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, '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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:40:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:40:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:04 INFO - 'HOME': '/Users/cltbld', 15:40:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:04 INFO - 'LOGNAME': 'cltbld', 15:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:04 INFO - 'MOZ_NO_REMOTE': '1', 15:40:04 INFO - 'NO_EM_RESTART': '1', 15:40:04 INFO - 'PAGER': '/bin/cat', 15:40:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:04 INFO - 'PWD': '/builds/slave/test', 15:40:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:04 INFO - 'SHELL': '/bin/bash', 15:40:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:40:04 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:40:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:04 INFO - 'USER': 'cltbld', 15:40:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:04 INFO - 'XPC_FLAGS': '0x0', 15:40:04 INFO - 'XPC_SERVICE_NAME': '0', 15:40:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:04 INFO - Cleaning up... 15:40:04 INFO - Return code: 0 15:40:04 INFO - Running post-action listener: _resource_record_post_action 15:40:04 INFO - Running post-action listener: _start_resource_monitoring 15:40:04 INFO - Starting resource monitoring. 15:40:04 INFO - ##### 15:40:04 INFO - ##### Finished create-virtualenv step (success) 15:40:04 INFO - ##### 15:40:04 INFO - ##### 15:40:04 INFO - ##### Running install step. 15:40:04 INFO - ##### 15:40:04 INFO - Running pre-action listener: _resource_record_pre_action 15:40:04 INFO - Running main action method: install 15:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:05 INFO - Reading from file tmpfile_stdout 15:40:05 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:05 INFO - Reading from file tmpfile_stdout 15:40:05 INFO - Output received: 15:40:05 INFO - Usage: mozinstall [options] installer 15:40:05 INFO - Options: 15:40:05 INFO - -h, --help show this help message and exit 15:40:05 INFO - -d DEST, --destination=DEST 15:40:05 INFO - Directory to install application into. [default: 15:40:05 INFO - "/builds/slave/test"] 15:40:05 INFO - --app=APP Application being installed. [default: firefox] 15:40:05 INFO - mkdir: /builds/slave/test/build/application 15:40:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:40:39 INFO - Reading from file tmpfile_stdout 15:40:39 INFO - Output received: 15:40:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:40:39 INFO - Running post-action listener: _resource_record_post_action 15:40:39 INFO - ##### 15:40:39 INFO - ##### Finished install step (success) 15:40:39 INFO - ##### 15:40:39 INFO - ##### 15:40:39 INFO - ##### Running run-tests step. 15:40:39 INFO - ##### 15:40:39 INFO - Running pre-action listener: _resource_record_pre_action 15:40:39 INFO - Running main action method: run_tests 15:40:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:40:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:40:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:40:39 INFO - 2016-07-05 15:40:39.475 screenresolution[1650:8033] starting screenresolution argv=screenresolution get 15:40:39 INFO - 2016-07-05 15:40:39.478 screenresolution[1650:8033] Display 0: 1600x1200x32@60 15:40:39 INFO - 2016-07-05 15:40:39.483 screenresolution[1651:8035] starting screenresolution argv=screenresolution list 15:40:39 INFO - Available Modes on Display 0 15:40:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:40:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:40:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:40:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:40:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:40:39 INFO - Intel Iris: 15:40:39 INFO - Chipset Model: Intel Iris 15:40:39 INFO - Type: GPU 15:40:39 INFO - Bus: Built-In 15:40:39 INFO - VRAM (Dynamic, Max): 1536 MB 15:40:39 INFO - Vendor: Intel (0x8086) 15:40:39 INFO - Device ID: 0x0a2e 15:40:39 INFO - Revision ID: 0x0009 15:40:39 INFO - Displays: 15:40:39 INFO - Display: 15:40:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:40:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:40:39 INFO - Display Serial Number: 1600x1200 60 15:40:39 INFO - Main Display: Yes 15:40:39 INFO - Mirror: Off 15:40:39 INFO - Online: Yes 15:40:39 INFO - Rotation: Supported 15:40:39 INFO - Return code: 0 15:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:39 INFO - Python 2.7.3 15:40:39 INFO - Return code: 0 15:40:39 INFO - grabbing minidump binary from tooltool 15:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e5bd30>, '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 0x106bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e53b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:40:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:40:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:40:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:39 INFO - Return code: 0 15:40:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:40:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:40:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 15:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 15:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.32H2J3Pf06/Render', 15:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:39 INFO - 'HOME': '/Users/cltbld', 15:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:39 INFO - 'LOGNAME': 'cltbld', 15:40:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:39 INFO - 'MOZ_NO_REMOTE': '1', 15:40:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:39 INFO - 'NO_EM_RESTART': '1', 15:40:39 INFO - 'PAGER': '/bin/cat', 15:40:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:39 INFO - 'PWD': '/builds/slave/test', 15:40:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:39 INFO - 'SHELL': '/bin/bash', 15:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sSrsXiu1Vw/Listeners', 15:40:39 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:39 INFO - 'USER': 'cltbld', 15:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:39 INFO - 'XPC_FLAGS': '0x0', 15:40:39 INFO - 'XPC_SERVICE_NAME': '0', 15:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 15:40:40 INFO - mozversion application_buildid: 20160705133123 15:40:40 INFO - mozversion application_changeset: dd8ef51f4920c5f4370afd0aebf43d1113363fc3 15:40:40 INFO - mozversion application_display_name: Nightly 15:40:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:40 INFO - mozversion application_name: Firefox 15:40:40 INFO - mozversion application_remotingname: firefox 15:40:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:40:40 INFO - mozversion application_vendor: Mozilla 15:40:40 INFO - mozversion application_version: 50.0a1 15:40:40 INFO - mozversion platform_buildid: 20160705133123 15:40:40 INFO - mozversion platform_changeset: dd8ef51f4920c5f4370afd0aebf43d1113363fc3 15:40:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:40:40 INFO - mozversion platform_version: 50.0a1 15:40:40 INFO - using testdate: 1467758440 15:40:40 INFO - actual date: 1467758440 15:40:40 INFO - starting webserver on 'localhost:49266' 15:40:40 INFO - SUITE-START | Running 2 tests 15:40:40 INFO - TEST-START | dromaeo_css 15:40:40 INFO - operating with platform_type : mac_ 15:40:40 INFO - Initialising browser for dromaeo_css test... 15:40:40 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpN1dTIE/profile http://localhost:49266/getInfo.html) 15:40:42 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 15:40:42 INFO - PROCESS | 1661 | colorDepth:24 15:40:42 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 15:40:42 INFO - PROCESS | 1661 | __metrics 15:40:42 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:40:42 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:40:43 INFO - TEST-INFO | 1661: exit 0 15:40:43 INFO - Browser initialized. 15:40:43 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:43 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpN1dTIE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:54 INFO - PROCESS | 1664 | RSS: Main: 243761152 15:40:54 INFO - PROCESS | 1664 | 15:41:47 INFO - PROCESS | 1664 | 1467758507132 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] 15:43:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 15:43:59 INFO - PROCESS | 1664 | RSS: Main: 372797440 15:43:59 INFO - PROCESS | 1664 | 15:47:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 15:47:02 INFO - PROCESS | 1664 | RSS: Main: 380551168 15:47:02 INFO - PROCESS | 1664 | 15:50:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 15:50:05 INFO - PROCESS | 1664 | RSS: Main: 372973568 15:50:05 INFO - PROCESS | 1664 | 15:53:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 15:53:08 INFO - PROCESS | 1664 | RSS: Main: 367382528 15:53:08 INFO - PROCESS | 1664 | 15:56:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 15:56:12 INFO - PROCESS | 1664 | RSS: Main: 338759680 15:56:12 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 15:59:16 INFO - PROCESS | 1664 | RSS: Main: 382717952 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | __start_tp_report 15:59:16 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:59:16 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:59:16 INFO - PROCESS | 1664 | |i|pagename|runs| 15:59:16 INFO - PROCESS | 1664 | |0;dojo.html;3327.4343361628526;3353.3963886500437;3354.4297469430203;3366.4108780963325;3381.949270760939;5205.791768329267;5350.411454739976;5605.831825045255;5651.660900345983;5686.658800471964;5735.340435849878;5747.453991870766;5816.680082595466;5861.589688721791;5875.647461152334;5122.897542049157;5126.564242039412;5197.48025197481;5208.713520756357;5250.7316105351765;4762.738049407281;5250.002499525078;5322.65402748822;5328.46715328468;5333.466653334674;5143.022825663112;5156.432792392834;5184.326037615124;5200.869978250536;5221.190715439113;5179.896402071975;5206.088934436496;5302.363716354023;5310.495502927216;5327.0145023170835;5159.5614372778355;5178.857980085928;5208.322918020633;5218.373795144569;5280.471952804727;5781.942180578225;5858.736356863908;5874.412558744134;5879.882402351972;5895.410458954113;6126.6017708848785;6228.190335256433;6242.812715618538;6271.498280137578;6349;6299.49604031681;6313.621182729005;6363.109164716943;6375.10748495211;6388.55280130391;5720.998825205573;5854.4730974212525;5861.238039054895;5906.675132867691;5925.970370148121;6264.812055638338;6323.810285691437;6337.651429171347;6345.555811093272;6345.809625711236;6237.688115594202;6244.625322480666;6247.187865577536;6254.530910181664;6260.749570017239;6194.163787888668;6197.845053873599;6330.68346582669;6334.714937827809;6362.968185159045;6156.984097623931;6225.62646241227;6226.906596400967;6247.562670613013;6317.747290108344;6314.842128946813;6321.304656487782;6340.080688300263;6344.302126766052;6350.936490635127;4874.5856602188505;4902.656814022984;4946.233333833262;4960.107180707508;5009.12340340439;4406.4051353066925;4414.028913638995;4438.533953934791;4448.288273876229;4457.086297309044;5829.387914269026;5835.474807267281;5839.416058394211;5847.298324201038;5934.851628709231;6179.16581261533;6195.256569211633;6236.126942228092;6321.462675672527;6361.363863613694;5819.621724587888;5846.386129456431;5848.824535263949;5856.1508581255475;5864.912026319608;4499.52754960731;5417.566594672496;5423.647462914898;5435.701036443033;5464.890702186013;6252.718627661766;6267.122602835698;6279.30927597973;6304.401081897213;6366.204224471941;6310.7791227306825;6316.747330106744;6337.746490140343;6338.207724034496;6339.144215530844;6383.457406120532;6397.488200944006;6410.4871610272;6416.165898433173;6418.711157997808;6089.69551522431;6124.167343420691;6159.260888693386;6192.59748116371;6262.185915830913;6134.263888333428;6281.15204447412;6317.873642527215;6333.683315834282;6343.5876668016435;5971.462568368867;6186.4741496973265;6192.040233763774;6204.348543402878;6205.937940620536;6104.732011601694;6109.327973922954;6123.755049797958;6155.7500573856805;6196.132541444292;6016.819495415145;6047.879042419215;6059.818205453844;6075.665838378932;6096.1465394845645;6034;6066.4540191382985;6156.107364432221;6189.412005859436;6202.81391558254;5948.8810223796145;5966.76132954677;5981.700914954322;6044.607100538451;6052.515798736003 15:59:16 INFO - PROCESS | 1664 | |1;ext.html;471.5331821496717;528.091682306433;531.537562320781;548.6406403805506;565.2453973944783;30637.38725225497;32109.35781284373;32125;32147.51778723321;41921;44089.559104408945;44821.551784482144;44836;44861.327080093826;44871.77564112183;9779.266555008368;9982.101610855021;9988.950055249714;9998.550065247045;10005.649802256887;8887.777805554848;9077.682281120162;9810.558524866397;9930.10629043386;12067.034637229;11913.642590722293;12241.38793060348;12272.938635306855;12279.754404911897;12312.13815032948;12104.576339828107;12181.269123852597;12188.390580470941;12201.145919785618;12220.755584888297;8774.780630484225;11794;12055.156139070268;12078.698032549257;12080.21478603888;42426.939326516775;42805.35791963123;42854.14291714179;42861;42900;3556.5376501054957;3898.4542164097047;4161.56303588122;4271.31658934569;4276.550962148961;20741.896290518453;21578.56842863134;25844.870775646003;33171.834140829385;33540.49689254663;3195.8082515049173;3299.769016168845;3304.256542277968;3328.334333133383;3343.029047296474;531848;535365;535399;535606;539274;60493.69753151206;61394.38605613976;61424;61516.69241653763;61558.69220653868;63300.68349658222;63407.36592634107;63443.6827815858;63489.68255158787;63589.046164307576;16826.831731682527;17058.57353566146;17084.91457542729;17093.145342732812;17101.828981710274;13172.736545269232;13234.272115033573;13245.735085298433;13254.668633284053;13266.336683165802;14133.363998620087;14362.712745745235;14388.784168237486;14578.198199099152;15723.97794143377;6335.809925702236;7226.241244669339;7232.421406287484;7258.419326453871;7284.198738138801;912.8539433690711;954.3033585482636;1009.7526106103904;1049.7585555322166;1087.282393620207;28869;30906.227344316572;31185.376292474477;31514.52728209079;31858.68141318557;1126.4761885723015;1146.9969805379167;1196.2702751321688;1199.9940000299944;1200.4117982188839;16146.515604531884;16222.351105955866;16309.51071467858;16315.1026693533;16741.91629041847;189779;190027;191890.04054979637;192189;192232.0388398049;51824;52737;58049.41950580524;59287.814243715744;59545.809083818946;1433.2332202271734;1455.919924404168;1471.2496626720235;1472.5729538433734;1473.9189344585936;29630.55554166646;31172.220694482818;32254.516182257754;32269.905694162888;32880.34239315248;47095.76452117854;51464;52076.95846083133;52122;52235;562041;566673;654250;663800;875830;39402.01494962534;40075;40339.596604034756;40388.1922361557;40403.98990025273;48158.5184148154;48539.5146048535;48649.513504865914;49209;49215.261771074176;39089.41365879571;39956.80021599874;40433.59566404416;40936;41607.959801005214;39911.40132897951;40090;40342.59657403389;40760.388594170516;41498.1700365997;38302;38659.806700966314;39013.41479877747;39038.219235615696;39231.80384098061;34317.31365372729;34376.96869093931;34906.47640285347;35195.82402087973;35209.47185792164;25139;25606.871965640054;25683.743162568884;25867.741322586535;25984.480310394065 15:59:16 INFO - PROCESS | 1664 | |2;jquery.html;361.5950135847851;384.75568014310915;388.97471664341816;393.1037235103962;412.6616174736715;15743.448979285728;17816.82183178168;21118.260860869872;21616.783832161673;21639.56720865582;20112.59774804503;21555.245566405178;21834.23580174694;21927.561448771015;22047.89760511972;5062.038212739586;5420.214068960008;5991.191189189469;6102.603330783485;6131.785387511438;5987.610805297663;6057.3336932937345;6099.847503812396;6101.664408457533;6108.053251745964;4706.411698537683;6019.067044608071;6116.052011938156;6122.877542449149;6187.412195841411;5910.822675319747;6091.664958427285;6111.633302001894;6140.171076904628;6147.631142131487;5895.911561326584;5921.052631578969;5923.644581325134;5930.970345148246;5942.732577034044;13695.589132326046;14058.85941140596;23442.296731098093;24231.757682423126;24308.54148751063;2214.933551993443;2248.8313376496826;2396;2450.130203777669;2880.3807181455877;10187.949060254725;10479.738006549896;10505.789884202273;13051.347432628425;14815.555533334018;1582.4065975259277;1820.5266630675987;1861.4973957031661;1875.6717574424422;1916.5879335942839;81591.59204203941;83067.5846620763;87025.56487217524;129498.99999999905;132116;11333.773324533464;11464.59873904426;11570.305781653127;11613.88386116145;11712.355820429788;12625.747485050248;14405.847532197398;14790.556283311518;14794.852051479347;14816.3332650031;8575.95712021436;8733.432326898732;8754.430961987451;8810.691625793066;8854.158854908774;7052.576845389293;8780.385373023955;8788.956055219684;8896.59965301563;8924.68763593271;3914.373700207953;4342.8708535780925;4507.4365704286965;4605.078984203105;4629.8148074077335;1608.6219738361476;1769.9203535840918;1861.8324350808493;1914.956348789913;1918.9904050479938;827.9047909490486;925.4956048953337;936.5551363102472;941.2140862379989;989.4211886046743;18277.63444731098;20294.79705202929;20794.79205207959;21793.67309490359;21799.346019619436;948.6869333120192;951.9671156794955;960.8558716192628;971.8056388722142;978.9608415663293;11202.439878006067;11548;11550.653480395602;11573.826392604118;11768.52925882955;242074;244182;245234;245466;581621;77539.1961480632;147909;151306;152540.2372988128;152741;986.9259805514472;996.4868092932146;1005.2762011351668;1017.9491025448846;1021.4484178543652;17912.820871791635;19216.61566768685;19521.804781952;19597.02014899278;19631.509212269808;57846.710766447584;58070;58097.12854307274;58499.15968057877;60011.69994150001;89096;89450;89733;90451.54774226087;90599.54700226456;8281.420300578902;8388.874166887379;8416.747497575083;8454.40819142654;8500.404971652173;9450.960394356753;13174.868251317364;13565.59303220905;13755.724885502434;13764.380602872694;7442.404607631268;9612.567434465325;10519.211059170746;11507.539698411969;11590;11105.777884442105;11381.54473821071;11424.714382140513;11487.774884619366;11691.240069395462;9954.303198776017;11836.585719499777;11844;12018.218815776947;12068.879311206776;8233.752987410388;8346.374021948257;8362.080171181044;8418.5790710463;8564.272036876935;10265.948670256601;11537.596184133516;11591.188616796744;11817.940910295394;11887.583934562248 15:59:16 INFO - PROCESS | 1664 | |3;mootools.html;1495.2523738130935;1549.666821633349;1646.6377396972662;1708.2312959168369;1888.5278680329918;8480.61837217326;8776.78058048549;8955.865662015074;8962.283017358612;8971.775705607362;7875.8424831503335;8137.511749295047;8469.153084691528;8679.392442529026;8749.650013999433;2365.763423657633;2396.628522579003;2400.099962514057;2401.4956859059616;2401.6637670726113;2373.679553260305;2384.95230095398;2417.16607770319;2420.286015625386;2423.503181847717;2110.4090854560673;2193.517426166241;2335.7313908900437;2402.411409204747;2409.638554216864;2375.3705268103968;2381.345130089222;2401.915932942356;2402.95194096118;2412.9276121716375;2379.58357287474;2381.20229723043;2401.363638635763;2404.2546810488802;2407.2778166549965;7702.768916932501;8910.732678019669;8991.685291014783;8997.505137217451;9010.684626038123;5224.08578498765;5349.732513374316;5391;5395.406505284415;5410.134378499461;3021.2597913511113;3491.3017396520545;3621.7645853019735;3644.453332000221;3665.816709164531;1072.8712554493434;1096.1011970184452;1099.9065079468255;1100.04845808376;1116.3413585984308;11790.823137652942;11804.586839460664;11811.22831575262;11813;11842.407879606071;4122.464079669624;4591.540845915381;4626.768661566908;4792.353032340659;4829.082474329936;9934.900650993397;9987.600495980225;9989.550470228856;9989.650362237287;10034.347767395097;5372.785088596413;5382;5384.703841288767;5395.919061214085;5401.000814849265;4793.18515852306;4937.35814344133;4947.158982972904;4947.554720075211;4995.575376093073;3192.4253634345578;3794.3739283017994;3831.214601006787;3885;3920.882373528799;1097.9066779323846;1118.765059337532;1161.821584529008;1175.3192934104313;1176.8705442401329;837.3050368194384;879.564615515324;886.2156991062827;930.8929473110544;975.1272611013104;6081.3614570470345;6224.657643829542;6260.655663938527;6317;6377.840553986095;778.4900889917113;964.9758756031156;974.3131092579714;1020.0411613083678;1094.195766111907;3978.4629075074495;4060.2894493463527;4160.3343465046055;4164.895877603084;4185.665146788249;10515;10689.144868410509;10691.41197234144;10775.568977240979;10782.191335649857;9765.853512197322;10718.517666705016;10747.086497647631;10748.140148788234;10752.139828813677;7934.2859142677435;8316.708915188061;8320.126386729427;8387.538685372241;8397.992240931006;6060.878782424415;6221.657808820558;6284.654344011124;6302.464290535356;6328.398802113792;10073.496325183565;10180.134688551556;10212.795744085226;10224.846627300447;10227.130693891102;9702.805943880941;10107.595696172071;10418.218633602359;10423.739406514898;10425.322354046963;7050.612216328152;7252.5648461092505;7291.380232680282;7297.306755858185;7298.96350518244;8377.204165604258;8397.034341050858;8486.321094312316;8493.490390576586;8503.319734421108;7483.363914067365;7510.962445187739;7709.113451953097;7732.613369331399;7847.136814950465;7397;7542.396608271215;7544.660490277841;7637;7734.8839767402405;7147.356737893615;7209.459290553124;7217.386522145676;7226.458015648742;7234.782956511313;4998.975005124951;5152.639315247897;5162.612804039787;5187.118189907649;5259.15853463444;5209.088409528242;5248.790048398021;5248.921266180934;5334.3865455473115;5381.515663590296 15:59:16 INFO - PROCESS | 1664 | |4;prototype.html;250.2854350828385;309.53106044342826;314.2301361663924;321.50969771099085;324.97725159238865;5229.450907654694;5280.917411930545;5322.760475778589;5324.5740340772745;5331.520163185312;4957.3059771632;5277.234800953859;5324.440933701963;5327.200919862013;5336.866578335544;3148.8425578721085;3634.7637403568815;3861.517310336208;4071.7149799514045;4077.062275676596;3965.524137103537;4034.253663072326;4047.4738284023038;4054.756714597119;4084.243775176673;3493.9825300873586;4026.8590599329027;4028.6374226319626;4033.878983630481;4047.1703300823406;3840.865569705061;3867.574566797664;4011.0947300032253;4018.618231268025;4037.8788636340955;3429.936719616927;3466.480027995796;3498.422760244582;3511.6129128993903;3538.610752817188;4467.419235499397;5199.402068762065;5216.252555393948;5217.895642087137;5218.921716174261;4057.7159598828093;4301.440812694361;4659.603933665642;4663.720176789404;4664.766761661904;4636.9536304637195;4803.615710743132;4845.53967373099;5575;5635.746391412396;3515.4726790981304;3675.5956844747056;3906.3945088511327;3911.9217615647526;3930.8820735377985;5560.527355174815;5569.275994120779;5826.621269617462;5834.3290521589715;5950.9702451487465;3279.1472817857834;3555.377808883487;3862.845486180521;3944.3097457944746;3954.090559171349;3386.5936087669143;3514.0687717754818;3602.675759181634;3782.8059725359226;4013.2776100301644;3998.5209170314592;4567.81728730847;4670.664667666167;4670.719756814602;4680.432055261525;4536.977315113403;5140.408852981955;5278.815241466607;5621.522170615462;5643.109558298016;1308.8756568126203;1333.693250552359;1334.2661536155076;1337.2444568818587;1344.8098432886966;813.6297984417076;820.913804050578;829.8423299573062;871.6513394642194;885.3183049052194;5154.433012368637;5171.50870667286;5171.844844654666;5197.7141257231215;5223.869403264873;5674.963894316521;5698.202251684767;5762.394948530427;5789.739461724233;5795.391483894215;4586.334981427674;4633.698809577367;4835.95164048355;5121.411037730709;5141.588672906233;2521.369657585604;2960.2534504642363;2968.7476564491826;2976.627921509811;2986.1190948670196;5819.6905696218855;5846.912296315559;5850.414958504116;5858.8242352729485;5902.4097590240635;5459.754311056012;5534.667919924817;5764.538836893039;5835.328937172195;5879.764809407661;5104.668196567211;5400.702961337164;5791.855203619859;5846.070796637099;5857.209276747584;5337.866553336043;5345.289507357608;5580.553555715452;5608.46719561641;5758.62568933018;4958.404991401055;4983.377077865267;5580.720963951705;5585.497305242548;5624.353199382123;5868.002439585195;5881.794137205177;5883.558733094949;5918.881622367442;5944.970275148769;3610.9458358125175;3826.426036094608;3850.7111966602056;3907.47249121365;3921.2941670499586;5076.6446348755235;5780.797672081457;5782.739776710142;5800.796972105956;5805.767769289181;4655.695430761794;5398.514133727984;5636.661800291996;5651.971740141437;5664.263645726194;4285.657147428261;5621.4659607337235;5646.774129034792;5668.319801623831;5720.856978575569;4778.562932904174;5216.817411390583;5420.078586640202;5511.724413779215;5590.161475778666;5465.754041068241;5590.8043218487155;5616.943830561642;5622.718864056863;5627.465390787868;4559.612432943237;4794.760261986957;5060.3927528696795;5498.80754173602;5516.862078448071 15:59:16 INFO - PROCESS | 1664 | |5;yui.html;742.5544673196083;753.118850944395;756.8486302739452;797.8882956386107;856.9700060497881;14941.402343906244;15923.92038039811;15969.520914372573;15970.600734981617;15972.680546389065;12339.876601233986;14631.268436578155;14634.121952682846;14657.340419681113;14676.559703208894;704.5068452083549;866.3199388480044;895.2166854002747;898.8966268879065;899.7975455522488;901.6168128545374;905.1355955062932;908.4594666173649;908.9818203635923;914.3736540469766;944.4286206844829;959.098447459386;961.4039295636715;962.74006018375;963.2582911158404;738.5088915870939;894.1639567004806;952.8142012307603;954.5609019850876;954.804265125651;697.4455308030128;697.6093387702903;697.8988046733195;700.110859208807;700.5096432497221;15358.155301458415;15452.613684657861;15475.07149571018;15475.226238688134;15494.147821869792;14549.34527946245;14809.777853332209;14834.406623735145;14884.404623815035;14891.553253402415;13463.394147263396;13474.393652285671;13576.864231357658;13627;13778.242196679179;4720.693154944952;4723.905521889577;4771.007566706485;4807.807687692523;4983.975080124613;121744;123536;124609.75390246072;126230;126795;9648.75878103039;11346.422933856566;11655.76688466226;11823.881761182278;12281.324527150913;23528;23816.88091559531;23872.925718342718;23908.521829563313;23911.28266152018;8062.717804876801;8137.674493020331;8177.223163799429;8238.923806153458;8397;4373.956260437419;4393.666983749079;4442.518257265517;4468.496404629511;4593.931091033637;3601.8019008954757;3732.1042949692423;3733.981330093332;3738.009427501714;3745.7190710696805;598.1476396135489;669.581511555278;678.2437582095928;692.9986169984325;693.2132030145821;612.963222206669;636.818506725581;650.2424675253371;652.5544332544905;654.2606854254661;8890.688825891062;8945.46327220369;9021.639134434548;9109.90890091104;9272.72181834546;540.8431554849128;602.3524710935761;604.6462819250787;607.7690477618477;620.8789286089169;4027.073773032219;4316.1583491219535;4434.224010798098;4469.709468884512;4478.2386994211065;89531.76170595657;89920.10079899248;90145;152366;155312;113423;124707;132586;132963.33518332348;133902;88118;107036;109175;110330;111811.44094279477;7236.985730446203;7920.485168464032;7988.760337189894;8545.786355341166;8763.211310982042;50731.74634126806;61755.38244617496;62932.68533657303;64011;64054;86829.56585217033;90044;90912;92482;94634;13472.79790803119;13769.793453098011;13794.586162415144;13954.372053257424;14428.639284017983;14720.263986800832;14909.627259318604;15007.77488337654;15559.222038898237;15657.686846262783;14018.22899740524;14049.367778450198;14163.787543186654;14192.148471091768;14212;14085.08446950945;14256.572302830931;14504.637384065483;14764.557063288119;14790.63023424423;13140.80288795688;13615.659608509866;13722.313884305546;13735.45058197661;13863.722725545635;11387.089032877515;11576;11958.512492163183;11959.641210763692;12283.550319290913;11333.603323883954;11652.592159274385;11703.648890533297;11758.647240582795;11780.941095294469 15:59:16 INFO - PROCESS | 1664 | __end_tp_report 15:59:16 INFO - PROCESS | 1664 | __start_cc_report 15:59:16 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1391 15:59:16 INFO - PROCESS | 1664 | __end_cc_report 15:59:16 INFO - PROCESS | 1664 | __startTimestamp1467759556428__endTimestamp 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:59:16 INFO - PROCESS | 1664 | Number of tests: 6 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5786.84 Median:6047.88 stddev:668.10 (11.0%) stddev-sans-first:641.69 15:59:16 INFO - PROCESS | 1664 | Values: 3327.4 3353.4 3354.4 3366.4 3381.9 5205.8 5350.4 5605.8 5651.7 5686.7 5735.3 5747.5 5816.7 5861.6 5875.6 5122.9 5126.6 5197.5 5208.7 5250.7 4762.7 5250.0 5322.7 5328.5 5333.5 5143.0 5156.4 5184.3 5200.9 5221.2 5179.9 5206.1 5302.4 5310.5 5327.0 5159.6 5178.9 5208.3 5218.4 5280.5 5781.9 5858.7 5874.4 5879.9 5895.4 6126.6 6228.2 6242.8 6271.5 6349.0 6299.5 6313.6 6363.1 6375.1 6388.6 5721.0 5854.5 5861.2 5906.7 5926.0 6264.8 6323.8 6337.7 6345.6 6345.8 6237.7 6244.6 6247.2 6254.5 6260.7 6194.2 6197.8 6330.7 6334.7 6363.0 6157.0 6225.6 6226.9 6247.6 6317.7 6314.8 6321.3 6340.1 6344.3 6350.9 4874.6 4902.7 4946.2 4960.1 5009.1 4406.4 4414.0 4438.5 4448.3 4457.1 5829.4 5835.5 5839.4 5847.3 5934.9 6179.2 6195.3 6236.1 6321.5 6361.4 5819.6 5846.4 5848.8 5856.2 5864.9 4499.5 5417.6 5423.6 5435.7 5464.9 6252.7 6267.1 6279.3 6304.4 6366.2 6310.8 6316.7 6337.7 6338.2 6339.1 6383.5 6397.5 6410.5 6416.2 6418.7 6089.7 6124.2 6159.3 6192.6 6262.2 6134.3 6281.2 6317.9 6333.7 6343.6 5971.5 6186.5 6192.0 6204.3 6205.9 6104.7 6109.3 6123.8 6155.8 6196.1 6016.8 6047.9 6059.8 6075.7 6096.1 6034.0 6066.5 6156.1 6189.4 6202.8 5948.9 5966.8 5981.7 6044.6 6052.5 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:62182.13 Median:21279.13 stddev:137032.40 (644.0%) stddev-sans-first:137338.48 15:59:16 INFO - PROCESS | 1664 | Values: 471.5 528.1 531.5 548.6 565.2 30637.4 32109.4 32125.0 32147.5 41921.0 44089.6 44821.6 44836.0 44861.3 44871.8 9779.3 9982.1 9989.0 9998.6 10005.6 8887.8 9077.7 9810.6 9930.1 12067.0 11913.6 12241.4 12272.9 12279.8 12312.1 12104.6 12181.3 12188.4 12201.1 12220.8 8774.8 11794.0 12055.2 12078.7 12080.2 42426.9 42805.4 42854.1 42861.0 42900.0 3556.5 3898.5 4161.6 4271.3 4276.6 20741.9 21578.6 25844.9 33171.8 33540.5 3195.8 3299.8 3304.3 3328.3 3343.0 531848.0 535365.0 535399.0 535606.0 539274.0 60493.7 61394.4 61424.0 61516.7 61558.7 63300.7 63407.4 63443.7 63489.7 63589.0 16826.8 17058.6 17084.9 17093.1 17101.8 13172.7 13234.3 13245.7 13254.7 13266.3 14133.4 14362.7 14388.8 14578.2 15724.0 6335.8 7226.2 7232.4 7258.4 7284.2 912.9 954.3 1009.8 1049.8 1087.3 28869.0 30906.2 31185.4 31514.5 31858.7 1126.5 1147.0 1196.3 1200.0 1200.4 16146.5 16222.4 16309.5 16315.1 16741.9 189779.0 190027.0 191890.0 192189.0 192232.0 51824.0 52737.0 58049.4 59287.8 59545.8 1433.2 1455.9 1471.2 1472.6 1473.9 29630.6 31172.2 32254.5 32269.9 32880.3 47095.8 51464.0 52077.0 52122.0 52235.0 562041.0 566673.0 654250.0 663800.0 875830.0 39402.0 40075.0 40339.6 40388.2 40404.0 48158.5 48539.5 48649.5 49209.0 49215.3 39089.4 39956.8 40433.6 40936.0 41608.0 39911.4 40090.0 40342.6 40760.4 41498.2 38302.0 38659.8 39013.4 39038.2 39231.8 34317.3 34377.0 34906.5 35195.8 35209.5 25139.0 25606.9 25683.7 25867.7 25984.5 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:27327.54 Median:123842.07 stddev:61269.47 (49.5%) stddev-sans-first:61407.90 15:59:16 INFO - PROCESS | 1664 | Values: 361.6 384.8 389.0 393.1 412.7 15743.4 17816.8 21118.3 21616.8 21639.6 20112.6 21555.2 21834.2 21927.6 22047.9 5062.0 5420.2 5991.2 6102.6 6131.8 5987.6 6057.3 6099.8 6101.7 6108.1 4706.4 6019.1 6116.1 6122.9 6187.4 5910.8 6091.7 6111.6 6140.2 6147.6 5895.9 5921.1 5923.6 5931.0 5942.7 13695.6 14058.9 23442.3 24231.8 24308.5 2214.9 2248.8 2396.0 2450.1 2880.4 10187.9 10479.7 10505.8 13051.3 14815.6 1582.4 1820.5 1861.5 1875.7 1916.6 81591.6 83067.6 87025.6 129499.0 132116.0 11333.8 11464.6 11570.3 11613.9 11712.4 12625.7 14405.8 14790.6 14794.9 14816.3 8576.0 8733.4 8754.4 8810.7 8854.2 7052.6 8780.4 8789.0 8896.6 8924.7 3914.4 4342.9 4507.4 4605.1 4629.8 1608.6 1769.9 1861.8 1915.0 1919.0 827.9 925.5 936.6 941.2 989.4 18277.6 20294.8 20794.8 21793.7 21799.3 948.7 952.0 960.9 971.8 979.0 11202.4 11548.0 11550.7 11573.8 11768.5 242074.0 244182.0 245234.0 245466.0 581621.0 77539.2 147909.0 151306.0 152540.2 152741.0 986.9 996.5 1005.3 1017.9 1021.4 17912.8 19216.6 19521.8 19597.0 19631.5 57846.7 58070.0 58097.1 58499.2 60011.7 89096.0 89450.0 89733.0 90451.5 90599.5 8281.4 8388.9 8416.7 8454.4 8500.4 9451.0 13174.9 13565.6 13755.7 13764.4 7442.4 9612.6 10519.2 11507.5 11590.0 11105.8 11381.5 11424.7 11487.8 11691.2 9954.3 11836.6 11844.0 12018.2 12068.9 8233.8 8346.4 8362.1 8418.6 8564.3 10265.9 11537.6 11591.2 11817.9 11887.6 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5775.62 Median:4997.28 stddev:3219.69 (64.4%) stddev-sans-first:3212.65 15:59:16 INFO - PROCESS | 1664 | Values: 1495.3 1549.7 1646.6 1708.2 1888.5 8480.6 8776.8 8955.9 8962.3 8971.8 7875.8 8137.5 8469.2 8679.4 8749.7 2365.8 2396.6 2400.1 2401.5 2401.7 2373.7 2385.0 2417.2 2420.3 2423.5 2110.4 2193.5 2335.7 2402.4 2409.6 2375.4 2381.3 2401.9 2403.0 2412.9 2379.6 2381.2 2401.4 2404.3 2407.3 7702.8 8910.7 8991.7 8997.5 9010.7 5224.1 5349.7 5391.0 5395.4 5410.1 3021.3 3491.3 3621.8 3644.5 3665.8 1072.9 1096.1 1099.9 1100.0 1116.3 11790.8 11804.6 11811.2 11813.0 11842.4 4122.5 4591.5 4626.8 4792.4 4829.1 9934.9 9987.6 9989.6 9989.7 10034.3 5372.8 5382.0 5384.7 5395.9 5401.0 4793.2 4937.4 4947.2 4947.6 4995.6 3192.4 3794.4 3831.2 3885.0 3920.9 1097.9 1118.8 1161.8 1175.3 1176.9 837.3 879.6 886.2 930.9 975.1 6081.4 6224.7 6260.7 6317.0 6377.8 778.5 965.0 974.3 1020.0 1094.2 3978.5 4060.3 4160.3 4164.9 4185.7 10515.0 10689.1 10691.4 10775.6 10782.2 9765.9 10718.5 10747.1 10748.1 10752.1 7934.3 8316.7 8320.1 8387.5 8398.0 6060.9 6221.7 6284.7 6302.5 6328.4 10073.5 10180.1 10212.8 10224.8 10227.1 9702.8 10107.6 10418.2 10423.7 10425.3 7050.6 7252.6 7291.4 7297.3 7299.0 8377.2 8397.0 8486.3 8493.5 8503.3 7483.4 7511.0 7709.1 7732.6 7847.1 7397.0 7542.4 7544.7 7637.0 7734.9 7147.4 7209.5 7217.4 7226.5 7234.8 4999.0 5152.6 5162.6 5187.1 5259.2 5209.1 5248.8 5248.9 5334.4 5381.5 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4509.25 Median:5141.00 stddev:1396.06 (27.2%) stddev-sans-first:1362.88 15:59:16 INFO - PROCESS | 1664 | Values: 250.3 309.5 314.2 321.5 325.0 5229.5 5280.9 5322.8 5324.6 5331.5 4957.3 5277.2 5324.4 5327.2 5336.9 3148.8 3634.8 3861.5 4071.7 4077.1 3965.5 4034.3 4047.5 4054.8 4084.2 3494.0 4026.9 4028.6 4033.9 4047.2 3840.9 3867.6 4011.1 4018.6 4037.9 3429.9 3466.5 3498.4 3511.6 3538.6 4467.4 5199.4 5216.3 5217.9 5218.9 4057.7 4301.4 4659.6 4663.7 4664.8 4637.0 4803.6 4845.5 5575.0 5635.7 3515.5 3675.6 3906.4 3911.9 3930.9 5560.5 5569.3 5826.6 5834.3 5951.0 3279.1 3555.4 3862.8 3944.3 3954.1 3386.6 3514.1 3602.7 3782.8 4013.3 3998.5 4567.8 4670.7 4670.7 4680.4 4537.0 5140.4 5278.8 5621.5 5643.1 1308.9 1333.7 1334.3 1337.2 1344.8 813.6 820.9 829.8 871.7 885.3 5154.4 5171.5 5171.8 5197.7 5223.9 5675.0 5698.2 5762.4 5789.7 5795.4 4586.3 4633.7 4836.0 5121.4 5141.6 2521.4 2960.3 2968.7 2976.6 2986.1 5819.7 5846.9 5850.4 5858.8 5902.4 5459.8 5534.7 5764.5 5835.3 5879.8 5104.7 5400.7 5791.9 5846.1 5857.2 5337.9 5345.3 5580.6 5608.5 5758.6 4958.4 4983.4 5580.7 5585.5 5624.4 5868.0 5881.8 5883.6 5918.9 5945.0 3610.9 3826.4 3850.7 3907.5 3921.3 5076.6 5780.8 5782.7 5800.8 5805.8 4655.7 5398.5 5636.7 5652.0 5664.3 4285.7 5621.5 5646.8 5668.3 5720.9 4778.6 5216.8 5420.1 5511.7 5590.2 5465.8 5590.8 5616.9 5622.7 5627.5 4559.6 4794.8 5060.4 5498.8 5516.9 15:59:16 INFO - PROCESS | 1664 | 15:59:16 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:24385.22 Median:4171.62 stddev:37849.70 (907.3%) stddev-sans-first:37914.24 15:59:16 INFO - PROCESS | 1664 | Values: 742.6 753.1 756.8 797.9 857.0 14941.4 15923.9 15969.5 15970.6 15972.7 12339.9 14631.3 14634.1 14657.3 14676.6 704.5 866.3 895.2 898.9 899.8 901.6 905.1 908.5 909.0 914.4 944.4 959.1 961.4 962.7 963.3 738.5 894.2 952.8 954.6 954.8 697.4 697.6 697.9 700.1 700.5 15358.2 15452.6 15475.1 15475.2 15494.1 14549.3 14809.8 14834.4 14884.4 14891.6 13463.4 13474.4 13576.9 13627.0 13778.2 4720.7 4723.9 4771.0 4807.8 4984.0 121744.0 123536.0 124609.8 126230.0 126795.0 9648.8 11346.4 11655.8 11823.9 12281.3 23528.0 23816.9 23872.9 23908.5 23911.3 8062.7 8137.7 8177.2 8238.9 8397.0 4374.0 4393.7 4442.5 4468.5 4593.9 3601.8 3732.1 3734.0 3738.0 3745.7 598.1 669.6 678.2 693.0 693.2 613.0 636.8 650.2 652.6 654.3 8890.7 8945.5 9021.6 9109.9 9272.7 540.8 602.4 604.6 607.8 620.9 4027.1 4316.2 4434.2 4469.7 4478.2 89531.8 89920.1 90145.0 152366.0 155312.0 113423.0 124707.0 132586.0 132963.3 133902.0 88118.0 107036.0 109175.0 110330.0 111811.4 7237.0 7920.5 7988.8 8545.8 8763.2 50731.7 61755.4 62932.7 64011.0 64054.0 86829.6 90044.0 90912.0 92482.0 94634.0 13472.8 13769.8 13794.6 13954.4 14428.6 14720.3 14909.6 15007.8 15559.2 15657.7 14018.2 14049.4 14163.8 14192.1 14212.0 14085.1 14256.6 14504.6 14764.6 14790.6 13140.8 13615.7 13722.3 13735.5 13863.7 11387.1 11576.0 11958.5 11959.6 12283.6 11333.6 11652.6 11703.6 11758.6 11780.9 15:59:16 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:59:16 INFO - PROCESS | 1664 | 15:59:17 INFO - TEST-INFO | 1664: exit 0 15:59:17 INFO - TEST-OK | dromaeo_css | took 1116978ms 15:59:17 INFO - TEST-START | kraken 15:59:17 INFO - operating with platform_type : mac_ 15:59:17 INFO - Initialising browser for kraken test... 15:59:17 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp0MhvAQ/profile http://localhost:49266/getInfo.html) 15:59:19 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 15:59:19 INFO - PROCESS | 1670 | colorDepth:24 15:59:19 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 15:59:19 INFO - PROCESS | 1670 | __metrics 15:59:19 INFO - PROCESS | 1670 | 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" 15:59:19 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:20 INFO - TEST-INFO | 1670: exit 0 15:59:20 INFO - Browser initialized. 15:59:20 INFO - Running cycle 1/1 for kraken test... 15:59:20 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp0MhvAQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:32 INFO - PROCESS | 1671 | RSS: Main: 244805632 15:59:32 INFO - PROCESS | 1671 | 15:59:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:35 INFO - PROCESS | 1671 | RSS: Main: 293982208 15:59:35 INFO - PROCESS | 1671 | 15:59:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 15:59:39 INFO - PROCESS | 1671 | RSS: Main: 304205824 15:59:39 INFO - PROCESS | 1671 | 15:59:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 15:59:43 INFO - PROCESS | 1671 | RSS: Main: 389931008 15:59:43 INFO - PROCESS | 1671 | 15:59:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 15:59:45 INFO - PROCESS | 1671 | RSS: Main: 290140160 15:59:45 INFO - PROCESS | 1671 | 15:59:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:48 INFO - PROCESS | 1671 | RSS: Main: 293945344 15:59:48 INFO - PROCESS | 1671 | 15:59:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:51 INFO - PROCESS | 1671 | RSS: Main: 330248192 15:59:51 INFO - PROCESS | 1671 | 15:59:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:55 INFO - PROCESS | 1671 | RSS: Main: 332800000 15:59:55 INFO - PROCESS | 1671 | 15:59:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 15:59:58 INFO - PROCESS | 1671 | RSS: Main: 330506240 15:59:58 INFO - PROCESS | 1671 | 16:00:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:00:00 INFO - PROCESS | 1671 | RSS: Main: 311414784 16:00:00 INFO - PROCESS | 1671 | 16:00:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:00:03 INFO - PROCESS | 1671 | RSS: Main: 318590976 16:00:03 INFO - PROCESS | 1671 | 16:00:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:00:06 INFO - PROCESS | 1671 | RSS: Main: 316395520 16:00:06 INFO - PROCESS | 1671 | 16:00:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:00:09 INFO - PROCESS | 1671 | RSS: Main: 324923392 16:00:09 INFO - PROCESS | 1671 | 16:00:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:13 INFO - PROCESS | 1671 | RSS: Main: 326520832 16:00:13 INFO - PROCESS | 1671 | 16:00:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 16:00:15 INFO - PROCESS | 1671 | RSS: Main: 370221056 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | __start_tp_report 16:00:15 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:00:15 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:00:15 INFO - PROCESS | 1671 | |i|pagename|runs| 16:00:15 INFO - PROCESS | 1671 | |0;ai-astar;102;112;111;113;113;112;111;112;112;111 16:00:15 INFO - PROCESS | 1671 | |1;audio-beat-detection;146;113;108;155;108;107;142;135;175;107 16:00:15 INFO - PROCESS | 1671 | |2;audio-dft;182;182;182;180;185;184;179;179;180;182 16:00:15 INFO - PROCESS | 1671 | |3;audio-fft;80;77;57;107;58;57;62;66;96;57 16:00:15 INFO - PROCESS | 1671 | |4;audio-oscillator;83;84;91;71;71;67;99;69;68;96 16:00:15 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;86;83;87;83;86;84;86;83;86;83 16:00:15 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;91;93;92;91;92;92;92;92;91 16:00:15 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;81;86;85;86;81;76;125;130;117 16:00:15 INFO - PROCESS | 1671 | |8;json-parse-financial;56;56;57;56;56;56;55;53;53;54 16:00:15 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;66;75;76;72;70;71;68;69;70;68 16:00:15 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;97;90;101;102;78;82;84;85;85;81 16:00:15 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;134;137;135;136;131;136;135;135;178;137 16:00:15 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;163;163;163;163;164;164;164;163;163;164 16:00:15 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;66;66;67;66;65;67;68;66;65 16:00:15 INFO - PROCESS | 1671 | __end_tp_report 16:00:15 INFO - PROCESS | 1671 | __start_cc_report 16:00:15 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1974 16:00:15 INFO - PROCESS | 1671 | __end_cc_report 16:00:15 INFO - PROCESS | 1671 | __startTimestamp1467759615135__endTimestamp 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:00:15 INFO - PROCESS | 1671 | Number of tests: 14 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.21 (2.9%) stddev-sans-first:0.78 16:00:15 INFO - PROCESS | 1671 | Values: 102.0 112.0 111.0 113.0 113.0 112.0 111.0 112.0 112.0 111.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:138.50 stddev:24.47 (17.7%) stddev-sans-first:25.22 16:00:15 INFO - PROCESS | 1671 | Values: 146.0 113.0 108.0 155.0 108.0 107.0 142.0 135.0 175.0 107.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:181.50 Median:182.00 stddev:2.01 (1.1%) stddev-sans-first:2.13 16:00:15 INFO - PROCESS | 1671 | Values: 182.0 182.0 182.0 180.0 185.0 184.0 179.0 179.0 180.0 182.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:71.70 Median:64.00 stddev:17.94 (28.0%) stddev-sans-first:18.77 16:00:15 INFO - PROCESS | 1671 | Values: 80.0 77.0 57.0 107.0 58.0 57.0 62.0 66.0 96.0 57.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:83.50 stddev:12.29 (14.7%) stddev-sans-first:12.98 16:00:15 INFO - PROCESS | 1671 | Values: 83.0 84.0 91.0 71.0 71.0 67.0 99.0 69.0 68.0 96.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 16:00:15 INFO - PROCESS | 1671 | Values: 86.0 83.0 87.0 83.0 86.0 84.0 86.0 83.0 86.0 83.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:91.80 Median:92.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:00:15 INFO - PROCESS | 1671 | Values: 92.0 91.0 93.0 92.0 91.0 92.0 92.0 92.0 92.0 91.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:83.00 stddev:20.34 (24.5%) stddev-sans-first:21.24 16:00:15 INFO - PROCESS | 1671 | Values: 85.0 81.0 86.0 85.0 86.0 81.0 76.0 125.0 130.0 117.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.40 (2.5%) stddev-sans-first:1.45 16:00:15 INFO - PROCESS | 1671 | Values: 56.0 56.0 57.0 56.0 56.0 56.0 55.0 53.0 53.0 54.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:3.14 (4.4%) stddev-sans-first:2.87 16:00:15 INFO - PROCESS | 1671 | Values: 66.0 75.0 76.0 72.0 70.0 71.0 68.0 69.0 70.0 68.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:84.50 stddev:8.61 (10.2%) stddev-sans-first:8.56 16:00:15 INFO - PROCESS | 1671 | Values: 97.0 90.0 101.0 102.0 78.0 82.0 84.0 85.0 85.0 81.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:136.00 stddev:13.67 (10.1%) stddev-sans-first:14.36 16:00:15 INFO - PROCESS | 1671 | Values: 134.0 137.0 135.0 136.0 131.0 136.0 135.0 135.0 178.0 137.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:163.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 16:00:15 INFO - PROCESS | 1671 | Values: 163.0 163.0 163.0 163.0 164.0 164.0 164.0 163.0 163.0 164.0 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 16:00:15 INFO - PROCESS | 1671 | Values: 66.0 66.0 66.0 67.0 66.0 65.0 67.0 68.0 66.0 65.0 16:00:15 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:00:15 INFO - PROCESS | 1671 | 16:00:15 INFO - TEST-INFO | 1671: exit 0 16:00:15 INFO - TEST-OK | kraken | took 58889ms 16:00:15 INFO - SUITE-END | took 1175s 16:00:16 INFO - Completed test suite (00:19:36) 16:00:16 INFO - javascript benchmark 16:00:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.4343361628526, 3353.3963886500437, 3354.4297469430203, 3366.4108780963325, 3381.949270760939, 5205.791768329267, 5350.411454739976, 5605.831825045255, 5651.660900345983, 5686.658800471964, 5735.340435849878, 5747.453991870766, 5816.680082595466, 5861.589688721791, 5875.647461152334, 5122.897542049157, 5126.564242039412, 5197.48025197481, 5208.713520756357, 5250.7316105351765, 4762.738049407281, 5250.002499525078, 5322.65402748822, 5328.46715328468, 5333.466653334674, 5143.022825663112, 5156.432792392834, 5184.326037615124, 5200.869978250536, 5221.190715439113, 5179.896402071975, 5206.088934436496, 5302.363716354023, 5310.495502927216, 5327.0145023170835, 5159.5614372778355, 5178.857980085928, 5208.322918020633, 5218.373795144569, 5280.471952804727, 5781.942180578225, 5858.736356863908, 5874.412558744134, 5879.882402351972, 5895.410458954113, 6126.6017708848785, 6228.190335256433, 6242.812715618538, 6271.498280137578, 6349.0, 6299.49604031681, 6313.621182729005, 6363.109164716943, 6375.10748495211, 6388.55280130391, 5720.998825205573, 5854.4730974212525, 5861.238039054895, 5906.675132867691, 5925.970370148121, 6264.812055638338, 6323.810285691437, 6337.651429171347, 6345.555811093272, 6345.809625711236, 6237.688115594202, 6244.625322480666, 6247.187865577536, 6254.530910181664, 6260.749570017239, 6194.163787888668, 6197.845053873599, 6330.68346582669, 6334.714937827809, 6362.968185159045, 6156.984097623931, 6225.62646241227, 6226.906596400967, 6247.562670613013, 6317.747290108344, 6314.842128946813, 6321.304656487782, 6340.080688300263, 6344.302126766052, 6350.936490635127, 4874.5856602188505, 4902.656814022984, 4946.233333833262, 4960.107180707508, 5009.12340340439, 4406.4051353066925, 4414.028913638995, 4438.533953934791, 4448.288273876229, 4457.086297309044, 5829.387914269026, 5835.474807267281, 5839.416058394211, 5847.298324201038, 5934.851628709231, 6179.16581261533, 6195.256569211633, 6236.126942228092, 6321.462675672527, 6361.363863613694, 5819.621724587888, 5846.386129456431, 5848.824535263949, 5856.1508581255475, 5864.912026319608, 4499.52754960731, 5417.566594672496, 5423.647462914898, 5435.701036443033, 5464.890702186013, 6252.718627661766, 6267.122602835698, 6279.30927597973, 6304.401081897213, 6366.204224471941, 6310.7791227306825, 6316.747330106744, 6337.746490140343, 6338.207724034496, 6339.144215530844, 6383.457406120532, 6397.488200944006, 6410.4871610272, 6416.165898433173, 6418.711157997808, 6089.69551522431, 6124.167343420691, 6159.260888693386, 6192.59748116371, 6262.185915830913, 6134.263888333428, 6281.15204447412, 6317.873642527215, 6333.683315834282, 6343.5876668016435, 5971.462568368867, 6186.4741496973265, 6192.040233763774, 6204.348543402878, 6205.937940620536, 6104.732011601694, 6109.327973922954, 6123.755049797958, 6155.7500573856805, 6196.132541444292, 6016.819495415145, 6047.879042419215, 6059.818205453844, 6075.665838378932, 6096.1465394845645, 6034.0, 6066.4540191382985, 6156.107364432221, 6189.412005859436, 6202.81391558254, 5948.8810223796145, 5966.76132954677, 5981.700914954322, 6044.607100538451, 6052.515798736003], "value": 5742.102062521509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.5331821496717, 528.091682306433, 531.537562320781, 548.6406403805506, 565.2453973944783, 30637.38725225497, 32109.35781284373, 32125.0, 32147.51778723321, 41921.0, 44089.559104408945, 44821.551784482144, 44836.0, 44861.327080093826, 44871.77564112183, 9779.266555008368, 9982.101610855021, 9988.950055249714, 9998.550065247045, 10005.649802256887, 8887.777805554848, 9077.682281120162, 9810.558524866397, 9930.10629043386, 12067.034637229, 11913.642590722293, 12241.38793060348, 12272.938635306855, 12279.754404911897, 12312.13815032948, 12104.576339828107, 12181.269123852597, 12188.390580470941, 12201.145919785618, 12220.755584888297, 8774.780630484225, 11794.0, 12055.156139070268, 12078.698032549257, 12080.21478603888, 42426.939326516775, 42805.35791963123, 42854.14291714179, 42861.0, 42900.0, 3556.5376501054957, 3898.4542164097047, 4161.56303588122, 4271.31658934569, 4276.550962148961, 20741.896290518453, 21578.56842863134, 25844.870775646003, 33171.834140829385, 33540.49689254663, 3195.8082515049173, 3299.769016168845, 3304.256542277968, 3328.334333133383, 3343.029047296474, 531848.0, 535365.0, 535399.0, 535606.0, 539274.0, 60493.69753151206, 61394.38605613976, 61424.0, 61516.69241653763, 61558.69220653868, 63300.68349658222, 63407.36592634107, 63443.6827815858, 63489.68255158787, 63589.046164307576, 16826.831731682527, 17058.57353566146, 17084.91457542729, 17093.145342732812, 17101.828981710274, 13172.736545269232, 13234.272115033573, 13245.735085298433, 13254.668633284053, 13266.336683165802, 14133.363998620087, 14362.712745745235, 14388.784168237486, 14578.198199099152, 15723.97794143377, 6335.809925702236, 7226.241244669339, 7232.421406287484, 7258.419326453871, 7284.198738138801, 912.8539433690711, 954.3033585482636, 1009.7526106103904, 1049.7585555322166, 1087.282393620207, 28869.0, 30906.227344316572, 31185.376292474477, 31514.52728209079, 31858.68141318557, 1126.4761885723015, 1146.9969805379167, 1196.2702751321688, 1199.9940000299944, 1200.4117982188839, 16146.515604531884, 16222.351105955866, 16309.51071467858, 16315.1026693533, 16741.91629041847, 189779.0, 190027.0, 191890.04054979637, 192189.0, 192232.0388398049, 51824.0, 52737.0, 58049.41950580524, 59287.814243715744, 59545.809083818946, 1433.2332202271734, 1455.919924404168, 1471.2496626720235, 1472.5729538433734, 1473.9189344585936, 29630.55554166646, 31172.220694482818, 32254.516182257754, 32269.905694162888, 32880.34239315248, 47095.76452117854, 51464.0, 52076.95846083133, 52122.0, 52235.0, 562041.0, 566673.0, 654250.0, 663800.0, 875830.0, 39402.01494962534, 40075.0, 40339.596604034756, 40388.1922361557, 40403.98990025273, 48158.5184148154, 48539.5146048535, 48649.513504865914, 49209.0, 49215.261771074176, 39089.41365879571, 39956.80021599874, 40433.59566404416, 40936.0, 41607.959801005214, 39911.40132897951, 40090.0, 40342.59657403389, 40760.388594170516, 41498.1700365997, 38302.0, 38659.806700966314, 39013.41479877747, 39038.219235615696, 39231.80384098061, 34317.31365372729, 34376.96869093931, 34906.47640285347, 35195.82402087973, 35209.47185792164, 25139.0, 25606.871965640054, 25683.743162568884, 25867.741322586535, 25984.480310394065], "value": 20268.94127410338, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.5950135847851, 384.75568014310915, 388.97471664341816, 393.1037235103962, 412.6616174736715, 15743.448979285728, 17816.82183178168, 21118.260860869872, 21616.783832161673, 21639.56720865582, 20112.59774804503, 21555.245566405178, 21834.23580174694, 21927.561448771015, 22047.89760511972, 5062.038212739586, 5420.214068960008, 5991.191189189469, 6102.603330783485, 6131.785387511438, 5987.610805297663, 6057.3336932937345, 6099.847503812396, 6101.664408457533, 6108.053251745964, 4706.411698537683, 6019.067044608071, 6116.052011938156, 6122.877542449149, 6187.412195841411, 5910.822675319747, 6091.664958427285, 6111.633302001894, 6140.171076904628, 6147.631142131487, 5895.911561326584, 5921.052631578969, 5923.644581325134, 5930.970345148246, 5942.732577034044, 13695.589132326046, 14058.85941140596, 23442.296731098093, 24231.757682423126, 24308.54148751063, 2214.933551993443, 2248.8313376496826, 2396.0, 2450.130203777669, 2880.3807181455877, 10187.949060254725, 10479.738006549896, 10505.789884202273, 13051.347432628425, 14815.555533334018, 1582.4065975259277, 1820.5266630675987, 1861.4973957031661, 1875.6717574424422, 1916.5879335942839, 81591.59204203941, 83067.5846620763, 87025.56487217524, 129498.99999999905, 132116.0, 11333.773324533464, 11464.59873904426, 11570.305781653127, 11613.88386116145, 11712.355820429788, 12625.747485050248, 14405.847532197398, 14790.556283311518, 14794.852051479347, 14816.3332650031, 8575.95712021436, 8733.432326898732, 8754.430961987451, 8810.691625793066, 8854.158854908774, 7052.576845389293, 8780.385373023955, 8788.956055219684, 8896.59965301563, 8924.68763593271, 3914.373700207953, 4342.8708535780925, 4507.4365704286965, 4605.078984203105, 4629.8148074077335, 1608.6219738361476, 1769.9203535840918, 1861.8324350808493, 1914.956348789913, 1918.9904050479938, 827.9047909490486, 925.4956048953337, 936.5551363102472, 941.2140862379989, 989.4211886046743, 18277.63444731098, 20294.79705202929, 20794.79205207959, 21793.67309490359, 21799.346019619436, 948.6869333120192, 951.9671156794955, 960.8558716192628, 971.8056388722142, 978.9608415663293, 11202.439878006067, 11548.0, 11550.653480395602, 11573.826392604118, 11768.52925882955, 242074.0, 244182.0, 245234.0, 245466.0, 581621.0, 77539.1961480632, 147909.0, 151306.0, 152540.2372988128, 152741.0, 986.9259805514472, 996.4868092932146, 1005.2762011351668, 1017.9491025448846, 1021.4484178543652, 17912.820871791635, 19216.61566768685, 19521.804781952, 19597.02014899278, 19631.509212269808, 57846.710766447584, 58070.0, 58097.12854307274, 58499.15968057877, 60011.69994150001, 89096.0, 89450.0, 89733.0, 90451.54774226087, 90599.54700226456, 8281.420300578902, 8388.874166887379, 8416.747497575083, 8454.40819142654, 8500.404971652173, 9450.960394356753, 13174.868251317364, 13565.59303220905, 13755.724885502434, 13764.380602872694, 7442.404607631268, 9612.567434465325, 10519.211059170746, 11507.539698411969, 11590.0, 11105.777884442105, 11381.54473821071, 11424.714382140513, 11487.774884619366, 11691.240069395462, 9954.303198776017, 11836.585719499777, 11844.0, 12018.218815776947, 12068.879311206776, 8233.752987410388, 8346.374021948257, 8362.080171181044, 8418.5790710463, 8564.272036876935, 10265.948670256601, 11537.596184133516, 11591.188616796744, 11817.940910295394, 11887.583934562248], "value": 9498.167187120935, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.2523738130935, 1549.666821633349, 1646.6377396972662, 1708.2312959168369, 1888.5278680329918, 8480.61837217326, 8776.78058048549, 8955.865662015074, 8962.283017358612, 8971.775705607362, 7875.8424831503335, 8137.511749295047, 8469.153084691528, 8679.392442529026, 8749.650013999433, 2365.763423657633, 2396.628522579003, 2400.099962514057, 2401.4956859059616, 2401.6637670726113, 2373.679553260305, 2384.95230095398, 2417.16607770319, 2420.286015625386, 2423.503181847717, 2110.4090854560673, 2193.517426166241, 2335.7313908900437, 2402.411409204747, 2409.638554216864, 2375.3705268103968, 2381.345130089222, 2401.915932942356, 2402.95194096118, 2412.9276121716375, 2379.58357287474, 2381.20229723043, 2401.363638635763, 2404.2546810488802, 2407.2778166549965, 7702.768916932501, 8910.732678019669, 8991.685291014783, 8997.505137217451, 9010.684626038123, 5224.08578498765, 5349.732513374316, 5391.0, 5395.406505284415, 5410.134378499461, 3021.2597913511113, 3491.3017396520545, 3621.7645853019735, 3644.453332000221, 3665.816709164531, 1072.8712554493434, 1096.1011970184452, 1099.9065079468255, 1100.04845808376, 1116.3413585984308, 11790.823137652942, 11804.586839460664, 11811.22831575262, 11813.0, 11842.407879606071, 4122.464079669624, 4591.540845915381, 4626.768661566908, 4792.353032340659, 4829.082474329936, 9934.900650993397, 9987.600495980225, 9989.550470228856, 9989.650362237287, 10034.347767395097, 5372.785088596413, 5382.0, 5384.703841288767, 5395.919061214085, 5401.000814849265, 4793.18515852306, 4937.35814344133, 4947.158982972904, 4947.554720075211, 4995.575376093073, 3192.4253634345578, 3794.3739283017994, 3831.214601006787, 3885.0, 3920.882373528799, 1097.9066779323846, 1118.765059337532, 1161.821584529008, 1175.3192934104313, 1176.8705442401329, 837.3050368194384, 879.564615515324, 886.2156991062827, 930.8929473110544, 975.1272611013104, 6081.3614570470345, 6224.657643829542, 6260.655663938527, 6317.0, 6377.840553986095, 778.4900889917113, 964.9758756031156, 974.3131092579714, 1020.0411613083678, 1094.195766111907, 3978.4629075074495, 4060.2894493463527, 4160.3343465046055, 4164.895877603084, 4185.665146788249, 10515.0, 10689.144868410509, 10691.41197234144, 10775.568977240979, 10782.191335649857, 9765.853512197322, 10718.517666705016, 10747.086497647631, 10748.140148788234, 10752.139828813677, 7934.2859142677435, 8316.708915188061, 8320.126386729427, 8387.538685372241, 8397.992240931006, 6060.878782424415, 6221.657808820558, 6284.654344011124, 6302.464290535356, 6328.398802113792, 10073.496325183565, 10180.134688551556, 10212.795744085226, 10224.846627300447, 10227.130693891102, 9702.805943880941, 10107.595696172071, 10418.218633602359, 10423.739406514898, 10425.322354046963, 7050.612216328152, 7252.5648461092505, 7291.380232680282, 7297.306755858185, 7298.96350518244, 8377.204165604258, 8397.034341050858, 8486.321094312316, 8493.490390576586, 8503.319734421108, 7483.363914067365, 7510.962445187739, 7709.113451953097, 7732.613369331399, 7847.136814950465, 7397.0, 7542.396608271215, 7544.660490277841, 7637.0, 7734.8839767402405, 7147.356737893615, 7209.459290553124, 7217.386522145676, 7226.458015648742, 7234.782956511313, 4998.975005124951, 5152.639315247897, 5162.612804039787, 5187.118189907649, 5259.15853463444, 5209.088409528242, 5248.790048398021, 5248.921266180934, 5334.3865455473115, 5381.515663590296], "value": 4636.59208807385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.2854350828385, 309.53106044342826, 314.2301361663924, 321.50969771099085, 324.97725159238865, 5229.450907654694, 5280.917411930545, 5322.760475778589, 5324.5740340772745, 5331.520163185312, 4957.3059771632, 5277.234800953859, 5324.440933701963, 5327.200919862013, 5336.866578335544, 3148.8425578721085, 3634.7637403568815, 3861.517310336208, 4071.7149799514045, 4077.062275676596, 3965.524137103537, 4034.253663072326, 4047.4738284023038, 4054.756714597119, 4084.243775176673, 3493.9825300873586, 4026.8590599329027, 4028.6374226319626, 4033.878983630481, 4047.1703300823406, 3840.865569705061, 3867.574566797664, 4011.0947300032253, 4018.618231268025, 4037.8788636340955, 3429.936719616927, 3466.480027995796, 3498.422760244582, 3511.6129128993903, 3538.610752817188, 4467.419235499397, 5199.402068762065, 5216.252555393948, 5217.895642087137, 5218.921716174261, 4057.7159598828093, 4301.440812694361, 4659.603933665642, 4663.720176789404, 4664.766761661904, 4636.9536304637195, 4803.615710743132, 4845.53967373099, 5575.0, 5635.746391412396, 3515.4726790981304, 3675.5956844747056, 3906.3945088511327, 3911.9217615647526, 3930.8820735377985, 5560.527355174815, 5569.275994120779, 5826.621269617462, 5834.3290521589715, 5950.9702451487465, 3279.1472817857834, 3555.377808883487, 3862.845486180521, 3944.3097457944746, 3954.090559171349, 3386.5936087669143, 3514.0687717754818, 3602.675759181634, 3782.8059725359226, 4013.2776100301644, 3998.5209170314592, 4567.81728730847, 4670.664667666167, 4670.719756814602, 4680.432055261525, 4536.977315113403, 5140.408852981955, 5278.815241466607, 5621.522170615462, 5643.109558298016, 1308.8756568126203, 1333.693250552359, 1334.2661536155076, 1337.2444568818587, 1344.8098432886966, 813.6297984417076, 820.913804050578, 829.8423299573062, 871.6513394642194, 885.3183049052194, 5154.433012368637, 5171.50870667286, 5171.844844654666, 5197.7141257231215, 5223.869403264873, 5674.963894316521, 5698.202251684767, 5762.394948530427, 5789.739461724233, 5795.391483894215, 4586.334981427674, 4633.698809577367, 4835.95164048355, 5121.411037730709, 5141.588672906233, 2521.369657585604, 2960.2534504642363, 2968.7476564491826, 2976.627921509811, 2986.1190948670196, 5819.6905696218855, 5846.912296315559, 5850.414958504116, 5858.8242352729485, 5902.4097590240635, 5459.754311056012, 5534.667919924817, 5764.538836893039, 5835.328937172195, 5879.764809407661, 5104.668196567211, 5400.702961337164, 5791.855203619859, 5846.070796637099, 5857.209276747584, 5337.866553336043, 5345.289507357608, 5580.553555715452, 5608.46719561641, 5758.62568933018, 4958.404991401055, 4983.377077865267, 5580.720963951705, 5585.497305242548, 5624.353199382123, 5868.002439585195, 5881.794137205177, 5883.558733094949, 5918.881622367442, 5944.970275148769, 3610.9458358125175, 3826.426036094608, 3850.7111966602056, 3907.47249121365, 3921.2941670499586, 5076.6446348755235, 5780.797672081457, 5782.739776710142, 5800.796972105956, 5805.767769289181, 4655.695430761794, 5398.514133727984, 5636.661800291996, 5651.971740141437, 5664.263645726194, 4285.657147428261, 5621.4659607337235, 5646.774129034792, 5668.319801623831, 5720.856978575569, 4778.562932904174, 5216.817411390583, 5420.078586640202, 5511.724413779215, 5590.161475778666, 5465.754041068241, 5590.8043218487155, 5616.943830561642, 5622.718864056863, 5627.465390787868, 4559.612432943237, 4794.760261986957, 5060.3927528696795, 5498.80754173602, 5516.862078448071], "value": 4061.441921253912, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.5544673196083, 753.118850944395, 756.8486302739452, 797.8882956386107, 856.9700060497881, 14941.402343906244, 15923.92038039811, 15969.520914372573, 15970.600734981617, 15972.680546389065, 12339.876601233986, 14631.268436578155, 14634.121952682846, 14657.340419681113, 14676.559703208894, 704.5068452083549, 866.3199388480044, 895.2166854002747, 898.8966268879065, 899.7975455522488, 901.6168128545374, 905.1355955062932, 908.4594666173649, 908.9818203635923, 914.3736540469766, 944.4286206844829, 959.098447459386, 961.4039295636715, 962.74006018375, 963.2582911158404, 738.5088915870939, 894.1639567004806, 952.8142012307603, 954.5609019850876, 954.804265125651, 697.4455308030128, 697.6093387702903, 697.8988046733195, 700.110859208807, 700.5096432497221, 15358.155301458415, 15452.613684657861, 15475.07149571018, 15475.226238688134, 15494.147821869792, 14549.34527946245, 14809.777853332209, 14834.406623735145, 14884.404623815035, 14891.553253402415, 13463.394147263396, 13474.393652285671, 13576.864231357658, 13627.0, 13778.242196679179, 4720.693154944952, 4723.905521889577, 4771.007566706485, 4807.807687692523, 4983.975080124613, 121744.0, 123536.0, 124609.75390246072, 126230.0, 126795.0, 9648.75878103039, 11346.422933856566, 11655.76688466226, 11823.881761182278, 12281.324527150913, 23528.0, 23816.88091559531, 23872.925718342718, 23908.521829563313, 23911.28266152018, 8062.717804876801, 8137.674493020331, 8177.223163799429, 8238.923806153458, 8397.0, 4373.956260437419, 4393.666983749079, 4442.518257265517, 4468.496404629511, 4593.931091033637, 3601.8019008954757, 3732.1042949692423, 3733.981330093332, 3738.009427501714, 3745.7190710696805, 598.1476396135489, 669.581511555278, 678.2437582095928, 692.9986169984325, 693.2132030145821, 612.963222206669, 636.818506725581, 650.2424675253371, 652.5544332544905, 654.2606854254661, 8890.688825891062, 8945.46327220369, 9021.639134434548, 9109.90890091104, 9272.72181834546, 540.8431554849128, 602.3524710935761, 604.6462819250787, 607.7690477618477, 620.8789286089169, 4027.073773032219, 4316.1583491219535, 4434.224010798098, 4469.709468884512, 4478.2386994211065, 89531.76170595657, 89920.10079899248, 90145.0, 152366.0, 155312.0, 113423.0, 124707.0, 132586.0, 132963.33518332348, 133902.0, 88118.0, 107036.0, 109175.0, 110330.0, 111811.44094279477, 7236.985730446203, 7920.485168464032, 7988.760337189894, 8545.786355341166, 8763.211310982042, 50731.74634126806, 61755.38244617496, 62932.68533657303, 64011.0, 64054.0, 86829.56585217033, 90044.0, 90912.0, 92482.0, 94634.0, 13472.79790803119, 13769.793453098011, 13794.586162415144, 13954.372053257424, 14428.639284017983, 14720.263986800832, 14909.627259318604, 15007.77488337654, 15559.222038898237, 15657.686846262783, 14018.22899740524, 14049.367778450198, 14163.787543186654, 14192.148471091768, 14212.0, 14085.08446950945, 14256.572302830931, 14504.637384065483, 14764.557063288119, 14790.63023424423, 13140.80288795688, 13615.659608509866, 13722.313884305546, 13735.45058197661, 13863.722725545635, 11387.089032877515, 11576.0, 11958.512492163183, 11959.641210763692, 12283.550319290913, 11333.603323883954, 11652.592159274385, 11703.648890533297, 11758.647240582795, 11780.941095294469], "value": 7992.20334461325, "unit": "score"}], "name": "dromaeo_css", "value": 7416.328636342569, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 113.0, 113.0, 112.0, 111.0, 112.0, 112.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 108.0, 155.0, 108.0, 107.0, 142.0, 135.0, 175.0, 107.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 182.0, 180.0, 185.0, 184.0, 179.0, 179.0, 180.0, 182.0], "value": 181.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 57.0, 107.0, 58.0, 57.0, 62.0, 66.0, 96.0, 57.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 91.0, 71.0, 71.0, 67.0, 99.0, 69.0, 68.0, 96.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 93.0, 92.0, 91.0, 92.0, 92.0, 92.0, 92.0, 91.0], "value": 91.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 86.0, 85.0, 86.0, 81.0, 76.0, 125.0, 130.0, 117.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 56.0, 56.0, 56.0, 55.0, 53.0, 53.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 76.0, 72.0, 70.0, 71.0, 68.0, 69.0, 70.0, 68.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 90.0, 101.0, 102.0, 78.0, 82.0, 84.0, 85.0, 85.0, 81.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 135.0, 136.0, 131.0, 136.0, 135.0, 135.0, 178.0, 137.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 163.0, 164.0, 164.0, 164.0, 163.0, 163.0, 164.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 67.0, 68.0, 66.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1428.5000000000002, "alertThreshold": 2.0}]} 16:00:16 INFO - Return code: 0 16:00:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:16 INFO - # TBPL SUCCESS # 16:00:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:16 INFO - 2016-07-05 16:00:16.445 screenresolution[1675:10291] starting screenresolution argv=screenresolution get 16:00:16 INFO - 2016-07-05 16:00:16.448 screenresolution[1675:10291] Display 0: 1600x1200x32@60 16:00:16 INFO - 2016-07-05 16:00:16.454 screenresolution[1676:10294] starting screenresolution argv=screenresolution list 16:00:16 INFO - Available Modes on Display 0 16:00:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:16 INFO - Intel Iris: 16:00:16 INFO - Chipset Model: Intel Iris 16:00:16 INFO - Type: GPU 16:00:16 INFO - Bus: Built-In 16:00:16 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:16 INFO - Vendor: Intel (0x8086) 16:00:16 INFO - Device ID: 0x0a2e 16:00:16 INFO - Revision ID: 0x0009 16:00:16 INFO - Displays: 16:00:16 INFO - Display: 16:00:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:16 INFO - Display Serial Number: 1600x1200 60 16:00:16 INFO - Main Display: Yes 16:00:16 INFO - Mirror: Off 16:00:16 INFO - Online: Yes 16:00:16 INFO - Rotation: Supported 16:00:16 INFO - Return code: 0 16:00:16 INFO - Running post-action listener: _resource_record_post_action 16:00:16 INFO - ##### 16:00:16 INFO - ##### Finished run-tests step (success) 16:00:16 INFO - ##### 16:00:16 INFO - Running post-run listener: _resource_record_post_run 16:00:16 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32030208; Write bytes: 706613248; Read time: 196; Write time: 1962 16:00:16 INFO - TinderboxPrint: CPU usage
53.3% 16:00:16 INFO - TinderboxPrint: I/O read bytes / time
32,030,208 / 196 16:00:16 INFO - TinderboxPrint: I/O write bytes / time
706,613,248 / 1,962 16:00:16 INFO - TinderboxPrint: CPU idle
2,264.8 (46.7%) 16:00:16 INFO - TinderboxPrint: CPU system
959.6 (19.8%) 16:00:16 INFO - TinderboxPrint: CPU user
1,621.3 (33.5%) 16:00:16 INFO - TinderboxPrint: Swap in / out
435,068,928 / 0 16:00:16 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398686720; Write bytes: 392214016; Read time: 29431; Write time: 790 16:00:16 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30096896; Write bytes: 296618496; Read time: 179; Write time: 1128 16:00:17 INFO - Running post-run listener: _upload_blobber_files 16:00:17 INFO - Blob upload gear active. 16:00:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:17 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 16:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:17 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:00:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:00:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:18 INFO - (blobuploader) - INFO - Done attempting. 16:00:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:00:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:00:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:19 INFO - (blobuploader) - INFO - Done attempting. 16:00:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:21 INFO - (blobuploader) - INFO - Done attempting. 16:00:21 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:21 INFO - Return code: 0 16:00:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a691ce0dceb5fe6d8268a84da894dfb2756aea64b5911676f84da4a329acbdb4606905a12e58753a7b74b039aeebd42d51efbbfbd1dc4c98c6dabc4d570dbcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/462568eee23e0768adbdb909c7e28fd8bbfa3fbe5c9cc326122f8020e6b5c3bffe5286bebdd9450d7c64b6413ce4e4f758bc513bd94ef5dbd3a26114da7cebf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e46d54d3c795147fbdd95a2fb10d43b1bb473554400525b0ec0258fd0db7b6120ec0a5f6f68a62fb82b66b5b2c3b2f176645aa051a95eedcc70242356ddf9bb9"} 16:00:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:21 INFO - Contents: 16:00:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a691ce0dceb5fe6d8268a84da894dfb2756aea64b5911676f84da4a329acbdb4606905a12e58753a7b74b039aeebd42d51efbbfbd1dc4c98c6dabc4d570dbcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/462568eee23e0768adbdb909c7e28fd8bbfa3fbe5c9cc326122f8020e6b5c3bffe5286bebdd9450d7c64b6413ce4e4f758bc513bd94ef5dbd3a26114da7cebf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e46d54d3c795147fbdd95a2fb10d43b1bb473554400525b0ec0258fd0db7b6120ec0a5f6f68a62fb82b66b5b2c3b2f176645aa051a95eedcc70242356ddf9bb9"} 16:00:21 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:21 INFO - Copying logs to upload dir... 16:00:21 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.456591 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-05 16:00:21.499195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 16:00:21.502453) ========= 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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a691ce0dceb5fe6d8268a84da894dfb2756aea64b5911676f84da4a329acbdb4606905a12e58753a7b74b039aeebd42d51efbbfbd1dc4c98c6dabc4d570dbcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/462568eee23e0768adbdb909c7e28fd8bbfa3fbe5c9cc326122f8020e6b5c3bffe5286bebdd9450d7c64b6413ce4e4f758bc513bd94ef5dbd3a26114da7cebf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e46d54d3c795147fbdd95a2fb10d43b1bb473554400525b0ec0258fd0db7b6120ec0a5f6f68a62fb82b66b5b2c3b2f176645aa051a95eedcc70242356ddf9bb9"} build_url:https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/DddXmo5KQ1eyIPOb_rNRIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2a691ce0dceb5fe6d8268a84da894dfb2756aea64b5911676f84da4a329acbdb4606905a12e58753a7b74b039aeebd42d51efbbfbd1dc4c98c6dabc4d570dbcb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/462568eee23e0768adbdb909c7e28fd8bbfa3fbe5c9cc326122f8020e6b5c3bffe5286bebdd9450d7c64b6413ce4e4f758bc513bd94ef5dbd3a26114da7cebf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e46d54d3c795147fbdd95a2fb10d43b1bb473554400525b0ec0258fd0db7b6120ec0a5f6f68a62fb82b66b5b2c3b2f176645aa051a95eedcc70242356ddf9bb9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 16:00:21.535549) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:00:21.536233) ========= 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.32H2J3Pf06/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.sSrsXiu1Vw/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.004903 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 16:00:21.586383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 16:00:21.586714) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 16:00:21.587063) ========= ========= Total master_lag: 0.21 =========