builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0121 starttime: 1468978401.06 results: success (0) buildid: 20160719163419 builduid: 0ab8feb161124daf980a6663ac19a9c0 revision: abd1161f6a90962bc80c44b3f1d2f31392a118c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.060930) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.061462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.061738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.087066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.087656) ========= 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.gAUhiKNbXT/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.alDUrYnk7i/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-19 18:33:21-- 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% 14.2M=0.001s 2016-07-19 18:33:21 (14.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160297 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.271315) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.271675) ========= 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.gAUhiKNbXT/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.alDUrYnk7i/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.028092 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:21.321030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-19 18:33:21.321426) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev abd1161f6a90962bc80c44b3f1d2f31392a118c6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev abd1161f6a90962bc80c44b3f1d2f31392a118c6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gAUhiKNbXT/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.alDUrYnk7i/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-19 18:33:21,395 truncating revision to first 12 chars 2016-07-19 18:33:21,395 Setting DEBUG logging. 2016-07-19 18:33:21,395 attempt 1/10 2016-07-19 18:33:21,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/abd1161f6a90?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 18:33:23,608 unpacking tar archive at: fx-team-abd1161f6a90/testing/mozharness/ program finished with exit code 0 elapsedTime=2.463423 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-19 18:33:23.812791) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:23.813314) ========= script_repo_revision: abd1161f6a90962bc80c44b3f1d2f31392a118c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:23.813797) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:23.815032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 18:33:23.836364) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-19 18:33:23.836828) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gAUhiKNbXT/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.alDUrYnk7i/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:33:24 INFO - MultiFileLogger online at 20160719 18:33:24 in /builds/slave/test 18:33:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:33:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:33:24 INFO - {'append_to_log': False, 18:33:24 INFO - 'base_work_dir': '/builds/slave/test', 18:33:24 INFO - 'blob_upload_branch': 'Fx-Team', 18:33:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:33:24 INFO - 'branch': 'Fx-Team', 18:33:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:33:24 INFO - 'config_files': ('talos/mac_config.py',), 18:33:24 INFO - 'default_actions': ('clobber', 18:33:24 INFO - 'read-buildbot-config', 18:33:24 INFO - 'download-and-extract', 18:33:24 INFO - 'populate-webroot', 18:33:24 INFO - 'create-virtualenv', 18:33:24 INFO - 'install', 18:33:24 INFO - 'run-tests'), 18:33:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:33:24 INFO - 'download_minidump_stackwalk': True, 18:33:24 INFO - 'download_symbols': 'ondemand', 18:33:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:33:24 INFO - 'tooltool.py': '/tools/tooltool.py', 18:33:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:33:24 INFO - '/tools/misc-python/virtualenv.py')}, 18:33:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:33:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:33:24 INFO - 'installer_path': 'installer.exe', 18:33:24 INFO - 'log_level': 'info', 18:33:24 INFO - 'log_name': 'talos', 18:33:24 INFO - 'log_to_console': True, 18:33:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:33:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:33:24 INFO - 'opt_config_files': (), 18:33:24 INFO - 'pip_index': False, 18:33:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:33:24 INFO - 'cmd': ('bash', 18:33:24 INFO - '-c', 18:33:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:33:24 INFO - 'enabled': True, 18:33:24 INFO - 'halt_on_failure': False, 18:33:24 INFO - 'name': 'check_screen_resolution'},), 18:33:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:33:24 INFO - 'cmd': ('bash', 18:33:24 INFO - '-c', 18:33:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:33:24 INFO - 'enabled': True, 18:33:24 INFO - 'halt_on_failure': False, 18:33:24 INFO - 'name': 'check_screen_resolution'},), 18:33:24 INFO - 'run_cmd_checks_enabled': True, 18:33:24 INFO - 'sps_profile': False, 18:33:24 INFO - 'sps_profile_interval': 0, 18:33:24 INFO - 'suite': 'dromaeojs', 18:33:24 INFO - 'system_bits': '32', 18:33:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:33:24 INFO - 'title': 't-yosemite-r7-0121', 18:33:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:33:24 INFO - 'use_talos_json': True, 18:33:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:33:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:33:24 INFO - 'work_dir': 'build'} 18:33:24 INFO - ##### 18:33:24 INFO - ##### Running clobber step. 18:33:24 INFO - ##### 18:33:24 INFO - Running pre-action listener: _resource_record_pre_action 18:33:24 INFO - Running main action method: clobber 18:33:24 INFO - rmtree: /builds/slave/test/build 18:33:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:33:26 INFO - Running post-action listener: _resource_record_post_action 18:33:26 INFO - ##### 18:33:26 INFO - ##### Finished clobber step (success) 18:33:26 INFO - ##### 18:33:26 INFO - ##### 18:33:26 INFO - ##### Running read-buildbot-config step. 18:33:26 INFO - ##### 18:33:26 INFO - Running pre-action listener: _resource_record_pre_action 18:33:26 INFO - Running main action method: read_buildbot_config 18:33:26 INFO - Using buildbot properties: 18:33:26 INFO - { 18:33:26 INFO - "project": "", 18:33:26 INFO - "product": "firefox", 18:33:26 INFO - "script_repo_revision": "production", 18:33:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:33:26 INFO - "repository": "", 18:33:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:33:26 INFO - "buildid": "20160719163419", 18:33:26 INFO - "pgo_build": "False", 18:33:26 INFO - "basedir": "/builds/slave/test", 18:33:26 INFO - "buildnumber": 852, 18:33:26 INFO - "slavename": "t-yosemite-r7-0121", 18:33:26 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:33:26 INFO - "platform": "yosemite_r7", 18:33:26 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:33:26 INFO - "branch": "fx-team", 18:33:26 INFO - "repo_path": "integration/fx-team", 18:33:26 INFO - "revision": "abd1161f6a90962bc80c44b3f1d2f31392a118c6", 18:33:26 INFO - "stage_platform": "macosx64", 18:33:26 INFO - "builduid": "0ab8feb161124daf980a6663ac19a9c0", 18:33:26 INFO - "slavebuilddir": "test" 18:33:26 INFO - } 18:33:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 18:33:26 INFO - Running post-action listener: _resource_record_post_action 18:33:26 INFO - ##### 18:33:26 INFO - ##### Finished read-buildbot-config step (success) 18:33:26 INFO - ##### 18:33:26 INFO - ##### 18:33:26 INFO - ##### Running download-and-extract step. 18:33:26 INFO - ##### 18:33:26 INFO - Running pre-action listener: _resource_record_pre_action 18:33:26 INFO - Running main action method: download_and_extract 18:33:26 INFO - mkdir: /builds/slave/test/build/tests 18:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:26 INFO - https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:33:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:33:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:33:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:27 INFO - Downloaded 1199 bytes. 18:33:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:33:27 INFO - Using the following test package requirements: 18:33:27 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:33:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 18:33:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 18:33:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'jsshell-mac.zip'], 18:33:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 18:33:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:33:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 18:33:27 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 18:33:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 18:33:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:33:27 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 18:33:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:33:27 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')]} 18:33:27 INFO - https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:33:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:33:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:33:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:28 INFO - Downloaded 18835323 bytes. 18:33:28 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'] 18:33:28 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 18:33:31 INFO - Return code: 0 18:33:31 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 18:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:31 INFO - https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:33:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:33:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:33:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:32 INFO - Downloaded 18835323 bytes. 18:33:32 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'] 18:33:32 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 18:33:36 INFO - Return code: 0 18:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:36 INFO - https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:33:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:33:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:33:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:37 INFO - Downloaded 14403317 bytes. 18:33:37 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'] 18:33:37 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 18:33:37 INFO - Return code: 0 18:33:37 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')]} 18:33:37 INFO - https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:33:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:33:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:33:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 18:33:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/alb64fjPTVSsmMawNU9MKQ/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 18:33:40 INFO - Downloaded 112868806 bytes. 18:33:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:33:40 INFO - mkdir: /builds/slave/test/properties 18:33:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:33:40 INFO - Writing to file /builds/slave/test/properties/build_url 18:33:40 INFO - Contents: 18:33:40 INFO - build_url:https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:33:42 INFO - Running post-action listener: _resource_record_post_action 18:33:42 INFO - Running post-action listener: set_extra_try_arguments 18:33:42 INFO - ##### 18:33:42 INFO - ##### Finished download-and-extract step (success) 18:33:42 INFO - ##### 18:33:42 INFO - ##### 18:33:42 INFO - ##### Running populate-webroot step. 18:33:42 INFO - ##### 18:33:42 INFO - Running pre-action listener: _resource_record_pre_action 18:33:42 INFO - Running main action method: populate_webroot 18:33:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:33:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:33:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:33:42 INFO - '--tppagecycles', 18:33:42 INFO - '7'], 18:33:42 INFO - 'tests': ['tsvgm']}}, 18:33:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:33:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:33:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:33:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:33:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:33:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:33:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['damp', 'tps']}, 18:33:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'tests': ['damp', 'tps']}, 18:33:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['dromaeo_dom']}, 18:33:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:33:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['basic_compositor_video']}, 18:33:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:33:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['a11yr', 18:33:42 INFO - 'ts_paint', 18:33:42 INFO - 'tpaint', 18:33:42 INFO - 'sessionrestore', 18:33:42 INFO - 'sessionrestore_no_auto_restore', 18:33:42 INFO - 'tabpaint']}, 18:33:42 INFO - 'other-e10s': {'tests': ['a11yr', 18:33:42 INFO - 'ts_paint', 18:33:42 INFO - 'tpaint', 18:33:42 INFO - 'sessionrestore', 18:33:42 INFO - 'sessionrestore_no_auto_restore', 18:33:42 INFO - 'tabpaint']}, 18:33:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:33:42 INFO - 'ts_paint', 18:33:42 INFO - 'tpaint', 18:33:42 INFO - 'sessionrestore', 18:33:42 INFO - 'sessionrestore_no_auto_restore', 18:33:42 INFO - 'tabpaint']}, 18:33:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:33:42 INFO - 'ts_paint', 18:33:42 INFO - 'tpaint', 18:33:42 INFO - 'sessionrestore', 18:33:42 INFO - 'sessionrestore_no_auto_restore', 18:33:42 INFO - 'tabpaint']}, 18:33:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['a11yr', 18:33:42 INFO - 'ts_paint', 18:33:42 INFO - 'tpaint', 18:33:42 INFO - 'sessionrestore', 18:33:42 INFO - 'sessionrestore_no_auto_restore', 18:33:42 INFO - 'tabpaint']}, 18:33:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['a11yr', 18:33:42 INFO - 'ts_paint', 18:33:42 INFO - 'tpaint', 18:33:42 INFO - 'sessionrestore', 18:33:42 INFO - 'sessionrestore_no_auto_restore', 18:33:42 INFO - 'tabpaint']}, 18:33:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['tsvgx', 18:33:42 INFO - 'tsvgr_opacity', 18:33:42 INFO - 'tart', 18:33:42 INFO - 'tscrollx', 18:33:42 INFO - 'cart']}, 18:33:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:33:42 INFO - 'tsvgr_opacity', 18:33:42 INFO - 'tart', 18:33:42 INFO - 'tscrollx', 18:33:42 INFO - 'cart']}, 18:33:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'talos_options': ['--disable-e10s'], 18:33:42 INFO - 'tests': ['tp5o']}, 18:33:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'tests': ['tp5o']}, 18:33:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'talos_options': ['--disable-e10s', 18:33:42 INFO - '--xperf_path', 18:33:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:33:42 INFO - 'tests': ['tp5n']}, 18:33:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:33:42 INFO - 'talos_options': ['--xperf_path', 18:33:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:33:42 INFO - 'tests': ['tp5n']}}, 18:33:42 INFO - 'talos.zip': {'path': '', 18:33:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:33:42 INFO - Running post-action listener: _resource_record_post_action 18:33:42 INFO - ##### 18:33:42 INFO - ##### Finished populate-webroot step (success) 18:33:42 INFO - ##### 18:33:42 INFO - ##### 18:33:42 INFO - ##### Running create-virtualenv step. 18:33:42 INFO - ##### 18:33:42 INFO - Running pre-action listener: _resource_record_pre_action 18:33:42 INFO - Running main action method: create_virtualenv 18:33:42 INFO - Creating virtualenv /builds/slave/test/build/venv 18:33:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:33:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:33:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:33:42 INFO - Using real prefix '/tools/python27' 18:33:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:33:43 INFO - Installing distribute.............................................................................................................................................................................................done. 18:33:45 INFO - Installing pip.................done. 18:33:45 INFO - Return code: 0 18:33:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:33:45 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')]} 18:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:45 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')]} 18:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:45 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:33:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:33:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:33:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:33:45 INFO - 'HOME': '/Users/cltbld', 18:33:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:33:45 INFO - 'LOGNAME': 'cltbld', 18:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:45 INFO - 'MOZ_NO_REMOTE': '1', 18:33:45 INFO - 'NO_EM_RESTART': '1', 18:33:45 INFO - 'PAGER': '/bin/cat', 18:33:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:45 INFO - 'PWD': '/builds/slave/test', 18:33:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:33:45 INFO - 'SHELL': '/bin/bash', 18:33:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:33:45 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:33:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:33:45 INFO - 'USER': 'cltbld', 18:33:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:33:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:45 INFO - 'XPC_FLAGS': '0x0', 18:33:45 INFO - 'XPC_SERVICE_NAME': '0', 18:33:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:33:46 INFO - Cleaning up... 18:33:46 INFO - Return code: 0 18:33:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:33: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')]} 18:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:33:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33: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')]} 18:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:33:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33: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', '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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:33: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:33: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 psutil>=3.1.1 18:33:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:33:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:33:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:33:46 INFO - 'HOME': '/Users/cltbld', 18:33:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:33:46 INFO - 'LOGNAME': 'cltbld', 18:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:46 INFO - 'MOZ_NO_REMOTE': '1', 18:33:46 INFO - 'NO_EM_RESTART': '1', 18:33:46 INFO - 'PAGER': '/bin/cat', 18:33:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:46 INFO - 'PWD': '/builds/slave/test', 18:33:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:33:46 INFO - 'SHELL': '/bin/bash', 18:33:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:33:46 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:33:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:33:46 INFO - 'USER': 'cltbld', 18:33:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:33:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:46 INFO - 'XPC_FLAGS': '0x0', 18:33:46 INFO - 'XPC_SERVICE_NAME': '0', 18:33:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:33:46 INFO - Downloading/unpacking psutil>=3.1.1 18:33: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 18:33: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 18:33: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 18:33: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 18:33: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 18:33: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 18:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:33:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:33:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:33:49 INFO - Installing collected packages: psutil 18:33:49 INFO - Running setup.py install for psutil 18:33:49 INFO - building 'psutil._psutil_osx' extension 18:33:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:33:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:33:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:33:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:33:50 INFO - building 'psutil._psutil_posix' extension 18:33:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:33:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:33:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:33:50 INFO - ^ 18:33:50 INFO - 1 warning generated. 18:33:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:33:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:33:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:33:50 INFO - Successfully installed psutil 18:33:50 INFO - Cleaning up... 18:33:50 INFO - Return code: 0 18:33:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:33:50 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')]} 18:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:50 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')]} 18:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:50 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:33:50 INFO - 'HOME': '/Users/cltbld', 18:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:33:50 INFO - 'LOGNAME': 'cltbld', 18:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:50 INFO - 'MOZ_NO_REMOTE': '1', 18:33:50 INFO - 'NO_EM_RESTART': '1', 18:33:50 INFO - 'PAGER': '/bin/cat', 18:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:50 INFO - 'PWD': '/builds/slave/test', 18:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:33:50 INFO - 'SHELL': '/bin/bash', 18:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:33:50 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:33:50 INFO - 'USER': 'cltbld', 18:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:50 INFO - 'XPC_FLAGS': '0x0', 18:33:50 INFO - 'XPC_SERVICE_NAME': '0', 18:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:33:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:33:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:33:53 INFO - Installing collected packages: mozsystemmonitor 18:33:53 INFO - Running setup.py install for mozsystemmonitor 18:33:53 INFO - Successfully installed mozsystemmonitor 18:33:53 INFO - Cleaning up... 18:33:53 INFO - Return code: 0 18:33:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:53 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:33:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:33:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:33:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:33:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:33:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:33:53 INFO - 'HOME': '/Users/cltbld', 18:33:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:33:53 INFO - 'LOGNAME': 'cltbld', 18:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:53 INFO - 'MOZ_NO_REMOTE': '1', 18:33:53 INFO - 'NO_EM_RESTART': '1', 18:33:53 INFO - 'PAGER': '/bin/cat', 18:33:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:53 INFO - 'PWD': '/builds/slave/test', 18:33:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:33:53 INFO - 'SHELL': '/bin/bash', 18:33:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:33:53 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:33:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:33:53 INFO - 'USER': 'cltbld', 18:33:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:33:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:53 INFO - 'XPC_FLAGS': '0x0', 18:33:53 INFO - 'XPC_SERVICE_NAME': '0', 18:33:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:33:53 INFO - Downloading/unpacking jsonschema==2.5.1 18:33: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 18:33: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 18:33: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 18:33: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 18:33: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 18:33: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 18:33:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:33:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:56 INFO - Downloading functools32-3.2.3-2.tar.gz 18:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:33:56 INFO - warning: no files found matching '*.txt' 18:33:56 INFO - no previously-included directories found matching 'build' 18:33:56 INFO - no previously-included directories found matching 'dist' 18:33:56 INFO - no previously-included directories found matching '.git*' 18:33:56 INFO - Installing collected packages: jsonschema, functools32 18:33:56 INFO - Running setup.py install for functools32 18:33:57 INFO - warning: no files found matching '*.txt' 18:33:57 INFO - no previously-included directories found matching 'build' 18:33:57 INFO - no previously-included directories found matching 'dist' 18:33:57 INFO - no previously-included directories found matching '.git*' 18:33:57 INFO - Successfully installed jsonschema functools32 18:33:57 INFO - Cleaning up... 18:33:57 INFO - Return code: 0 18:33:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:33:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:33:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:33:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:33:57 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:33:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:33:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:33:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:33:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:33:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:33:57 INFO - 'HOME': '/Users/cltbld', 18:33:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:33:57 INFO - 'LOGNAME': 'cltbld', 18:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:33:57 INFO - 'MOZ_NO_REMOTE': '1', 18:33:57 INFO - 'NO_EM_RESTART': '1', 18:33:57 INFO - 'PAGER': '/bin/cat', 18:33:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:33:57 INFO - 'PWD': '/builds/slave/test', 18:33:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:33:57 INFO - 'SHELL': '/bin/bash', 18:33:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:33:57 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:33:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:33:57 INFO - 'USER': 'cltbld', 18:33:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:33:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:33:57 INFO - 'XPC_FLAGS': '0x0', 18:33:57 INFO - 'XPC_SERVICE_NAME': '0', 18:33:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:33:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:33:57 INFO - Downloading/unpacking blobuploader==1.2.4 18:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:33:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:33:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:34:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:34:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:34:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:34:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:34:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:34:01 INFO - Downloading docopt-0.6.1.tar.gz 18:34:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:34:01 INFO - Installing collected packages: blobuploader, requests, docopt 18:34:01 INFO - Running setup.py install for blobuploader 18:34:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:34:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:34:01 INFO - Running setup.py install for requests 18:34:01 INFO - Running setup.py install for docopt 18:34:01 INFO - Successfully installed blobuploader requests docopt 18:34:01 INFO - Cleaning up... 18:34:02 INFO - Return code: 0 18:34:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:34:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:34:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:34:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:34:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:02 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:34:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:34:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:34:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:34:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:34:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:34:02 INFO - 'HOME': '/Users/cltbld', 18:34:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:34:02 INFO - 'LOGNAME': 'cltbld', 18:34:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:34:02 INFO - 'MOZ_NO_REMOTE': '1', 18:34:02 INFO - 'NO_EM_RESTART': '1', 18:34:02 INFO - 'PAGER': '/bin/cat', 18:34:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:34:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:34:02 INFO - 'PWD': '/builds/slave/test', 18:34:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:34:02 INFO - 'SHELL': '/bin/bash', 18:34:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:34:02 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:34:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:34:02 INFO - 'USER': 'cltbld', 18:34:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:34:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:34:02 INFO - 'XPC_FLAGS': '0x0', 18:34:02 INFO - 'XPC_SERVICE_NAME': '0', 18:34:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:34:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-9K4kUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-p0Srt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-PwZil2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-1gnLMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Z_2uYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Ec_WE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:34:02 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-DAw3FB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-nTm6wP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-UaDArH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-sZM0Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-4JgWxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-n_8LeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-2cLem6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-WEnQTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-PjQkyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:34:03 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-uLVYGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:34:04 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-x5adwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:34:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:34:04 INFO - Running setup.py install for manifestparser 18:34:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:34:04 INFO - Running setup.py install for mozcrash 18:34:04 INFO - Running setup.py install for mozdebug 18:34:04 INFO - Running setup.py install for mozdevice 18:34:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:34:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:34:04 INFO - Running setup.py install for mozfile 18:34:04 INFO - Running setup.py install for mozhttpd 18:34:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Running setup.py install for mozinfo 18:34:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Running setup.py install for mozInstall 18:34:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Running setup.py install for mozleak 18:34:05 INFO - Running setup.py install for mozlog 18:34:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Running setup.py install for moznetwork 18:34:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:34:05 INFO - Running setup.py install for mozprocess 18:34:05 INFO - Running setup.py install for mozprofile 18:34:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:34:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:34:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:34:06 INFO - Running setup.py install for mozrunner 18:34:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:34:06 INFO - Running setup.py install for mozscreenshot 18:34:06 INFO - Running setup.py install for moztest 18:34:06 INFO - Running setup.py install for mozversion 18:34:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:34:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:34:06 INFO - Cleaning up... 18:34:06 INFO - Return code: 0 18:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:34:06 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')]} 18:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:06 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')]} 18:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:06 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:34:06 INFO - 'HOME': '/Users/cltbld', 18:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:34:06 INFO - 'LOGNAME': 'cltbld', 18:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:34:06 INFO - 'MOZ_NO_REMOTE': '1', 18:34:06 INFO - 'NO_EM_RESTART': '1', 18:34:06 INFO - 'PAGER': '/bin/cat', 18:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:34:06 INFO - 'PWD': '/builds/slave/test', 18:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:34:06 INFO - 'SHELL': '/bin/bash', 18:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:34:06 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:34:06 INFO - 'USER': 'cltbld', 18:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:34:06 INFO - 'XPC_FLAGS': '0x0', 18:34:06 INFO - 'XPC_SERVICE_NAME': '0', 18:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:34:06 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-1AK3oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-GNMDog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Qm9X3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-kisnOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-eQxVEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-SF7RYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-lGY0aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-r2EvSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-DLZGxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:34:07 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-kxSPU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:34:08 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)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-xN9arb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-sooQiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-JC3FtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-wtsacW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-sgq1EC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-PdatAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:34:08 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-KMiCr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:34:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:34:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:34:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:34:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:34:11 INFO - Downloading blessings-1.6.tar.gz 18:34:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:34:11 INFO - Installing collected packages: blessings 18:34:11 INFO - Running setup.py install for blessings 18:34:11 INFO - Successfully installed blessings 18:34:11 INFO - Cleaning up... 18:34:11 INFO - Return code: 0 18:34:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:34:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:34:11 INFO - Reading from file tmpfile_stdout 18:34:11 INFO - Current package versions: 18:34:11 INFO - blessings == 1.6 18:34:11 INFO - blobuploader == 1.2.4 18:34:11 INFO - docopt == 0.6.1 18:34:11 INFO - functools32 == 3.2.3-2 18:34:11 INFO - jsonschema == 2.5.1 18:34:11 INFO - manifestparser == 1.1 18:34:11 INFO - mozInstall == 1.12 18:34:11 INFO - mozcrash == 0.17 18:34:11 INFO - mozdebug == 0.1 18:34:11 INFO - mozdevice == 0.48 18:34:11 INFO - mozfile == 1.2 18:34:11 INFO - mozhttpd == 0.7 18:34:11 INFO - mozinfo == 0.9 18:34:11 INFO - mozleak == 0.1 18:34:11 INFO - mozlog == 3.2 18:34:11 INFO - moznetwork == 0.27 18:34:11 INFO - mozprocess == 0.23 18:34:11 INFO - mozprofile == 0.28 18:34:11 INFO - mozrunner == 6.12 18:34:11 INFO - mozscreenshot == 0.1 18:34:11 INFO - mozsystemmonitor == 0.3 18:34:11 INFO - moztest == 0.7 18:34:11 INFO - mozversion == 1.4 18:34:11 INFO - psutil == 3.1.1 18:34:11 INFO - requests == 1.2.3 18:34:11 INFO - wsgiref == 0.1.2 18:34:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:11 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:34:11 INFO - 'HOME': '/Users/cltbld', 18:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:34:11 INFO - 'LOGNAME': 'cltbld', 18:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:34:11 INFO - 'MOZ_NO_REMOTE': '1', 18:34:11 INFO - 'NO_EM_RESTART': '1', 18:34:11 INFO - 'PAGER': '/bin/cat', 18:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:34:11 INFO - 'PWD': '/builds/slave/test', 18:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:34:11 INFO - 'SHELL': '/bin/bash', 18:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:34:11 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:34:11 INFO - 'USER': 'cltbld', 18:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:34:11 INFO - 'XPC_FLAGS': '0x0', 18:34:11 INFO - 'XPC_SERVICE_NAME': '0', 18:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:34:12 INFO - Cleaning up... 18:34:12 INFO - Return code: 0 18:34:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:34:12 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')]} 18:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:12 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')]} 18:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:34:12 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 0x10d628a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6bd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bfac68870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, '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.gAUhiKNbXT/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.alDUrYnk7i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:34:12 INFO - 'HOME': '/Users/cltbld', 18:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:34:12 INFO - 'LOGNAME': 'cltbld', 18:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:34:12 INFO - 'MOZ_NO_REMOTE': '1', 18:34:12 INFO - 'NO_EM_RESTART': '1', 18:34:12 INFO - 'PAGER': '/bin/cat', 18:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:34:12 INFO - 'PWD': '/builds/slave/test', 18:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:34:12 INFO - 'SHELL': '/bin/bash', 18:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:34:12 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:34:12 INFO - 'USER': 'cltbld', 18:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:34:12 INFO - 'XPC_FLAGS': '0x0', 18:34:12 INFO - 'XPC_SERVICE_NAME': '0', 18:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:34:12 INFO - Cleaning up... 18:34:12 INFO - Return code: 0 18:34:12 INFO - Running post-action listener: _resource_record_post_action 18:34:12 INFO - Running post-action listener: _start_resource_monitoring 18:34:12 INFO - Starting resource monitoring. 18:34:12 INFO - ##### 18:34:12 INFO - ##### Finished create-virtualenv step (success) 18:34:12 INFO - ##### 18:34:12 INFO - ##### 18:34:12 INFO - ##### Running install step. 18:34:12 INFO - ##### 18:34:12 INFO - Running pre-action listener: _resource_record_pre_action 18:34:12 INFO - Running main action method: install 18:34:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:34:12 INFO - Reading from file tmpfile_stdout 18:34:12 INFO - Detecting whether we're running mozinstall >=1.0... 18:34:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:34:12 INFO - Reading from file tmpfile_stdout 18:34:12 INFO - Output received: 18:34:12 INFO - Usage: mozinstall [options] installer 18:34:12 INFO - Options: 18:34:12 INFO - -h, --help show this help message and exit 18:34:12 INFO - -d DEST, --destination=DEST 18:34:12 INFO - Directory to install application into. [default: 18:34:12 INFO - "/builds/slave/test"] 18:34:12 INFO - --app=APP Application being installed. [default: firefox] 18:34:12 INFO - mkdir: /builds/slave/test/build/application 18:34:12 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'] 18:34:12 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 18:34:47 INFO - Reading from file tmpfile_stdout 18:34:47 INFO - Output received: 18:34:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:34:47 INFO - Running post-action listener: _resource_record_post_action 18:34:47 INFO - ##### 18:34:47 INFO - ##### Finished install step (success) 18:34:47 INFO - ##### 18:34:47 INFO - ##### 18:34:47 INFO - ##### Running run-tests step. 18:34:47 INFO - ##### 18:34:47 INFO - Running pre-action listener: _resource_record_pre_action 18:34:47 INFO - Running main action method: run_tests 18:34:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:34:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:34:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:34:47 INFO - 2016-07-19 18:34:47.184 screenresolution[1635:7304] starting screenresolution argv=screenresolution get 18:34:47 INFO - 2016-07-19 18:34:47.187 screenresolution[1635:7304] Display 0: 1600x1200x32@60 18:34:47 INFO - 2016-07-19 18:34:47.193 screenresolution[1636:7306] starting screenresolution argv=screenresolution list 18:34:47 INFO - Available Modes on Display 0 18:34:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:34:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:34:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:34:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:34:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:34:47 INFO - Intel Iris: 18:34:47 INFO - Chipset Model: Intel Iris 18:34:47 INFO - Type: GPU 18:34:47 INFO - Bus: Built-In 18:34:47 INFO - VRAM (Dynamic, Max): 1536 MB 18:34:47 INFO - Vendor: Intel (0x8086) 18:34:47 INFO - Device ID: 0x0a2e 18:34:47 INFO - Revision ID: 0x0009 18:34:47 INFO - Displays: 18:34:47 INFO - Display: 18:34:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:34:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:34:47 INFO - Display Serial Number: 1600x1200 60 18:34:47 INFO - Main Display: Yes 18:34:47 INFO - Mirror: Off 18:34:47 INFO - Online: Yes 18:34:47 INFO - Rotation: Supported 18:34:47 INFO - Return code: 0 18:34:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:34:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:34:47 INFO - Python 2.7.3 18:34:47 INFO - Return code: 0 18:34:47 INFO - grabbing minidump binary from tooltool 18:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:34:47 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 0x10d58cd30>, '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 0x10d2f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d584b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:34:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:34:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:34:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:34:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:34:47 INFO - Return code: 0 18:34:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:34:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:34:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:34:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:34:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:34:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:34:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0121 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:34:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gAUhiKNbXT/Render', 18:34:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:34:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:34:47 INFO - 'HOME': '/Users/cltbld', 18:34:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:34:47 INFO - 'LOGNAME': 'cltbld', 18:34:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:34:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:34:47 INFO - 'MOZ_NO_REMOTE': '1', 18:34:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:34:47 INFO - 'NO_EM_RESTART': '1', 18:34:47 INFO - 'PAGER': '/bin/cat', 18:34:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:34:47 INFO - 'PWD': '/builds/slave/test', 18:34:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:34:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:34:47 INFO - 'SHELL': '/bin/bash', 18:34:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.alDUrYnk7i/Listeners', 18:34:47 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 18:34:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:34:47 INFO - 'USER': 'cltbld', 18:34:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:34:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:34:47 INFO - 'XPC_FLAGS': '0x0', 18:34:47 INFO - 'XPC_SERVICE_NAME': '0', 18:34:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:34:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:34:47 INFO - mozversion application_buildid: 20160719163419 18:34:47 INFO - mozversion application_changeset: abd1161f6a90962bc80c44b3f1d2f31392a118c6 18:34:47 INFO - mozversion application_display_name: Nightly 18:34:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:34:47 INFO - mozversion application_name: Firefox 18:34:47 INFO - mozversion application_remotingname: firefox 18:34:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:34:47 INFO - mozversion application_vendor: Mozilla 18:34:47 INFO - mozversion application_version: 50.0a1 18:34:47 INFO - mozversion platform_buildid: 20160719163419 18:34:47 INFO - mozversion platform_changeset: abd1161f6a90962bc80c44b3f1d2f31392a118c6 18:34:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:34:47 INFO - mozversion platform_version: 50.0a1 18:34:47 INFO - using testdate: 1468978487 18:34:47 INFO - actual date: 1468978487 18:34:47 INFO - starting webserver on 'localhost:49267' 18:34:47 INFO - SUITE-START | Running 2 tests 18:34:47 INFO - TEST-START | dromaeo_css 18:34:47 INFO - operating with platform_type : mac_ 18:34:47 INFO - Initialising browser for dromaeo_css test... 18:34:47 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpNanR2P/profile http://localhost:49267/getInfo.html) 18:34:50 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 18:34:50 INFO - PROCESS | 1646 | colorDepth:24 18:34:50 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 18:34:50 INFO - PROCESS | 1646 | __metrics 18:34:50 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:34:50 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:34:50 INFO - TEST-INFO | 1646: exit 0 18:34:50 INFO - Browser initialized. 18:34:50 INFO - Running cycle 1/1 for dromaeo_css test... 18:34:50 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpNanR2P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:02 INFO - PROCESS | 1648 | RSS: Main: 244924416 18:35:02 INFO - PROCESS | 1648 | 18:35:53 INFO - PROCESS | 1648 | 1468978553850 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:38:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:38:06 INFO - PROCESS | 1648 | RSS: Main: 378900480 18:38:06 INFO - PROCESS | 1648 | 18:41:09 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 18:41:10 INFO - PROCESS | 1648 | RSS: Main: 381018112 18:41:10 INFO - PROCESS | 1648 | 18:44:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 18:44:13 INFO - PROCESS | 1648 | RSS: Main: 372113408 18:44:13 INFO - PROCESS | 1648 | 18:47:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 18:47:16 INFO - PROCESS | 1648 | RSS: Main: 372883456 18:47:16 INFO - PROCESS | 1648 | 18:50:19 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 18:50:20 INFO - PROCESS | 1648 | RSS: Main: 346787840 18:50:20 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 18:53:23 INFO - PROCESS | 1648 | RSS: Main: 417521664 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | __start_tp_report 18:53:23 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:53:23 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:53:23 INFO - PROCESS | 1648 | |i|pagename|runs| 18:53:23 INFO - PROCESS | 1648 | |0;dojo.html;3325.3183097465017;3325.3515564464924;3326.2848487575166;3375.611804642464;3398.626151123376;5652.830415087554;5697.943020569798;5722.570807189467;5790.276215473066;5862.706864656766;4195.349720793282;5677.630953987988;5752.827415177551;5767.971160144204;5821.3305469870975;5134.820281290155;5186.325777648919;5232.189010703328;5257.421683614818;5339.813106541272;5346.03771321162;5355.410904800468;5364.7317634118335;5407.729613519328;5425.213344065108;4877.9268310975185;5136;5198.2722418861385;5210.635255532115;5303.973480132613;5229.895402091937;5232.110541208004;5232.58139348855;5240.187770895517;5245.370555533323;5148.948510514884;5181.145111056709;5203.531682148587;5208.817691380784;5221.138512145491;5644.294463192101;5656.43435656431;5715.114157305624;5723.627964182315;5728.770849166029;6257.655828929407;6334.778282760128;6339.524535659844;6341.397567231105;6341.778037768702;5259.86850328741;6114.633122012649;6262.185915830958;6329.082283068929;6349.841253968688;5685.090385538294;5762.625429347079;5821.388754180834;5826.621269617462;5902.026165682701;6285.874282514415;6291.213598300213;6292.056191571301;6296.842578935563;6305.211848518935;6152.630842149485;6181.350958149419;6185.41238582334;6188.1027251048345;6191.659458729813;6102.450779429784;6215.720292586844;6263.749450022039;6274.309825919146;6341.302456729756;6272.5922815016875;6278.30938596754;6280;6285.088662144055;6303.779367722108;6113.537117903944;6166.229221347332;6297.275813281532;6310.873782524323;6326.145970294044;4906.067847109038;4934.605231581465;4977.477364876709;4979.228219625964;4980.178270585386;4423.071155057474;4427.22523558376;4436.201483732959;4444.11117776446;4473.217186992263;5622.240997465372;5839.649621022667;5865.032269675541;5868.794592189253;5872.7357268923;6162.22972128484;6238.844028899314;6291.842703932438;6323.494120470351;6327.272363678144;5838.153467747238;5851.473367396955;5873.676947767914;5887.293524777054;5917.05327147663;4475.48531918827;5432.2123292123215;5438.5377242934;5454.10007348783;5464.18037294409;6230.314665386803;6268.216472940882;6269.247690277196;6303.810885673437;6304.621722696653;6319.494440444661;6336.334684858023;6349.174607301022;6361.777337793155;6363.713632886624;6201.782937597162;6289.150964619717;6295.3704629538515;6308.116863639002;6314;5956.493698035542;6105.914670190187;6145.832291864532;6212.316645168938;6244.7694576176555;6323.08315294289;6331;6350.460210882126;6354.650494222862;6356.618602883842;5997.070454079625;6082.452579267887;6171.9691401544505;6182.258129024546;6197.0084786435755;5970.014947533608;6024.246969128859;6051.33435322123;6127.922628246602;6189.473894718999;6008.399160083956;6019.097135429721;6023.819285421444;6041.848953776191;6075.726592303268;5326.866828329323;6045.818625441244;6217.782377616762;6235.937640623717;6329.936700632935;5886.146508756292;5995.040793473024;6013.849653758691;6075.726592303268;6080.939190608037 18:53:23 INFO - PROCESS | 1648 | |1;ext.html;494.40424288732083;520.3651545114958;534.5269436548655;547.2967237100327;555.9638623489471;22978.425539361524;40999.975000624996;41835.79082104589;42054.57945420545;42110.789446052724;42716.359254611125;43092.138157236834;43278.783606081925;43292.78353608227;43430.78284608581;8736.475811451333;9709.514524273776;9894.703158905244;9904.603815847357;9923.851142232872;8665.220130188281;11842.348670823101;11961.7607647847;12011.519539218421;12243.69390765229;11849.644510664652;11881.465334059945;11909.642710718692;11920.7615847683;11924.403779811018;8748.168923952215;11762.470688819023;11893.643190704293;11904.285742855456;12009.219400739012;11748.353840538766;11789.292642441482;11811.409429528489;11818.586349477813;11820.763584728344;40677.98305042368;41033.589664103274;41062.794686026675;41091.794541027404;41210;3538.135064286606;3619.5837478690023;3633.1462106404974;4078.1843631273864;4088.8364465421346;31050.53424198639;32180.195495112574;32532.837335813405;32556.6744332556;32624;3286.047046356554;3414.0270023043313;3438.5186073949667;3445.896623101311;3447.672471115265;405241;407851;408111;409311;411063;58928.11607825886;59009.70495147497;59305.110423343685;59358;59375.70312148412;60652.39347606467;60671.393286067454;60925.69537152375;60937.39062609406;60940.695296523234;16377.0992595406;16643.001419914843;16649.001059936443;16656.75014874778;16657.33370665184;11740.823887641693;11997.280163190062;12739.426725797175;12980.35098245084;13033.869661303266;15202.17573891118;15282.923585382003;15289.541313760605;15305.693886122215;15449.922750386177;6022.247219097613;6111.388861113853;6141.140240366352;6184.783532576428;6188.257409110935;766.5439063757055;850.8425941200898;909.6497848328441;976.2190247801872;987.550664447689;30032.699673003426;30112.698873011428;30485.695143048284;30993;31002.68997310043;1069.0485467933545;1099.6865893220395;1175.8059920113258;1229.8708635593134;1233.7470818482368;12700;15779.68440631181;15782.289796959165;15891.761623575656;16074.598135046483;663589;670324;671151;672223;673729;58406.707966460745;58722.70638646779;58735.70632146898;58771;58853;1152.1416544674105;1277.9297138657464;1300.772364836169;1403.333416627094;1454.3092031285266;26450.67746612623;28936.565951511166;29992.4001519964;30799.846000769852;31317.686823131477;49002;49511.50488495213;49963;49965.25052124148;50037.74981125071;594186;594789;595898;595967;596088;33754.831225843714;34411.79558715433;34871;40713.59286407098;40785.59214407937;47345.28982065341;48245.75877120592;48855.267170991756;49090;49191.754041229564;38076.428853566664;38957;39267.80366098265;39520.01199970024;40612.79693601513;39474.80262598669;39563;39709.404358935215;40142.59857401506;40746.59253407428;30437;38026.619733802305;38048.619513805614;38600.613993860825;38861.6113838858;34253.29300907276;34427.622895083914;35332.64667353294;35428.64571354356;36142.63857361498;21560;25490.49019019572;25855.741442585335;26163;26350.86824565865 18:53:23 INFO - PROCESS | 1648 | |2;jquery.html;344.85343728915205;365.1765269317688;394.0758920331821;398.6113539299182;411.53496548899756;15782.921085394571;15867.285972131252;20612.278570250044;21070.367888963316;21083.94580270984;20758.754474731526;21294.57410851782;21330.146794128217;21353;21637.891810540965;5440.047991601474;5916.2308899843065;6060.787872424466;6086.300075491331;6099.359567245442;6014.578979471439;6056.60632058917;6128.724207410655;6131.785387511438;6132.8466788330425;4846.709197448165;5943.049112142058;5946.43508866657;5955.34491205967;6000.298373862445;4843.685160464559;5973.998710431989;6000.699965001776;6007.399260074001;6031.095335699658;5125.154349532323;5144.0740666679985;5163.277141200235;5169.819056333048;5173.9741301293625;26243.719535058117;26518.204453866416;26601.201963941112;26626.866865665743;27019.189424317105;2403.4592216751266;2448.8775561222046;2568.4092658688605;2603.609458581209;3053.2366908272934;14763.77854332175;15646.765298520531;15763.054216746918;15856.127912964783;16315.755263671055;1767.5846176148596;1811.7916439609482;1821.3989383503545;1900.6000069364986;1926.1428664244418;78512;88511.55744221238;130491;132096;133020.33489832585;8608;10529.684109476728;10989.780204395867;11004.504797284142;11254.7186320341;11359.943200284111;13561.932190338985;13702.52041178574;13708.93145534266;13709.931450342883;7183.42532597391;7351.632418379059;7366.410687145016;7399.223081576357;7426.554406735613;6105.572609917263;6751.898721519181;6927.238003819576;6934.306569343025;6989.580625162506;6474.255460621995;6580.80257592273;6584.5720028198975;6588.505862060269;6678.1318428604945;1714.7427885817228;1855.935042273514;1893.9242430302909;1899.990500047491;1904.5048287445363;861.8707193920962;939.5396255834592;993.7465946183821;1029.8661174047327;1032.6437379104198;21586.78413215848;22590.88704556467;22626.321210363716;22670.546589068457;22708.253138232336;929.8930622978444;975.9560819763128;995.9203263738883;1031.7678522332565;1079.3146352066337;11325.830112548318;11626.011788997861;12084.274943503242;12312.569060082855;12358.876411235773;567948.1602591961;603523;603827;605581;637742;86109;88311.55844220737;144350;146192.26903865414;149081;1002.5137808163036;1004.8492726091145;1014.4217795856439;1018.6434747838346;1020.0258752890971;18576.164072617034;18928.810711892704;18971;18971.715424268372;18984.05079746035;58186.4181358198;59277.703611481666;59431.702841485516;60353.09470358036;60677.39322606717;73320;88074.55962720401;89759;89867.10132898849;90733.54633227056;8034.276915077671;8057.952466179359;8336.666533338841;8362.265648249133;8456.746297611082;10866.18503612248;12169;13024.934875325564;13026.674333141747;13273.734525309246;10010.649627263303;10449.895501045195;11274.492647831032;11420;11444.313341199555;11252.549898003988;11266.324020558794;11318.207725459139;11425.428728563373;11533.135014874086;11258.268212566158;11420.371879546705;11632.418379080842;11717.00405465545;11726.413679315829;8096.757097287091;8161.102278749451;8180.918190818015;8185.79535511617;8319.584020798815;9927.354721943051;11137.83293250618;11151.609693660683;11235.550577977116;11422.714432139262 18:53:23 INFO - PROCESS | 1648 | |3;mootools.html;1561.6408226107994;1602.1028224194451;1797.2810875649739;1926.2102537959431;1940.5445915563328;8343.54110523921;8603.397762156652;8796.648134074638;8808.251298639616;8817.250533704626;7581.241875812416;7789.182135875736;8364.330853531717;8575.356848236375;8642.26540744036;2332.2070496031347;2360.8111351091957;2365.2549446924163;2366.7278262999716;2368.928932132039;2342.5666251743396;2344.777246161612;2382.4282172278704;2383.9046438142454;2384.821138414617;2077.781832907546;2148.4414052346347;2308.9769102309015;2372.4306166519996;2376.0733314007553;2347.988260058689;2360.6223004319313;2380.1074597026113;2385.5825230584664;2386.2602593195993;2354.458474550863;2367.5028244068767;2390.5935990881594;2391.1272385579314;2391.330427480291;8798.912010879872;8906.821863562758;8910.376273660846;8916.331275154325;8917.55412229386;5018.322526458918;5165.380154381461;5174;5175.327207463043;5194.480551944813;3447.500112483676;3530.8058056806867;3598.9100272493133;3609.458581212813;3619.402798538238;1058.4760543531002;1086.2884810449168;1099.4777480696685;1103.7020004598714;1793.973090403645;11205.495752691148;11457.885421145766;11473.08215342779;11474;11492.195546311761;4154;4233.682473814476;4295.140971805651;4297.892552686208;4483.820647174141;8931.151540603632;9004;9014.549272536478;9022.097790220925;9028.36801423894;5112.182050871843;5112.437631860493;5115.87210319745;5116.386033675983;5121.820736274212;4703.717776933395;4789.808407663724;4803.1114243864995;4811.975940120277;4824.710517368969;3538.716902647782;3547.166415892263;3567;3601.675849173587;3635.4546817976984;934.8597710343503;1061.2889364126054;1098.4123493930708;1104.8839871813504;1105.6959336182615;805.778410936997;884.5267781736868;893.9061398553058;904.3759805734022;1002.2983911262146;6090;6117.602355846856;6170.043643235305;6181.690915454209;6325.810225693236;937.3953799799092;948.3882895532342;957.6504575829912;996.0487734213808;1033.560736686905;3893.7858417787293;4102.118044620421;4120.402541631506;4136.937945930814;4179.707420480538;8884.911150888538;10187.796244075076;10347.43089130105;10451.111655509367;10558.947205263925;9720.222382209406;10268.178545716324;10490.05589496949;10511.211659125596;10622.256442049136;8097.190280971973;8315.417920745489;8327.791805204797;8337.749867503984;8338.874916876252;6051.939480605138;6134.754609815557;6142.04798256271;6164.489391077191;6227.19046523949;10047.145992590711;10055.19558435338;10057.597696092367;10069.144122749356;10092.142167915516;9762.219022478326;9985.650502232387;10295.742606434898;10315.948420258152;10318.896811032095;7282.781516554512;7590.0891892973195;7594.088709354913;7673.232676732506;7675.692972281046;8271.048829384697;8328.417010809428;8386.371022173484;8387.706430274911;8390.916090839257;6731.091302674076;6833.068678046805;7596.734114305973;7676.15562288159;7690.115636701627;7436.479446438698;7436.479446438698;7487.176410594939;7551.169371369035;7576.128745194374;7088.893666595109;7091.149062112373;7138.428925685829;7140.357367836919;7150.96424517874;4153.792310384409;5085.186370180753;5120.871978200575;5166.819161329336;5253.9211911820585;5136.409312929061;5162.5095615916425;5175.14610089331;5186.4294927557185;5335.599830012687 18:53:23 INFO - PROCESS | 1648 | |4;prototype.html;285.47900082349713;310.8507916200224;318.05378997482495;324.9561309223254;329.94061069007597;4299.398084268205;4802.663813533054;5499.697516636588;5511.614187006906;5594.832155035345;4920.195488571516;5032.849014529561;5340.786368545263;5385.2999110115725;5447.400785913546;3480.8259587020675;3936.921261574774;4072.6741860651223;4084.816183271745;4225.302825033866;3415.3510832941784;4142.896427589304;4155.106652069804;4194.475690538683;4209.957900420987;3791.848326066954;3812.7140464465137;4117.238310912476;4137.565555616662;4141.440905477767;3916.158026024404;4006.317346714358;4072.5316588592395;4192.916141677179;4239.6396306314;3653.104989277644;3667.5782285037294;3669.504616876714;3689.797061161635;3692.7045836333295;4447.399601053881;4963.60291176705;5359.088954877641;5366;5384.542313903323;4487.349334346534;4840.15297322967;4882.2432522959;4937.358143441365;4957.7769000395065;4865.5134486551415;5053.823116190915;5355.77735239875;5784.710764461802;5823.650580965155;3444.7244220462303;4058.3303754880408;4063.4717486726536;4087.9386809197886;4117.423560701504;5305.628605997582;5563.443655634444;5954.55340849438;6067.939320606782;6072.210612620375;3390.881319153867;3891.7664940103687;3931.5871833457077;3956.4460975463458;3979.462772525672;3268.346330733863;3536.6109727929907;3860.72596043305;3875.5155605549307;4000.7599544027453;3928.862489812843;4671.346011558385;4692.788824502905;4706.411698537683;4716.443573620697;5445;5724.713764311768;5739.560686708132;5783.739731712083;5786.855328616818;1282.3780466473752;1402.1797248609678;1451.4266864588428;1455.6069861137435;1458.7017554376614;826.367828611101;869.4088020146361;904.9321300902459;908.5411867007119;925.995370023159;4591.324166679138;5187.221916712448;5456.317960254968;5485.725713714378;5486.506214440719;5992.970035149883;6003.459688627957;6017.939820601738;6024.14454748961;6031.135210774847;5014.724190169502;5402.594805389612;5411.9729401353525;5452.645578037414;5517.97917385285;2541.0180059553577;2810.564362523812;3023.866050231163;3104.037748298035;3154.6214454265637;4638.976805115953;5478.493414311097;5653.551333686448;6159.798839226398;6205.689715514206;4978.6017118630425;5004.624653151028;5135.537801597874;6134.650376917071;6137.938620613826;5151.016858509426;5925.674087925206;6134.325224225332;6137.232845894263;6139.526513636695;4950.8762280943265;5662.931676149905;5836.9493491172;5872.559558033079;6008.218931538872;5855.765769369348;5900.439458251631;5910.468057874812;5911.940880591311;5927.8221653350465;5714.885702285847;5916.290045194604;6133.478654314434;6161.784337548164;6205.317415084247;3143.3556120994685;3399.467396789309;3502.0719509330556;3508.8947331580093;3547.2728090741853;5073.7675422813845;5117.437081920912;5135.974320128375;5155.097857874782;5170.991656626921;4738.620910327097;4917.606591472745;4950.975245123751;4961.82633607822;4994.825181118788;4952.47998960104;5253.789848406021;5869.325027621878;5896.911546326723;5910.433735060298;4788.353572267699;5258.368995720538;5485.533729632866;5684.516816070515;5729.455701708331;4930;5660.858478537904;5781.311690064141;5864.472197502245;5885.676287804212;4772.976135119302;5609.943900560942;5686.208844214059;5718.456746609066;5742.684152371493 18:53:23 INFO - PROCESS | 1648 | |5;yui.html;644.3105876711918;813.4915677701437;814.5397850214629;845.699776579314;851.3614788908319;14954.775678364833;16229.107399093045;16262.674746505063;16276.023438593678;16286.755698664529;14668.853311466883;14696.412143514246;14881.330340134693;15409.92295038526;16096.356145754156;896.5741272895372;918.7565295196778;923.695180590407;924;927.4620719982428;715.9427245820348;733.6185183704471;890.3278025091047;916.5783739479814;921.944683319003;966.0918736387808;968.825611389953;969.1471505075524;970.1026550440849;971.0968799016947;741.2365263778318;958.7219706285169;960.0495509445668;961.3943215774052;961.9899105938806;601.335524245711;691.3190507350256;714.8820444626631;716.9818857222756;718.9892151617678;15277.770833437507;15330.386784528606;15349.07905525672;15350.309236084404;15376;13675.863241367659;13733.793993090112;13975.580732578039;14064.367103480368;14091.71816563663;13268.535601254007;13281.269530175836;13343.733125337536;13506.86493135066;13818.861811381857;4589.105124500724;4594.2419500782335;4614.653900957408;4693.882652933704;4776.761161941889;110022;111171;111580.88419115786;111891;112315;11185.49665265065;11554.248973816675;11675.649730508097;11691.941540292244;11766.647000589996;19747.60504789896;21340.93295335258;22560.774392255866;22623.547529049654;22627;8327.708530201415;8342.582870856433;8425.662973481114;8489.151084891462;8531.10423405546;4545.704529205657;4622.191116554657;4634.559716826896;4651.953480465219;4659.5573420525;3453.7755045922436;3634.727395445352;3636.9636303636626;3644.9453258201147;3645.198056427582;659.2978477920999;662.8508585568305;674.8346655069441;680.2755065854849;683.54885775388;601.6179725874014;662.6587307536622;664.6244871647503;703.6200451755994;709.4395427612232;8399.790005249917;9218;9541;9934.552945117486;9957.800843983225;603.7373742422061;644.8291202831253;664.4385494257344;680.9377371300786;683.1392445518583;4515.751633660115;4678.625709943196;4699.979009026083;4718.174319494075;4787.640926930494;86576.7013494798;98532;155437;160230;165280;102635;133625;135760;137217.3139134318;140606;115662;118210;118955.99999999827;119394;120538;6829.795106146824;8983.46099234048;9003.684871029482;9161.541922903694;9197.12477459255;66472;66800.6659966697;66863;67154.66422667855;67783.27192004488;80725.9818504542;93274.53362733143;93404.59893101745;100486;101200;12146.392680366123;12156.149069565046;12197.69505762363;12280.38598070075;12411.62765117048;11826.34955077479;13473.191608503521;13582.524611638546;13765.931170344084;14114.2942852859;12329.383530823603;12704.3012634306;12775.105742597929;12808.167469114478;12903.935480322538;12295.631131066082;12484.500619975099;12532.310722910328;12710.364481775689;12809.935950320561;11522.711932201762;12303.323317217639;12369.876301236873;12603.68490787701;12650.987920966485;10557.41934193596;10585.52365143555;10638.202134840072;10826.945865270938;10994.560217591206;10123.79752404933;10355.741106472398;10451.790964180826;10659.573617055232;10714.517846697074 18:53:23 INFO - PROCESS | 1648 | __end_tp_report 18:53:23 INFO - PROCESS | 1648 | __start_cc_report 18:53:23 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1482 18:53:23 INFO - PROCESS | 1648 | __end_cc_report 18:53:23 INFO - PROCESS | 1648 | __startTimestamp1468979603452__endTimestamp 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:53:23 INFO - PROCESS | 1648 | Number of tests: 6 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5760.63 Median:6008.40 stddev:666.89 (11.1%) stddev-sans-first:640.99 18:53:23 INFO - PROCESS | 1648 | Values: 3325.3 3325.4 3326.3 3375.6 3398.6 5652.8 5697.9 5722.6 5790.3 5862.7 4195.3 5677.6 5752.8 5768.0 5821.3 5134.8 5186.3 5232.2 5257.4 5339.8 5346.0 5355.4 5364.7 5407.7 5425.2 4877.9 5136.0 5198.3 5210.6 5304.0 5229.9 5232.1 5232.6 5240.2 5245.4 5148.9 5181.1 5203.5 5208.8 5221.1 5644.3 5656.4 5715.1 5723.6 5728.8 6257.7 6334.8 6339.5 6341.4 6341.8 5259.9 6114.6 6262.2 6329.1 6349.8 5685.1 5762.6 5821.4 5826.6 5902.0 6285.9 6291.2 6292.1 6296.8 6305.2 6152.6 6181.4 6185.4 6188.1 6191.7 6102.5 6215.7 6263.7 6274.3 6341.3 6272.6 6278.3 6280.0 6285.1 6303.8 6113.5 6166.2 6297.3 6310.9 6326.1 4906.1 4934.6 4977.5 4979.2 4980.2 4423.1 4427.2 4436.2 4444.1 4473.2 5622.2 5839.6 5865.0 5868.8 5872.7 6162.2 6238.8 6291.8 6323.5 6327.3 5838.2 5851.5 5873.7 5887.3 5917.1 4475.5 5432.2 5438.5 5454.1 5464.2 6230.3 6268.2 6269.2 6303.8 6304.6 6319.5 6336.3 6349.2 6361.8 6363.7 6201.8 6289.2 6295.4 6308.1 6314.0 5956.5 6105.9 6145.8 6212.3 6244.8 6323.1 6331.0 6350.5 6354.7 6356.6 5997.1 6082.5 6172.0 6182.3 6197.0 5970.0 6024.2 6051.3 6127.9 6189.5 6008.4 6019.1 6023.8 6041.8 6075.7 5326.9 6045.8 6217.8 6235.9 6329.9 5886.1 5995.0 6013.8 6075.7 6080.9 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:69755.23 Median:38909.31 stddev:152074.20 (390.8%) stddev-sans-first:152411.90 18:53:23 INFO - PROCESS | 1648 | Values: 494.4 520.4 534.5 547.3 556.0 22978.4 41000.0 41835.8 42054.6 42110.8 42716.4 43092.1 43278.8 43292.8 43430.8 8736.5 9709.5 9894.7 9904.6 9923.9 8665.2 11842.3 11961.8 12011.5 12243.7 11849.6 11881.5 11909.6 11920.8 11924.4 8748.2 11762.5 11893.6 11904.3 12009.2 11748.4 11789.3 11811.4 11818.6 11820.8 40678.0 41033.6 41062.8 41091.8 41210.0 3538.1 3619.6 3633.1 4078.2 4088.8 31050.5 32180.2 32532.8 32556.7 32624.0 3286.0 3414.0 3438.5 3445.9 3447.7 405241.0 407851.0 408111.0 409311.0 411063.0 58928.1 59009.7 59305.1 59358.0 59375.7 60652.4 60671.4 60925.7 60937.4 60940.7 16377.1 16643.0 16649.0 16656.8 16657.3 11740.8 11997.3 12739.4 12980.4 13033.9 15202.2 15282.9 15289.5 15305.7 15449.9 6022.2 6111.4 6141.1 6184.8 6188.3 766.5 850.8 909.6 976.2 987.6 30032.7 30112.7 30485.7 30993.0 31002.7 1069.0 1099.7 1175.8 1229.9 1233.7 12700.0 15779.7 15782.3 15891.8 16074.6 663589.0 670324.0 671151.0 672223.0 673729.0 58406.7 58722.7 58735.7 58771.0 58853.0 1152.1 1277.9 1300.8 1403.3 1454.3 26450.7 28936.6 29992.4 30799.8 31317.7 49002.0 49511.5 49963.0 49965.3 50037.7 594186.0 594789.0 595898.0 595967.0 596088.0 33754.8 34411.8 34871.0 40713.6 40785.6 47345.3 48245.8 48855.3 49090.0 49191.8 38076.4 38957.0 39267.8 39520.0 40612.8 39474.8 39563.0 39709.4 40142.6 40746.6 30437.0 38026.6 38048.6 38600.6 38861.6 34253.3 34427.6 35332.6 35428.6 36142.6 21560.0 25490.5 25855.7 26163.0 26350.9 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:35475.58 Median:2586.01 stddev:100842.34 (3899.5%) stddev-sans-first:101090.72 18:53:23 INFO - PROCESS | 1648 | Values: 344.9 365.2 394.1 398.6 411.5 15782.9 15867.3 20612.3 21070.4 21083.9 20758.8 21294.6 21330.1 21353.0 21637.9 5440.0 5916.2 6060.8 6086.3 6099.4 6014.6 6056.6 6128.7 6131.8 6132.8 4846.7 5943.0 5946.4 5955.3 6000.3 4843.7 5974.0 6000.7 6007.4 6031.1 5125.2 5144.1 5163.3 5169.8 5174.0 26243.7 26518.2 26601.2 26626.9 27019.2 2403.5 2448.9 2568.4 2603.6 3053.2 14763.8 15646.8 15763.1 15856.1 16315.8 1767.6 1811.8 1821.4 1900.6 1926.1 78512.0 88511.6 130491.0 132096.0 133020.3 8608.0 10529.7 10989.8 11004.5 11254.7 11359.9 13561.9 13702.5 13708.9 13709.9 7183.4 7351.6 7366.4 7399.2 7426.6 6105.6 6751.9 6927.2 6934.3 6989.6 6474.3 6580.8 6584.6 6588.5 6678.1 1714.7 1855.9 1893.9 1900.0 1904.5 861.9 939.5 993.7 1029.9 1032.6 21586.8 22590.9 22626.3 22670.5 22708.3 929.9 976.0 995.9 1031.8 1079.3 11325.8 11626.0 12084.3 12312.6 12358.9 567948.2 603523.0 603827.0 605581.0 637742.0 86109.0 88311.6 144350.0 146192.3 149081.0 1002.5 1004.8 1014.4 1018.6 1020.0 18576.2 18928.8 18971.0 18971.7 18984.1 58186.4 59277.7 59431.7 60353.1 60677.4 73320.0 88074.6 89759.0 89867.1 90733.5 8034.3 8058.0 8336.7 8362.3 8456.7 10866.2 12169.0 13024.9 13026.7 13273.7 10010.6 10449.9 11274.5 11420.0 11444.3 11252.5 11266.3 11318.2 11425.4 11533.1 11258.3 11420.4 11632.4 11717.0 11726.4 8096.8 8161.1 8180.9 8185.8 8319.6 9927.4 11137.8 11151.6 11235.6 11422.7 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5655.91 Median:5112.31 stddev:3124.13 (61.1%) stddev-sans-first:3117.74 18:53:23 INFO - PROCESS | 1648 | Values: 1561.6 1602.1 1797.3 1926.2 1940.5 8343.5 8603.4 8796.6 8808.3 8817.3 7581.2 7789.2 8364.3 8575.4 8642.3 2332.2 2360.8 2365.3 2366.7 2368.9 2342.6 2344.8 2382.4 2383.9 2384.8 2077.8 2148.4 2309.0 2372.4 2376.1 2348.0 2360.6 2380.1 2385.6 2386.3 2354.5 2367.5 2390.6 2391.1 2391.3 8798.9 8906.8 8910.4 8916.3 8917.6 5018.3 5165.4 5174.0 5175.3 5194.5 3447.5 3530.8 3598.9 3609.5 3619.4 1058.5 1086.3 1099.5 1103.7 1794.0 11205.5 11457.9 11473.1 11474.0 11492.2 4154.0 4233.7 4295.1 4297.9 4483.8 8931.2 9004.0 9014.5 9022.1 9028.4 5112.2 5112.4 5115.9 5116.4 5121.8 4703.7 4789.8 4803.1 4812.0 4824.7 3538.7 3547.2 3567.0 3601.7 3635.5 934.9 1061.3 1098.4 1104.9 1105.7 805.8 884.5 893.9 904.4 1002.3 6090.0 6117.6 6170.0 6181.7 6325.8 937.4 948.4 957.7 996.0 1033.6 3893.8 4102.1 4120.4 4136.9 4179.7 8884.9 10187.8 10347.4 10451.1 10558.9 9720.2 10268.2 10490.1 10511.2 10622.3 8097.2 8315.4 8327.8 8337.7 8338.9 6051.9 6134.8 6142.0 6164.5 6227.2 10047.1 10055.2 10057.6 10069.1 10092.1 9762.2 9985.7 10295.7 10315.9 10318.9 7282.8 7590.1 7594.1 7673.2 7675.7 8271.0 8328.4 8386.4 8387.7 8390.9 6731.1 6833.1 7596.7 7676.2 7690.1 7436.5 7436.5 7487.2 7551.2 7576.1 7088.9 7091.1 7138.4 7140.4 7151.0 4153.8 5085.2 5120.9 5166.8 5253.9 5136.4 5162.5 5175.1 5186.4 5335.6 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4590.21 Median:5023.79 stddev:1426.71 (28.4%) stddev-sans-first:1393.65 18:53:23 INFO - PROCESS | 1648 | Values: 285.5 310.9 318.1 325.0 329.9 4299.4 4802.7 5499.7 5511.6 5594.8 4920.2 5032.8 5340.8 5385.3 5447.4 3480.8 3936.9 4072.7 4084.8 4225.3 3415.4 4142.9 4155.1 4194.5 4210.0 3791.8 3812.7 4117.2 4137.6 4141.4 3916.2 4006.3 4072.5 4192.9 4239.6 3653.1 3667.6 3669.5 3689.8 3692.7 4447.4 4963.6 5359.1 5366.0 5384.5 4487.3 4840.2 4882.2 4937.4 4957.8 4865.5 5053.8 5355.8 5784.7 5823.7 3444.7 4058.3 4063.5 4087.9 4117.4 5305.6 5563.4 5954.6 6067.9 6072.2 3390.9 3891.8 3931.6 3956.4 3979.5 3268.3 3536.6 3860.7 3875.5 4000.8 3928.9 4671.3 4692.8 4706.4 4716.4 5445.0 5724.7 5739.6 5783.7 5786.9 1282.4 1402.2 1451.4 1455.6 1458.7 826.4 869.4 904.9 908.5 926.0 4591.3 5187.2 5456.3 5485.7 5486.5 5993.0 6003.5 6017.9 6024.1 6031.1 5014.7 5402.6 5412.0 5452.6 5518.0 2541.0 2810.6 3023.9 3104.0 3154.6 4639.0 5478.5 5653.6 6159.8 6205.7 4978.6 5004.6 5135.5 6134.7 6137.9 5151.0 5925.7 6134.3 6137.2 6139.5 4950.9 5662.9 5836.9 5872.6 6008.2 5855.8 5900.4 5910.5 5911.9 5927.8 5714.9 5916.3 6133.5 6161.8 6205.3 3143.4 3399.5 3502.1 3508.9 3547.3 5073.8 5117.4 5136.0 5155.1 5171.0 4738.6 4917.6 4951.0 4961.8 4994.8 4952.5 5253.8 5869.3 5896.9 5910.4 4788.4 5258.4 5485.5 5684.5 5729.5 4930.0 5660.9 5781.3 5864.5 5885.7 4773.0 5609.9 5686.2 5718.5 5742.7 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:24906.57 Median:3544.25 stddev:39721.42 (1120.7%) stddev-sans-first:39791.07 18:53:23 INFO - PROCESS | 1648 | Values: 644.3 813.5 814.5 845.7 851.4 14954.8 16229.1 16262.7 16276.0 16286.8 14668.9 14696.4 14881.3 15409.9 16096.4 896.6 918.8 923.7 924.0 927.5 715.9 733.6 890.3 916.6 921.9 966.1 968.8 969.1 970.1 971.1 741.2 958.7 960.0 961.4 962.0 601.3 691.3 714.9 717.0 719.0 15277.8 15330.4 15349.1 15350.3 15376.0 13675.9 13733.8 13975.6 14064.4 14091.7 13268.5 13281.3 13343.7 13506.9 13818.9 4589.1 4594.2 4614.7 4693.9 4776.8 110022.0 111171.0 111580.9 111891.0 112315.0 11185.5 11554.2 11675.6 11691.9 11766.6 19747.6 21340.9 22560.8 22623.5 22627.0 8327.7 8342.6 8425.7 8489.2 8531.1 4545.7 4622.2 4634.6 4652.0 4659.6 3453.8 3634.7 3637.0 3644.9 3645.2 659.3 662.9 674.8 680.3 683.5 601.6 662.7 664.6 703.6 709.4 8399.8 9218.0 9541.0 9934.6 9957.8 603.7 644.8 664.4 680.9 683.1 4515.8 4678.6 4700.0 4718.2 4787.6 86576.7 98532.0 155437.0 160230.0 165280.0 102635.0 133625.0 135760.0 137217.3 140606.0 115662.0 118210.0 118956.0 119394.0 120538.0 6829.8 8983.5 9003.7 9161.5 9197.1 66472.0 66800.7 66863.0 67154.7 67783.3 80726.0 93274.5 93404.6 100486.0 101200.0 12146.4 12156.1 12197.7 12280.4 12411.6 11826.3 13473.2 13582.5 13765.9 14114.3 12329.4 12704.3 12775.1 12808.2 12903.9 12295.6 12484.5 12532.3 12710.4 12809.9 11522.7 12303.3 12369.9 12603.7 12651.0 10557.4 10585.5 10638.2 10826.9 10994.6 10123.8 10355.7 10451.8 10659.6 10714.5 18:53:23 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:53:23 INFO - PROCESS | 1648 | 18:53:23 INFO - TEST-INFO | 1648: exit 0 18:53:24 INFO - TEST-OK | dromaeo_css | took 1116235ms 18:53:24 INFO - TEST-START | kraken 18:53:24 INFO - operating with platform_type : mac_ 18:53:24 INFO - Initialising browser for kraken test... 18:53:24 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpF_QE3i/profile http://localhost:49267/getInfo.html) 18:53:26 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 18:53:26 INFO - PROCESS | 1657 | colorDepth:24 18:53:26 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 18:53:26 INFO - PROCESS | 1657 | __metrics 18:53:26 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:53:26 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:53:27 INFO - TEST-INFO | 1657: exit 0 18:53:27 INFO - Browser initialized. 18:53:27 INFO - Running cycle 1/1 for kraken test... 18:53:27 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpF_QE3i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:53:39 INFO - PROCESS | 1658 | RSS: Main: 244977664 18:53:39 INFO - PROCESS | 1658 | 18:53:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 18:53:42 INFO - PROCESS | 1658 | RSS: Main: 294043648 18:53:42 INFO - PROCESS | 1658 | 18:53:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 18:53:45 INFO - PROCESS | 1658 | RSS: Main: 305168384 18:53:45 INFO - PROCESS | 1658 | 18:53:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 18:53:49 INFO - PROCESS | 1658 | RSS: Main: 391053312 18:53:49 INFO - PROCESS | 1658 | 18:53:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 18:53:52 INFO - PROCESS | 1658 | RSS: Main: 291692544 18:53:52 INFO - PROCESS | 1658 | 18:53:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:53:55 INFO - PROCESS | 1658 | RSS: Main: 295841792 18:53:55 INFO - PROCESS | 1658 | 18:53:58 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 18:53:58 INFO - PROCESS | 1658 | RSS: Main: 334827520 18:53:58 INFO - PROCESS | 1658 | 18:54:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 18:54:02 INFO - PROCESS | 1658 | RSS: Main: 334548992 18:54:02 INFO - PROCESS | 1658 | 18:54:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 18:54:05 INFO - PROCESS | 1658 | RSS: Main: 331341824 18:54:05 INFO - PROCESS | 1658 | 18:54:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:54:07 INFO - PROCESS | 1658 | RSS: Main: 311549952 18:54:07 INFO - PROCESS | 1658 | 18:54:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:54:10 INFO - PROCESS | 1658 | RSS: Main: 318615552 18:54:10 INFO - PROCESS | 1658 | 18:54:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:54:13 INFO - PROCESS | 1658 | RSS: Main: 317825024 18:54:13 INFO - PROCESS | 1658 | 18:54:15 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:54:16 INFO - PROCESS | 1658 | RSS: Main: 325439488 18:54:16 INFO - PROCESS | 1658 | 18:54:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:54:20 INFO - PROCESS | 1658 | RSS: Main: 328151040 18:54:20 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 18:54:21 INFO - PROCESS | 1658 | RSS: Main: 371359744 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | __start_tp_report 18:54:21 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 18:54:21 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 18:54:21 INFO - PROCESS | 1658 | |i|pagename|runs| 18:54:21 INFO - PROCESS | 1658 | |0;ai-astar;101;111;111;112;112;112;111;111;111;113 18:54:21 INFO - PROCESS | 1658 | |1;audio-beat-detection;141;106;105;156;123;113;128;105;138;106 18:54:21 INFO - PROCESS | 1658 | |2;audio-dft;186;184;184;185;181;183;183;183;181;181 18:54:21 INFO - PROCESS | 1658 | |3;audio-fft;78;76;59;108;82;82;61;68;82;57 18:54:21 INFO - PROCESS | 1658 | |4;audio-oscillator;81;88;92;71;71;68;97;70;67;101 18:54:21 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;88;86;89;85;88;85;91;132;121;84 18:54:21 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;93;93;95;94;93;94;95;93;94 18:54:21 INFO - PROCESS | 1658 | |7;imaging-desaturate;84;84;87;84;86;87;87;90;86;87 18:54:21 INFO - PROCESS | 1658 | |8;json-parse-financial;51;50;53;51;54;53;51;52;51;51 18:54:21 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;66;77;81;77;72;72;72;72;71;74 18:54:21 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;83;83;84;83;83;80;84;82;83;93 18:54:21 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;138;137;132;148;121;134;137;138;221;134 18:54:21 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;163;164;161;164;163;163;163;160;162;160 18:54:21 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;66;67;67;65;66;66;65;65;65;64 18:54:21 INFO - PROCESS | 1658 | __end_tp_report 18:54:21 INFO - PROCESS | 1658 | __start_cc_report 18:54:21 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2007 18:54:21 INFO - PROCESS | 1658 | __end_cc_report 18:54:21 INFO - PROCESS | 1658 | __startTimestamp1468979661951__endTimestamp 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | ------- Summary: start ------- 18:54:21 INFO - PROCESS | 1658 | Number of tests: 14 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:3.41 (3.1%) stddev-sans-first:0.73 18:54:21 INFO - PROCESS | 1658 | Values: 101.0 111.0 111.0 112.0 112.0 112.0 111.0 111.0 111.0 113.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:125.50 stddev:18.20 (14.5%) stddev-sans-first:17.97 18:54:21 INFO - PROCESS | 1658 | Values: 141.0 106.0 105.0 156.0 123.0 113.0 128.0 105.0 138.0 106.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:1.73 (0.9%) stddev-sans-first:1.48 18:54:21 INFO - PROCESS | 1658 | Values: 186.0 184.0 184.0 185.0 181.0 183.0 183.0 183.0 181.0 181.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:75.30 Median:77.00 stddev:15.17 (19.7%) stddev-sans-first:16.05 18:54:21 INFO - PROCESS | 1658 | Values: 78.0 76.0 59.0 108.0 82.0 82.0 61.0 68.0 82.0 57.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:76.00 stddev:12.95 (17.0%) stddev-sans-first:13.74 18:54:21 INFO - PROCESS | 1658 | Values: 81.0 88.0 92.0 71.0 71.0 68.0 97.0 70.0 67.0 101.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:87.00 stddev:16.99 (19.5%) stddev-sans-first:17.83 18:54:21 INFO - PROCESS | 1658 | Values: 88.0 86.0 89.0 85.0 88.0 85.0 91.0 132.0 121.0 84.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 18:54:21 INFO - PROCESS | 1658 | Values: 94.0 93.0 93.0 95.0 94.0 93.0 94.0 95.0 93.0 94.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:87.00 stddev:1.87 (2.2%) stddev-sans-first:1.81 18:54:21 INFO - PROCESS | 1658 | Values: 84.0 84.0 87.0 84.0 86.0 87.0 87.0 90.0 86.0 87.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:51.50 stddev:1.25 (2.4%) stddev-sans-first:1.30 18:54:21 INFO - PROCESS | 1658 | Values: 51.0 50.0 53.0 51.0 54.0 53.0 51.0 52.0 51.0 51.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:73.00 stddev:4.12 (5.6%) stddev-sans-first:3.38 18:54:21 INFO - PROCESS | 1658 | Values: 66.0 77.0 81.0 77.0 72.0 72.0 72.0 72.0 71.0 74.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:83.00 stddev:3.43 (4.1%) stddev-sans-first:3.62 18:54:21 INFO - PROCESS | 1658 | Values: 83.0 83.0 84.0 83.0 83.0 80.0 84.0 82.0 83.0 93.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:137.50 stddev:27.86 (20.3%) stddev-sans-first:29.47 18:54:21 INFO - PROCESS | 1658 | Values: 138.0 137.0 132.0 148.0 121.0 134.0 137.0 138.0 221.0 134.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:1.49 (0.9%) stddev-sans-first:1.56 18:54:21 INFO - PROCESS | 1658 | Values: 163.0 164.0 161.0 164.0 163.0 163.0 163.0 160.0 162.0 160.0 18:54:21 INFO - PROCESS | 1658 | 18:54:21 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 18:54:21 INFO - PROCESS | 1658 | Values: 66.0 67.0 67.0 65.0 66.0 66.0 65.0 65.0 65.0 64.0 18:54:21 INFO - PROCESS | 1658 | -------- Summary: end -------- 18:54:21 INFO - PROCESS | 1658 | 18:54:22 INFO - TEST-INFO | 1658: exit 0 18:54:22 INFO - TEST-OK | kraken | took 58776ms 18:54:22 INFO - SUITE-END | took 1175s 18:54:23 INFO - Completed test suite (00:19:35) 18:54:23 INFO - javascript benchmark 18:54:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.3183097465017, 3325.3515564464924, 3326.2848487575166, 3375.611804642464, 3398.626151123376, 5652.830415087554, 5697.943020569798, 5722.570807189467, 5790.276215473066, 5862.706864656766, 4195.349720793282, 5677.630953987988, 5752.827415177551, 5767.971160144204, 5821.3305469870975, 5134.820281290155, 5186.325777648919, 5232.189010703328, 5257.421683614818, 5339.813106541272, 5346.03771321162, 5355.410904800468, 5364.7317634118335, 5407.729613519328, 5425.213344065108, 4877.9268310975185, 5136.0, 5198.2722418861385, 5210.635255532115, 5303.973480132613, 5229.895402091937, 5232.110541208004, 5232.58139348855, 5240.187770895517, 5245.370555533323, 5148.948510514884, 5181.145111056709, 5203.531682148587, 5208.817691380784, 5221.138512145491, 5644.294463192101, 5656.43435656431, 5715.114157305624, 5723.627964182315, 5728.770849166029, 6257.655828929407, 6334.778282760128, 6339.524535659844, 6341.397567231105, 6341.778037768702, 5259.86850328741, 6114.633122012649, 6262.185915830958, 6329.082283068929, 6349.841253968688, 5685.090385538294, 5762.625429347079, 5821.388754180834, 5826.621269617462, 5902.026165682701, 6285.874282514415, 6291.213598300213, 6292.056191571301, 6296.842578935563, 6305.211848518935, 6152.630842149485, 6181.350958149419, 6185.41238582334, 6188.1027251048345, 6191.659458729813, 6102.450779429784, 6215.720292586844, 6263.749450022039, 6274.309825919146, 6341.302456729756, 6272.5922815016875, 6278.30938596754, 6280.0, 6285.088662144055, 6303.779367722108, 6113.537117903944, 6166.229221347332, 6297.275813281532, 6310.873782524323, 6326.145970294044, 4906.067847109038, 4934.605231581465, 4977.477364876709, 4979.228219625964, 4980.178270585386, 4423.071155057474, 4427.22523558376, 4436.201483732959, 4444.11117776446, 4473.217186992263, 5622.240997465372, 5839.649621022667, 5865.032269675541, 5868.794592189253, 5872.7357268923, 6162.22972128484, 6238.844028899314, 6291.842703932438, 6323.494120470351, 6327.272363678144, 5838.153467747238, 5851.473367396955, 5873.676947767914, 5887.293524777054, 5917.05327147663, 4475.48531918827, 5432.2123292123215, 5438.5377242934, 5454.10007348783, 5464.18037294409, 6230.314665386803, 6268.216472940882, 6269.247690277196, 6303.810885673437, 6304.621722696653, 6319.494440444661, 6336.334684858023, 6349.174607301022, 6361.777337793155, 6363.713632886624, 6201.782937597162, 6289.150964619717, 6295.3704629538515, 6308.116863639002, 6314.0, 5956.493698035542, 6105.914670190187, 6145.832291864532, 6212.316645168938, 6244.7694576176555, 6323.08315294289, 6331.0, 6350.460210882126, 6354.650494222862, 6356.618602883842, 5997.070454079625, 6082.452579267887, 6171.9691401544505, 6182.258129024546, 6197.0084786435755, 5970.014947533608, 6024.246969128859, 6051.33435322123, 6127.922628246602, 6189.473894718999, 6008.399160083956, 6019.097135429721, 6023.819285421444, 6041.848953776191, 6075.726592303268, 5326.866828329323, 6045.818625441244, 6217.782377616762, 6235.937640623717, 6329.936700632935, 5886.146508756292, 5995.040793473024, 6013.849653758691, 6075.726592303268, 6080.939190608037], "value": 5717.803153386792, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.40424288732083, 520.3651545114958, 534.5269436548655, 547.2967237100327, 555.9638623489471, 22978.425539361524, 40999.975000624996, 41835.79082104589, 42054.57945420545, 42110.789446052724, 42716.359254611125, 43092.138157236834, 43278.783606081925, 43292.78353608227, 43430.78284608581, 8736.475811451333, 9709.514524273776, 9894.703158905244, 9904.603815847357, 9923.851142232872, 8665.220130188281, 11842.348670823101, 11961.7607647847, 12011.519539218421, 12243.69390765229, 11849.644510664652, 11881.465334059945, 11909.642710718692, 11920.7615847683, 11924.403779811018, 8748.168923952215, 11762.470688819023, 11893.643190704293, 11904.285742855456, 12009.219400739012, 11748.353840538766, 11789.292642441482, 11811.409429528489, 11818.586349477813, 11820.763584728344, 40677.98305042368, 41033.589664103274, 41062.794686026675, 41091.794541027404, 41210.0, 3538.135064286606, 3619.5837478690023, 3633.1462106404974, 4078.1843631273864, 4088.8364465421346, 31050.53424198639, 32180.195495112574, 32532.837335813405, 32556.6744332556, 32624.0, 3286.047046356554, 3414.0270023043313, 3438.5186073949667, 3445.896623101311, 3447.672471115265, 405241.0, 407851.0, 408111.0, 409311.0, 411063.0, 58928.11607825886, 59009.70495147497, 59305.110423343685, 59358.0, 59375.70312148412, 60652.39347606467, 60671.393286067454, 60925.69537152375, 60937.39062609406, 60940.695296523234, 16377.0992595406, 16643.001419914843, 16649.001059936443, 16656.75014874778, 16657.33370665184, 11740.823887641693, 11997.280163190062, 12739.426725797175, 12980.35098245084, 13033.869661303266, 15202.17573891118, 15282.923585382003, 15289.541313760605, 15305.693886122215, 15449.922750386177, 6022.247219097613, 6111.388861113853, 6141.140240366352, 6184.783532576428, 6188.257409110935, 766.5439063757055, 850.8425941200898, 909.6497848328441, 976.2190247801872, 987.550664447689, 30032.699673003426, 30112.698873011428, 30485.695143048284, 30993.0, 31002.68997310043, 1069.0485467933545, 1099.6865893220395, 1175.8059920113258, 1229.8708635593134, 1233.7470818482368, 12700.0, 15779.68440631181, 15782.289796959165, 15891.761623575656, 16074.598135046483, 663589.0, 670324.0, 671151.0, 672223.0, 673729.0, 58406.707966460745, 58722.70638646779, 58735.70632146898, 58771.0, 58853.0, 1152.1416544674105, 1277.9297138657464, 1300.772364836169, 1403.333416627094, 1454.3092031285266, 26450.67746612623, 28936.565951511166, 29992.4001519964, 30799.846000769852, 31317.686823131477, 49002.0, 49511.50488495213, 49963.0, 49965.25052124148, 50037.74981125071, 594186.0, 594789.0, 595898.0, 595967.0, 596088.0, 33754.831225843714, 34411.79558715433, 34871.0, 40713.59286407098, 40785.59214407937, 47345.28982065341, 48245.75877120592, 48855.267170991756, 49090.0, 49191.754041229564, 38076.428853566664, 38957.0, 39267.80366098265, 39520.01199970024, 40612.79693601513, 39474.80262598669, 39563.0, 39709.404358935215, 40142.59857401506, 40746.59253407428, 30437.0, 38026.619733802305, 38048.619513805614, 38600.613993860825, 38861.6113838858, 34253.29300907276, 34427.622895083914, 35332.64667353294, 35428.64571354356, 36142.63857361498, 21560.0, 25490.49019019572, 25855.741442585335, 26163.0, 26350.86824565865], "value": 20428.74454695362, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.85343728915205, 365.1765269317688, 394.0758920331821, 398.6113539299182, 411.53496548899756, 15782.921085394571, 15867.285972131252, 20612.278570250044, 21070.367888963316, 21083.94580270984, 20758.754474731526, 21294.57410851782, 21330.146794128217, 21353.0, 21637.891810540965, 5440.047991601474, 5916.2308899843065, 6060.787872424466, 6086.300075491331, 6099.359567245442, 6014.578979471439, 6056.60632058917, 6128.724207410655, 6131.785387511438, 6132.8466788330425, 4846.709197448165, 5943.049112142058, 5946.43508866657, 5955.34491205967, 6000.298373862445, 4843.685160464559, 5973.998710431989, 6000.699965001776, 6007.399260074001, 6031.095335699658, 5125.154349532323, 5144.0740666679985, 5163.277141200235, 5169.819056333048, 5173.9741301293625, 26243.719535058117, 26518.204453866416, 26601.201963941112, 26626.866865665743, 27019.189424317105, 2403.4592216751266, 2448.8775561222046, 2568.4092658688605, 2603.609458581209, 3053.2366908272934, 14763.77854332175, 15646.765298520531, 15763.054216746918, 15856.127912964783, 16315.755263671055, 1767.5846176148596, 1811.7916439609482, 1821.3989383503545, 1900.6000069364986, 1926.1428664244418, 78512.0, 88511.55744221238, 130491.0, 132096.0, 133020.33489832585, 8608.0, 10529.684109476728, 10989.780204395867, 11004.504797284142, 11254.7186320341, 11359.943200284111, 13561.932190338985, 13702.52041178574, 13708.93145534266, 13709.931450342883, 7183.42532597391, 7351.632418379059, 7366.410687145016, 7399.223081576357, 7426.554406735613, 6105.572609917263, 6751.898721519181, 6927.238003819576, 6934.306569343025, 6989.580625162506, 6474.255460621995, 6580.80257592273, 6584.5720028198975, 6588.505862060269, 6678.1318428604945, 1714.7427885817228, 1855.935042273514, 1893.9242430302909, 1899.990500047491, 1904.5048287445363, 861.8707193920962, 939.5396255834592, 993.7465946183821, 1029.8661174047327, 1032.6437379104198, 21586.78413215848, 22590.88704556467, 22626.321210363716, 22670.546589068457, 22708.253138232336, 929.8930622978444, 975.9560819763128, 995.9203263738883, 1031.7678522332565, 1079.3146352066337, 11325.830112548318, 11626.011788997861, 12084.274943503242, 12312.569060082855, 12358.876411235773, 567948.1602591961, 603523.0, 603827.0, 605581.0, 637742.0, 86109.0, 88311.55844220737, 144350.0, 146192.26903865414, 149081.0, 1002.5137808163036, 1004.8492726091145, 1014.4217795856439, 1018.6434747838346, 1020.0258752890971, 18576.164072617034, 18928.810711892704, 18971.0, 18971.715424268372, 18984.05079746035, 58186.4181358198, 59277.703611481666, 59431.702841485516, 60353.09470358036, 60677.39322606717, 73320.0, 88074.55962720401, 89759.0, 89867.10132898849, 90733.54633227056, 8034.276915077671, 8057.952466179359, 8336.666533338841, 8362.265648249133, 8456.746297611082, 10866.18503612248, 12169.0, 13024.934875325564, 13026.674333141747, 13273.734525309246, 10010.649627263303, 10449.895501045195, 11274.492647831032, 11420.0, 11444.313341199555, 11252.549898003988, 11266.324020558794, 11318.207725459139, 11425.428728563373, 11533.135014874086, 11258.268212566158, 11420.371879546705, 11632.418379080842, 11717.00405465545, 11726.413679315829, 8096.757097287091, 8161.102278749451, 8180.918190818015, 8185.79535511617, 8319.584020798815, 9927.354721943051, 11137.83293250618, 11151.609693660683, 11235.550577977116, 11422.714432139262], "value": 9783.533893737856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.6408226107994, 1602.1028224194451, 1797.2810875649739, 1926.2102537959431, 1940.5445915563328, 8343.54110523921, 8603.397762156652, 8796.648134074638, 8808.251298639616, 8817.250533704626, 7581.241875812416, 7789.182135875736, 8364.330853531717, 8575.356848236375, 8642.26540744036, 2332.2070496031347, 2360.8111351091957, 2365.2549446924163, 2366.7278262999716, 2368.928932132039, 2342.5666251743396, 2344.777246161612, 2382.4282172278704, 2383.9046438142454, 2384.821138414617, 2077.781832907546, 2148.4414052346347, 2308.9769102309015, 2372.4306166519996, 2376.0733314007553, 2347.988260058689, 2360.6223004319313, 2380.1074597026113, 2385.5825230584664, 2386.2602593195993, 2354.458474550863, 2367.5028244068767, 2390.5935990881594, 2391.1272385579314, 2391.330427480291, 8798.912010879872, 8906.821863562758, 8910.376273660846, 8916.331275154325, 8917.55412229386, 5018.322526458918, 5165.380154381461, 5174.0, 5175.327207463043, 5194.480551944813, 3447.500112483676, 3530.8058056806867, 3598.9100272493133, 3609.458581212813, 3619.402798538238, 1058.4760543531002, 1086.2884810449168, 1099.4777480696685, 1103.7020004598714, 1793.973090403645, 11205.495752691148, 11457.885421145766, 11473.08215342779, 11474.0, 11492.195546311761, 4154.0, 4233.682473814476, 4295.140971805651, 4297.892552686208, 4483.820647174141, 8931.151540603632, 9004.0, 9014.549272536478, 9022.097790220925, 9028.36801423894, 5112.182050871843, 5112.437631860493, 5115.87210319745, 5116.386033675983, 5121.820736274212, 4703.717776933395, 4789.808407663724, 4803.1114243864995, 4811.975940120277, 4824.710517368969, 3538.716902647782, 3547.166415892263, 3567.0, 3601.675849173587, 3635.4546817976984, 934.8597710343503, 1061.2889364126054, 1098.4123493930708, 1104.8839871813504, 1105.6959336182615, 805.778410936997, 884.5267781736868, 893.9061398553058, 904.3759805734022, 1002.2983911262146, 6090.0, 6117.602355846856, 6170.043643235305, 6181.690915454209, 6325.810225693236, 937.3953799799092, 948.3882895532342, 957.6504575829912, 996.0487734213808, 1033.560736686905, 3893.7858417787293, 4102.118044620421, 4120.402541631506, 4136.937945930814, 4179.707420480538, 8884.911150888538, 10187.796244075076, 10347.43089130105, 10451.111655509367, 10558.947205263925, 9720.222382209406, 10268.178545716324, 10490.05589496949, 10511.211659125596, 10622.256442049136, 8097.190280971973, 8315.417920745489, 8327.791805204797, 8337.749867503984, 8338.874916876252, 6051.939480605138, 6134.754609815557, 6142.04798256271, 6164.489391077191, 6227.19046523949, 10047.145992590711, 10055.19558435338, 10057.597696092367, 10069.144122749356, 10092.142167915516, 9762.219022478326, 9985.650502232387, 10295.742606434898, 10315.948420258152, 10318.896811032095, 7282.781516554512, 7590.0891892973195, 7594.088709354913, 7673.232676732506, 7675.692972281046, 8271.048829384697, 8328.417010809428, 8386.371022173484, 8387.706430274911, 8390.916090839257, 6731.091302674076, 6833.068678046805, 7596.734114305973, 7676.15562288159, 7690.115636701627, 7436.479446438698, 7436.479446438698, 7487.176410594939, 7551.169371369035, 7576.128745194374, 7088.893666595109, 7091.149062112373, 7138.428925685829, 7140.357367836919, 7150.96424517874, 4153.792310384409, 5085.186370180753, 5120.871978200575, 5166.819161329336, 5253.9211911820585, 5136.409312929061, 5162.5095615916425, 5175.14610089331, 5186.4294927557185, 5335.599830012687], "value": 4566.734827056486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.47900082349713, 310.8507916200224, 318.05378997482495, 324.9561309223254, 329.94061069007597, 4299.398084268205, 4802.663813533054, 5499.697516636588, 5511.614187006906, 5594.832155035345, 4920.195488571516, 5032.849014529561, 5340.786368545263, 5385.2999110115725, 5447.400785913546, 3480.8259587020675, 3936.921261574774, 4072.6741860651223, 4084.816183271745, 4225.302825033866, 3415.3510832941784, 4142.896427589304, 4155.106652069804, 4194.475690538683, 4209.957900420987, 3791.848326066954, 3812.7140464465137, 4117.238310912476, 4137.565555616662, 4141.440905477767, 3916.158026024404, 4006.317346714358, 4072.5316588592395, 4192.916141677179, 4239.6396306314, 3653.104989277644, 3667.5782285037294, 3669.504616876714, 3689.797061161635, 3692.7045836333295, 4447.399601053881, 4963.60291176705, 5359.088954877641, 5366.0, 5384.542313903323, 4487.349334346534, 4840.15297322967, 4882.2432522959, 4937.358143441365, 4957.7769000395065, 4865.5134486551415, 5053.823116190915, 5355.77735239875, 5784.710764461802, 5823.650580965155, 3444.7244220462303, 4058.3303754880408, 4063.4717486726536, 4087.9386809197886, 4117.423560701504, 5305.628605997582, 5563.443655634444, 5954.55340849438, 6067.939320606782, 6072.210612620375, 3390.881319153867, 3891.7664940103687, 3931.5871833457077, 3956.4460975463458, 3979.462772525672, 3268.346330733863, 3536.6109727929907, 3860.72596043305, 3875.5155605549307, 4000.7599544027453, 3928.862489812843, 4671.346011558385, 4692.788824502905, 4706.411698537683, 4716.443573620697, 5445.0, 5724.713764311768, 5739.560686708132, 5783.739731712083, 5786.855328616818, 1282.3780466473752, 1402.1797248609678, 1451.4266864588428, 1455.6069861137435, 1458.7017554376614, 826.367828611101, 869.4088020146361, 904.9321300902459, 908.5411867007119, 925.995370023159, 4591.324166679138, 5187.221916712448, 5456.317960254968, 5485.725713714378, 5486.506214440719, 5992.970035149883, 6003.459688627957, 6017.939820601738, 6024.14454748961, 6031.135210774847, 5014.724190169502, 5402.594805389612, 5411.9729401353525, 5452.645578037414, 5517.97917385285, 2541.0180059553577, 2810.564362523812, 3023.866050231163, 3104.037748298035, 3154.6214454265637, 4638.976805115953, 5478.493414311097, 5653.551333686448, 6159.798839226398, 6205.689715514206, 4978.6017118630425, 5004.624653151028, 5135.537801597874, 6134.650376917071, 6137.938620613826, 5151.016858509426, 5925.674087925206, 6134.325224225332, 6137.232845894263, 6139.526513636695, 4950.8762280943265, 5662.931676149905, 5836.9493491172, 5872.559558033079, 6008.218931538872, 5855.765769369348, 5900.439458251631, 5910.468057874812, 5911.940880591311, 5927.8221653350465, 5714.885702285847, 5916.290045194604, 6133.478654314434, 6161.784337548164, 6205.317415084247, 3143.3556120994685, 3399.467396789309, 3502.0719509330556, 3508.8947331580093, 3547.2728090741853, 5073.7675422813845, 5117.437081920912, 5135.974320128375, 5155.097857874782, 5170.991656626921, 4738.620910327097, 4917.606591472745, 4950.975245123751, 4961.82633607822, 4994.825181118788, 4952.47998960104, 5253.789848406021, 5869.325027621878, 5896.911546326723, 5910.433735060298, 4788.353572267699, 5258.368995720538, 5485.533729632866, 5684.516816070515, 5729.455701708331, 4930.0, 5660.858478537904, 5781.311690064141, 5864.472197502245, 5885.676287804212, 4772.976135119302, 5609.943900560942, 5686.208844214059, 5718.456746609066, 5742.684152371493], "value": 4140.709901039952, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.3105876711918, 813.4915677701437, 814.5397850214629, 845.699776579314, 851.3614788908319, 14954.775678364833, 16229.107399093045, 16262.674746505063, 16276.023438593678, 16286.755698664529, 14668.853311466883, 14696.412143514246, 14881.330340134693, 15409.92295038526, 16096.356145754156, 896.5741272895372, 918.7565295196778, 923.695180590407, 924.0, 927.4620719982428, 715.9427245820348, 733.6185183704471, 890.3278025091047, 916.5783739479814, 921.944683319003, 966.0918736387808, 968.825611389953, 969.1471505075524, 970.1026550440849, 971.0968799016947, 741.2365263778318, 958.7219706285169, 960.0495509445668, 961.3943215774052, 961.9899105938806, 601.335524245711, 691.3190507350256, 714.8820444626631, 716.9818857222756, 718.9892151617678, 15277.770833437507, 15330.386784528606, 15349.07905525672, 15350.309236084404, 15376.0, 13675.863241367659, 13733.793993090112, 13975.580732578039, 14064.367103480368, 14091.71816563663, 13268.535601254007, 13281.269530175836, 13343.733125337536, 13506.86493135066, 13818.861811381857, 4589.105124500724, 4594.2419500782335, 4614.653900957408, 4693.882652933704, 4776.761161941889, 110022.0, 111171.0, 111580.88419115786, 111891.0, 112315.0, 11185.49665265065, 11554.248973816675, 11675.649730508097, 11691.941540292244, 11766.647000589996, 19747.60504789896, 21340.93295335258, 22560.774392255866, 22623.547529049654, 22627.0, 8327.708530201415, 8342.582870856433, 8425.662973481114, 8489.151084891462, 8531.10423405546, 4545.704529205657, 4622.191116554657, 4634.559716826896, 4651.953480465219, 4659.5573420525, 3453.7755045922436, 3634.727395445352, 3636.9636303636626, 3644.9453258201147, 3645.198056427582, 659.2978477920999, 662.8508585568305, 674.8346655069441, 680.2755065854849, 683.54885775388, 601.6179725874014, 662.6587307536622, 664.6244871647503, 703.6200451755994, 709.4395427612232, 8399.790005249917, 9218.0, 9541.0, 9934.552945117486, 9957.800843983225, 603.7373742422061, 644.8291202831253, 664.4385494257344, 680.9377371300786, 683.1392445518583, 4515.751633660115, 4678.625709943196, 4699.979009026083, 4718.174319494075, 4787.640926930494, 86576.7013494798, 98532.0, 155437.0, 160230.0, 165280.0, 102635.0, 133625.0, 135760.0, 137217.3139134318, 140606.0, 115662.0, 118210.0, 118955.99999999827, 119394.0, 120538.0, 6829.795106146824, 8983.46099234048, 9003.684871029482, 9161.541922903694, 9197.12477459255, 66472.0, 66800.6659966697, 66863.0, 67154.66422667855, 67783.27192004488, 80725.9818504542, 93274.53362733143, 93404.59893101745, 100486.0, 101200.0, 12146.392680366123, 12156.149069565046, 12197.69505762363, 12280.38598070075, 12411.62765117048, 11826.34955077479, 13473.191608503521, 13582.524611638546, 13765.931170344084, 14114.2942852859, 12329.383530823603, 12704.3012634306, 12775.105742597929, 12808.167469114478, 12903.935480322538, 12295.631131066082, 12484.500619975099, 12532.310722910328, 12710.364481775689, 12809.935950320561, 11522.711932201762, 12303.323317217639, 12369.876301236873, 12603.68490787701, 12650.987920966485, 10557.41934193596, 10585.52365143555, 10638.202134840072, 10826.945865270938, 10994.560217591206, 10123.79752404933, 10355.741106472398, 10451.790964180826, 10659.573617055232, 10714.517846697074], "value": 7941.509502365498, "unit": "score"}], "name": "dromaeo_css", "value": 7454.74572540794, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 112.0, 112.0, 112.0, 111.0, 111.0, 111.0, 113.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 106.0, 105.0, 156.0, 123.0, 113.0, 128.0, 105.0, 138.0, 106.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 185.0, 181.0, 183.0, 183.0, 183.0, 181.0, 181.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 59.0, 108.0, 82.0, 82.0, 61.0, 68.0, 82.0, 57.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 88.0, 92.0, 71.0, 71.0, 68.0, 97.0, 70.0, 67.0, 101.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 88.0, 85.0, 91.0, 132.0, 121.0, 84.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 94.0, 93.0, 94.0, 95.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 84.0, 86.0, 87.0, 87.0, 90.0, 86.0, 87.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 53.0, 51.0, 54.0, 53.0, 51.0, 52.0, 51.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 81.0, 77.0, 72.0, 72.0, 72.0, 72.0, 71.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 83.0, 83.0, 80.0, 84.0, 82.0, 83.0, 93.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 132.0, 148.0, 121.0, 134.0, 137.0, 138.0, 221.0, 134.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 161.0, 164.0, 163.0, 163.0, 163.0, 160.0, 162.0, 160.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 66.0, 66.0, 65.0, 65.0, 65.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1427.3, "alertThreshold": 2.0}]} 18:54:23 INFO - Return code: 0 18:54:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:54:23 INFO - # TBPL SUCCESS # 18:54:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:54:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:54:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:54:23 INFO - 2016-07-19 18:54:23.290 screenresolution[1662:9497] starting screenresolution argv=screenresolution get 18:54:23 INFO - 2016-07-19 18:54:23.292 screenresolution[1662:9497] Display 0: 1600x1200x32@60 18:54:23 INFO - 2016-07-19 18:54:23.298 screenresolution[1663:9501] starting screenresolution argv=screenresolution list 18:54:23 INFO - Available Modes on Display 0 18:54:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:54:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:54:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:54:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:54:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:54:23 INFO - Intel Iris: 18:54:23 INFO - Chipset Model: Intel Iris 18:54:23 INFO - Type: GPU 18:54:23 INFO - Bus: Built-In 18:54:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:54:23 INFO - Vendor: Intel (0x8086) 18:54:23 INFO - Device ID: 0x0a2e 18:54:23 INFO - Revision ID: 0x0009 18:54:23 INFO - Displays: 18:54:23 INFO - Display: 18:54:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:54:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:54:23 INFO - Display Serial Number: 1600x1200 60 18:54:23 INFO - Main Display: Yes 18:54:23 INFO - Mirror: Off 18:54:23 INFO - Online: Yes 18:54:23 INFO - Rotation: Supported 18:54:23 INFO - Return code: 0 18:54:23 INFO - Running post-action listener: _resource_record_post_action 18:54:23 INFO - ##### 18:54:23 INFO - ##### Finished run-tests step (success) 18:54:23 INFO - ##### 18:54:23 INFO - Running post-run listener: _resource_record_post_run 18:54:23 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36656640; Write bytes: 705903104; Read time: 386; Write time: 1942 18:54:23 INFO - TinderboxPrint: CPU usage
53.7% 18:54:23 INFO - TinderboxPrint: I/O read bytes / time
36,656,640 / 386 18:54:23 INFO - TinderboxPrint: I/O write bytes / time
705,903,104 / 1,942 18:54:23 INFO - TinderboxPrint: CPU idle
2,240.8 (46.3%) 18:54:23 INFO - TinderboxPrint: CPU system
972.1 (20.1%) 18:54:23 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 18:54:23 INFO - TinderboxPrint: Swap in / out
444,047,360 / 0 18:54:23 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393052672; Write bytes: 387758080; Read time: 29250; Write time: 730 18:54:23 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35689984; Write bytes: 295059968; Read time: 371; Write time: 1164 18:54:23 INFO - Running post-run listener: _upload_blobber_files 18:54:23 INFO - Blob upload gear active. 18:54:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:54:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:54:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:54:24 INFO - (blobuploader) - INFO - Open directory for files ... 18:54:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:54:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:54:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:54:25 INFO - (blobuploader) - INFO - Done attempting. 18:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:54:26 INFO - (blobuploader) - INFO - Done attempting. 18:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:54:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:54:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:54:29 INFO - (blobuploader) - INFO - Done attempting. 18:54:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:54:29 INFO - Return code: 0 18:54:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:54:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:54:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/206bc4b7f8d950879a1911e7a3685df44a9dcfc60968dae4326fce820b1d5bfd33169bbd6ccbd823a7a97a94b23ca3a911dfa6de8f328b3c9c507b9ce292df81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/643981db2ed5e0cf423b7e1b09169f3db27708128d8cf5a3312fa337fd7c98d664a694cb94761d8f2b4961e85146406f8f5595b786005fbbb94e10b73f1d902a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0d8789f5627f1d3bb823b0c94780b102b7a0159c4e43f7d9e5679909079e75f319b6574d5262b30863c3a96f26222ab60af60cee24a970d73527a0952605f6"} 18:54:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:54:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:54:29 INFO - Contents: 18:54:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/206bc4b7f8d950879a1911e7a3685df44a9dcfc60968dae4326fce820b1d5bfd33169bbd6ccbd823a7a97a94b23ca3a911dfa6de8f328b3c9c507b9ce292df81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/643981db2ed5e0cf423b7e1b09169f3db27708128d8cf5a3312fa337fd7c98d664a694cb94761d8f2b4961e85146406f8f5595b786005fbbb94e10b73f1d902a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0d8789f5627f1d3bb823b0c94780b102b7a0159c4e43f7d9e5679909079e75f319b6574d5262b30863c3a96f26222ab60af60cee24a970d73527a0952605f6"} 18:54:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:54:29 INFO - Copying logs to upload dir... 18:54:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:54:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.381873 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-19 18:54:29.257843) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 18:54:29.261329) ========= 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.gAUhiKNbXT/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.alDUrYnk7i/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/206bc4b7f8d950879a1911e7a3685df44a9dcfc60968dae4326fce820b1d5bfd33169bbd6ccbd823a7a97a94b23ca3a911dfa6de8f328b3c9c507b9ce292df81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/643981db2ed5e0cf423b7e1b09169f3db27708128d8cf5a3312fa337fd7c98d664a694cb94761d8f2b4961e85146406f8f5595b786005fbbb94e10b73f1d902a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0d8789f5627f1d3bb823b0c94780b102b7a0159c4e43f7d9e5679909079e75f319b6574d5262b30863c3a96f26222ab60af60cee24a970d73527a0952605f6"} build_url:https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/alb64fjPTVSsmMawNU9MKQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/206bc4b7f8d950879a1911e7a3685df44a9dcfc60968dae4326fce820b1d5bfd33169bbd6ccbd823a7a97a94b23ca3a911dfa6de8f328b3c9c507b9ce292df81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/643981db2ed5e0cf423b7e1b09169f3db27708128d8cf5a3312fa337fd7c98d664a694cb94761d8f2b4961e85146406f8f5595b786005fbbb94e10b73f1d902a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0d8789f5627f1d3bb823b0c94780b102b7a0159c4e43f7d9e5679909079e75f319b6574d5262b30863c3a96f26222ab60af60cee24a970d73527a0952605f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 18:54:29.295173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 18:54:29.295728) ========= 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.gAUhiKNbXT/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.alDUrYnk7i/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.005079 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 18:54:29.345051) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 18:54:29.345420) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 18:54:29.345822) ========= ========= Total master_lag: 0.18 =========