builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1467832771.46 results: success (0) buildid: 20160706101143 builduid: ee359ac03b0f4b72828703fee84e8bc6 revision: 372bb24e7acdd5d5baeaa548decc1f9a5c5504ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.462487) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.462925) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.463214) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.574819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.575139) ========= 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.vQr7Z3roia/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.pNRtgoys2p/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-06 12:19:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-07-06 12:19:31 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137444 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.734863) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.735180) ========= 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.vQr7Z3roia/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.pNRtgoys2p/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.027716 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:31.783835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 12:19:31.784190) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 372bb24e7acdd5d5baeaa548decc1f9a5c5504ba --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 372bb24e7acdd5d5baeaa548decc1f9a5c5504ba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vQr7Z3roia/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.pNRtgoys2p/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-06 12:19:31,859 truncating revision to first 12 chars 2016-07-06 12:19:31,859 Setting DEBUG logging. 2016-07-06 12:19:31,859 attempt 1/10 2016-07-06 12:19:31,859 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/372bb24e7acd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 12:19:32,973 unpacking tar archive at: autoland-372bb24e7acd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.393949 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 12:19:33.200181) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:33.200543) ========= script_repo_revision: 372bb24e7acdd5d5baeaa548decc1f9a5c5504ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:33.200979) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:33.201333) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 12:19:33.220365) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-06 12:19:33.220667) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vQr7Z3roia/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.pNRtgoys2p/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:19:33 INFO - MultiFileLogger online at 20160706 12:19:33 in /builds/slave/test 12:19:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 12:19:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:19:33 INFO - {'append_to_log': False, 12:19:33 INFO - 'base_work_dir': '/builds/slave/test', 12:19:33 INFO - 'blob_upload_branch': 'Autoland', 12:19:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:19:33 INFO - 'branch': 'Autoland', 12:19:33 INFO - 'buildbot_json_path': 'buildprops.json', 12:19:33 INFO - 'config_files': ('talos/mac_config.py',), 12:19:33 INFO - 'default_actions': ('clobber', 12:19:33 INFO - 'read-buildbot-config', 12:19:33 INFO - 'download-and-extract', 12:19:33 INFO - 'populate-webroot', 12:19:33 INFO - 'create-virtualenv', 12:19:33 INFO - 'install', 12:19:33 INFO - 'run-tests'), 12:19:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:19:33 INFO - 'download_minidump_stackwalk': True, 12:19:33 INFO - 'download_symbols': 'ondemand', 12:19:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:19:33 INFO - 'tooltool.py': '/tools/tooltool.py', 12:19:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:19:33 INFO - '/tools/misc-python/virtualenv.py')}, 12:19:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:19:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:19:33 INFO - 'installer_path': 'installer.exe', 12:19:33 INFO - 'log_level': 'info', 12:19:33 INFO - 'log_name': 'talos', 12:19:33 INFO - 'log_to_console': True, 12:19:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:19:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:19:33 INFO - 'opt_config_files': (), 12:19:33 INFO - 'pip_index': False, 12:19:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:19:33 INFO - 'cmd': ('bash', 12:19:33 INFO - '-c', 12:19:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:19:33 INFO - 'enabled': True, 12:19:33 INFO - 'halt_on_failure': False, 12:19:33 INFO - 'name': 'check_screen_resolution'},), 12:19:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:19:33 INFO - 'cmd': ('bash', 12:19:33 INFO - '-c', 12:19:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:19:33 INFO - 'enabled': True, 12:19:33 INFO - 'halt_on_failure': False, 12:19:33 INFO - 'name': 'check_screen_resolution'},), 12:19:33 INFO - 'run_cmd_checks_enabled': True, 12:19:33 INFO - 'sps_profile': False, 12:19:33 INFO - 'sps_profile_interval': 0, 12:19:33 INFO - 'suite': 'dromaeojs', 12:19:33 INFO - 'system_bits': '32', 12:19:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:19:33 INFO - 'title': 't-yosemite-r7-0074', 12:19:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:19:33 INFO - 'use_talos_json': True, 12:19:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:19:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:19:33 INFO - 'work_dir': 'build'} 12:19:33 INFO - ##### 12:19:33 INFO - ##### Running clobber step. 12:19:33 INFO - ##### 12:19:33 INFO - Running pre-action listener: _resource_record_pre_action 12:19:33 INFO - Running main action method: clobber 12:19:33 INFO - rmtree: /builds/slave/test/build 12:19:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:19:35 INFO - Running post-action listener: _resource_record_post_action 12:19:35 INFO - ##### 12:19:35 INFO - ##### Finished clobber step (success) 12:19:35 INFO - ##### 12:19:35 INFO - ##### 12:19:35 INFO - ##### Running read-buildbot-config step. 12:19:35 INFO - ##### 12:19:35 INFO - Running pre-action listener: _resource_record_pre_action 12:19:35 INFO - Running main action method: read_buildbot_config 12:19:35 INFO - Using buildbot properties: 12:19:35 INFO - { 12:19:35 INFO - "project": "", 12:19:35 INFO - "product": "firefox", 12:19:35 INFO - "script_repo_revision": "production", 12:19:35 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 12:19:35 INFO - "repository": "", 12:19:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 12:19:35 INFO - "buildid": "20160706101143", 12:19:35 INFO - "pgo_build": "False", 12:19:35 INFO - "basedir": "/builds/slave/test", 12:19:35 INFO - "buildnumber": 21, 12:19:35 INFO - "slavename": "t-yosemite-r7-0074", 12:19:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:19:35 INFO - "platform": "yosemite_r7", 12:19:35 INFO - "scheduler": "tests-autoland-macosx64-talos", 12:19:35 INFO - "branch": "autoland", 12:19:35 INFO - "repo_path": "integration/autoland", 12:19:35 INFO - "revision": "372bb24e7acdd5d5baeaa548decc1f9a5c5504ba", 12:19:35 INFO - "stage_platform": "macosx64", 12:19:35 INFO - "builduid": "ee359ac03b0f4b72828703fee84e8bc6", 12:19:35 INFO - "slavebuilddir": "test" 12:19:35 INFO - } 12:19:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:19:35 INFO - Running post-action listener: _resource_record_post_action 12:19:35 INFO - ##### 12:19:35 INFO - ##### Finished read-buildbot-config step (success) 12:19:35 INFO - ##### 12:19:35 INFO - ##### 12:19:35 INFO - ##### Running download-and-extract step. 12:19:35 INFO - ##### 12:19:35 INFO - Running pre-action listener: _resource_record_pre_action 12:19:35 INFO - Running main action method: download_and_extract 12:19:35 INFO - mkdir: /builds/slave/test/build/tests 12:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:19:35 INFO - https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:19:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:19:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:19:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:37 INFO - Downloaded 1199 bytes. 12:19:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:19:37 INFO - Using the following test package requirements: 12:19:37 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:19:37 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:19:37 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:19:37 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'jsshell-mac.zip'], 12:19:37 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:19:37 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:19:37 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:19:37 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:19:37 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:19:37 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:19:37 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:19:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:19: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')]} 12:19:37 INFO - https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:19:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:19:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:19:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:38 INFO - Downloaded 18804809 bytes. 12:19:38 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'] 12:19:38 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 12:19:41 INFO - Return code: 0 12:19:41 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 12:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:19:41 INFO - https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:19:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:19:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:19:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:48 INFO - Downloaded 18804809 bytes. 12:19:48 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'] 12:19:48 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 12:19:52 INFO - Return code: 0 12:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:19:52 INFO - https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:19:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:19:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:19:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:53 INFO - Downloaded 14403333 bytes. 12:19:53 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'] 12:19:53 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 12:19:54 INFO - Return code: 0 12:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:54 INFO - https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:19:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:19:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:19:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O785NOX-Qg2PidnepmuYyw/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 12:19:57 INFO - Downloaded 112411148 bytes. 12:19:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:19:57 INFO - mkdir: /builds/slave/test/properties 12:19:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:19:57 INFO - Writing to file /builds/slave/test/properties/build_url 12:19:57 INFO - Contents: 12:19:57 INFO - build_url:https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:19:58 INFO - Running post-action listener: _resource_record_post_action 12:19:58 INFO - Running post-action listener: set_extra_try_arguments 12:19:58 INFO - ##### 12:19:58 INFO - ##### Finished download-and-extract step (success) 12:19:58 INFO - ##### 12:19:58 INFO - ##### 12:19:58 INFO - ##### Running populate-webroot step. 12:19:58 INFO - ##### 12:19:58 INFO - Running pre-action listener: _resource_record_pre_action 12:19:58 INFO - Running main action method: populate_webroot 12:19:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:19:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:19:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:19:58 INFO - '--tppagecycles', 12:19:58 INFO - '7'], 12:19:58 INFO - 'tests': ['tsvgm']}}, 12:19:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:19:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:19:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:19:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:19:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['damp', 'tps']}, 12:19:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'tests': ['damp', 'tps']}, 12:19:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['dromaeo_dom']}, 12:19:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:19:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['basic_compositor_video']}, 12:19:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:19:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['a11yr', 12:19:58 INFO - 'ts_paint', 12:19:58 INFO - 'tpaint', 12:19:58 INFO - 'sessionrestore', 12:19:58 INFO - 'sessionrestore_no_auto_restore', 12:19:58 INFO - 'tabpaint']}, 12:19:58 INFO - 'other-e10s': {'tests': ['a11yr', 12:19:58 INFO - 'ts_paint', 12:19:58 INFO - 'tpaint', 12:19:58 INFO - 'sessionrestore', 12:19:58 INFO - 'sessionrestore_no_auto_restore', 12:19:58 INFO - 'tabpaint']}, 12:19:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:19:58 INFO - 'ts_paint', 12:19:58 INFO - 'tpaint', 12:19:58 INFO - 'sessionrestore', 12:19:58 INFO - 'sessionrestore_no_auto_restore', 12:19:58 INFO - 'tabpaint']}, 12:19:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:19:58 INFO - 'ts_paint', 12:19:58 INFO - 'tpaint', 12:19:58 INFO - 'sessionrestore', 12:19:58 INFO - 'sessionrestore_no_auto_restore', 12:19:58 INFO - 'tabpaint']}, 12:19:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['a11yr', 12:19:58 INFO - 'ts_paint', 12:19:58 INFO - 'tpaint', 12:19:58 INFO - 'sessionrestore', 12:19:58 INFO - 'sessionrestore_no_auto_restore', 12:19:58 INFO - 'tabpaint']}, 12:19:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['a11yr', 12:19:58 INFO - 'ts_paint', 12:19:58 INFO - 'tpaint', 12:19:58 INFO - 'sessionrestore', 12:19:58 INFO - 'sessionrestore_no_auto_restore', 12:19:58 INFO - 'tabpaint']}, 12:19:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['tsvgx', 12:19:58 INFO - 'tsvgr_opacity', 12:19:58 INFO - 'tart', 12:19:58 INFO - 'tscrollx', 12:19:58 INFO - 'cart']}, 12:19:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:19:58 INFO - 'tsvgr_opacity', 12:19:58 INFO - 'tart', 12:19:58 INFO - 'tscrollx', 12:19:58 INFO - 'cart']}, 12:19:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'talos_options': ['--disable-e10s'], 12:19:58 INFO - 'tests': ['tp5o']}, 12:19:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'tests': ['tp5o']}, 12:19:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'talos_options': ['--disable-e10s', 12:19:58 INFO - '--xperf_path', 12:19:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:58 INFO - 'tests': ['tp5n']}, 12:19:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:58 INFO - 'talos_options': ['--xperf_path', 12:19:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:58 INFO - 'tests': ['tp5n']}}, 12:19:58 INFO - 'talos.zip': {'path': '', 12:19:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:19:58 INFO - Running post-action listener: _resource_record_post_action 12:19:58 INFO - ##### 12:19:58 INFO - ##### Finished populate-webroot step (success) 12:19:58 INFO - ##### 12:19:58 INFO - ##### 12:19:58 INFO - ##### Running create-virtualenv step. 12:19:58 INFO - ##### 12:19:58 INFO - Running pre-action listener: _resource_record_pre_action 12:19:58 INFO - Running main action method: create_virtualenv 12:19:58 INFO - Creating virtualenv /builds/slave/test/build/venv 12:19:58 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 12:19:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:19:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:19:58 INFO - Using real prefix '/tools/python27' 12:19:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:19:59 INFO - Installing distribute.............................................................................................................................................................................................done. 12:20:01 INFO - Installing pip.................done. 12:20:01 INFO - Return code: 0 12:20:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:01 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:01 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 12:20:01 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 12:20:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:01 INFO - 'HOME': '/Users/cltbld', 12:20:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:01 INFO - 'LOGNAME': 'cltbld', 12:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:01 INFO - 'MOZ_NO_REMOTE': '1', 12:20:01 INFO - 'NO_EM_RESTART': '1', 12:20:01 INFO - 'PAGER': '/bin/cat', 12:20:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:01 INFO - 'PWD': '/builds/slave/test', 12:20:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:01 INFO - 'SHELL': '/bin/bash', 12:20:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:01 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:01 INFO - 'USER': 'cltbld', 12:20:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:01 INFO - 'XPC_FLAGS': '0x0', 12:20:01 INFO - 'XPC_SERVICE_NAME': '0', 12:20:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:02 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 12:20:02 INFO - Cleaning up... 12:20:02 INFO - Return code: 0 12:20:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:20: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')]} 12:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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')]} 12:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:02 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:02 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 12:20:02 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 12:20:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:02 INFO - 'HOME': '/Users/cltbld', 12:20:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:02 INFO - 'LOGNAME': 'cltbld', 12:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:02 INFO - 'MOZ_NO_REMOTE': '1', 12:20:02 INFO - 'NO_EM_RESTART': '1', 12:20:02 INFO - 'PAGER': '/bin/cat', 12:20:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:02 INFO - 'PWD': '/builds/slave/test', 12:20:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:02 INFO - 'SHELL': '/bin/bash', 12:20:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:02 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:02 INFO - 'USER': 'cltbld', 12:20:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:02 INFO - 'XPC_FLAGS': '0x0', 12:20:02 INFO - 'XPC_SERVICE_NAME': '0', 12:20:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:02 INFO - Downloading/unpacking psutil>=3.1.1 12:20:02 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 12:20:02 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 12:20:02 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 12:20:02 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 12:20:02 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 12:20:02 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 12:20:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:20:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:20:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:20:05 INFO - Installing collected packages: psutil 12:20:05 INFO - Running setup.py install for psutil 12:20:05 INFO - building 'psutil._psutil_osx' extension 12:20:05 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 12:20:05 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 12:20:05 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 12:20:05 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 12:20:05 INFO - building 'psutil._psutil_posix' extension 12:20:05 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 12:20:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:20:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:20:06 INFO - ^ 12:20:06 INFO - 1 warning generated. 12:20:06 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 12:20:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:20:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:20:06 INFO - Successfully installed psutil 12:20:06 INFO - Cleaning up... 12:20:06 INFO - Return code: 0 12:20:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:20: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')]} 12:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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')]} 12:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:06 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:06 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 12:20:06 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 12:20:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:06 INFO - 'HOME': '/Users/cltbld', 12:20:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:06 INFO - 'LOGNAME': 'cltbld', 12:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:06 INFO - 'MOZ_NO_REMOTE': '1', 12:20:06 INFO - 'NO_EM_RESTART': '1', 12:20:06 INFO - 'PAGER': '/bin/cat', 12:20:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:06 INFO - 'PWD': '/builds/slave/test', 12:20:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:06 INFO - 'SHELL': '/bin/bash', 12:20:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:06 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:06 INFO - 'USER': 'cltbld', 12:20:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:06 INFO - 'XPC_FLAGS': '0x0', 12:20:06 INFO - 'XPC_SERVICE_NAME': '0', 12:20:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:20:06 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 12:20:06 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 12:20:06 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 12:20:06 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 12:20:06 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 12:20:06 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 12:20:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:20:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:20:09 INFO - Installing collected packages: mozsystemmonitor 12:20:09 INFO - Running setup.py install for mozsystemmonitor 12:20:09 INFO - Successfully installed mozsystemmonitor 12:20:09 INFO - Cleaning up... 12:20:09 INFO - Return code: 0 12:20:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:09 INFO - 'HOME': '/Users/cltbld', 12:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:09 INFO - 'LOGNAME': 'cltbld', 12:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:09 INFO - 'MOZ_NO_REMOTE': '1', 12:20:09 INFO - 'NO_EM_RESTART': '1', 12:20:09 INFO - 'PAGER': '/bin/cat', 12:20:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:09 INFO - 'PWD': '/builds/slave/test', 12:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:09 INFO - 'SHELL': '/bin/bash', 12:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:09 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:09 INFO - 'USER': 'cltbld', 12:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:09 INFO - 'XPC_FLAGS': '0x0', 12:20:09 INFO - 'XPC_SERVICE_NAME': '0', 12:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:09 INFO - Downloading/unpacking jsonschema==2.5.1 12:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:20:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:20:11 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 12:20:11 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 12:20:11 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 12:20:11 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 12:20:11 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 12:20:11 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 12:20:12 INFO - Downloading functools32-3.2.3-2.tar.gz 12:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:20:12 INFO - warning: no files found matching '*.txt' 12:20:12 INFO - no previously-included directories found matching 'build' 12:20:12 INFO - no previously-included directories found matching 'dist' 12:20:12 INFO - no previously-included directories found matching '.git*' 12:20:12 INFO - Installing collected packages: jsonschema, functools32 12:20:12 INFO - Running setup.py install for functools32 12:20:12 INFO - warning: no files found matching '*.txt' 12:20:12 INFO - no previously-included directories found matching 'build' 12:20:12 INFO - no previously-included directories found matching 'dist' 12:20:12 INFO - no previously-included directories found matching '.git*' 12:20:12 INFO - Successfully installed jsonschema functools32 12:20:12 INFO - Cleaning up... 12:20:12 INFO - Return code: 0 12:20:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:20: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')]} 12:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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')]} 12:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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', '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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:20: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 blobuploader==1.2.4 12:20:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:12 INFO - 'HOME': '/Users/cltbld', 12:20:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:12 INFO - 'LOGNAME': 'cltbld', 12:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:12 INFO - 'MOZ_NO_REMOTE': '1', 12:20:12 INFO - 'NO_EM_RESTART': '1', 12:20:12 INFO - 'PAGER': '/bin/cat', 12:20:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:12 INFO - 'PWD': '/builds/slave/test', 12:20:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:12 INFO - 'SHELL': '/bin/bash', 12:20:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:12 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:12 INFO - 'USER': 'cltbld', 12:20:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:12 INFO - 'XPC_FLAGS': '0x0', 12:20:12 INFO - 'XPC_SERVICE_NAME': '0', 12:20:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:12 INFO - Downloading/unpacking blobuploader==1.2.4 12:20:12 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 12:20:12 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 12:20:12 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 12:20:12 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 12:20:12 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 12:20:12 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 12:20:15 INFO - Downloading blobuploader-1.2.4.tar.gz 12:20:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:20:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:20:15 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 12:20:15 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 12:20:15 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 12:20:15 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 12:20:15 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 12:20:15 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 12:20:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:20:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:20:16 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 12:20:16 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 12:20:16 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 12:20:16 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 12:20:16 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 12:20:16 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 12:20:16 INFO - Downloading docopt-0.6.1.tar.gz 12:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:20:16 INFO - Installing collected packages: blobuploader, requests, docopt 12:20:16 INFO - Running setup.py install for blobuploader 12:20:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:20:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:20:16 INFO - Running setup.py install for requests 12:20:17 INFO - Running setup.py install for docopt 12:20:17 INFO - Successfully installed blobuploader requests docopt 12:20:17 INFO - Cleaning up... 12:20:17 INFO - Return code: 0 12:20:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:17 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:17 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 12:20:17 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 12:20:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:17 INFO - 'HOME': '/Users/cltbld', 12:20:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:17 INFO - 'LOGNAME': 'cltbld', 12:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:17 INFO - 'MOZ_NO_REMOTE': '1', 12:20:17 INFO - 'NO_EM_RESTART': '1', 12:20:17 INFO - 'PAGER': '/bin/cat', 12:20:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:17 INFO - 'PWD': '/builds/slave/test', 12:20:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:17 INFO - 'SHELL': '/bin/bash', 12:20:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:17 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:17 INFO - 'USER': 'cltbld', 12:20:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:17 INFO - 'XPC_FLAGS': '0x0', 12:20:17 INFO - 'XPC_SERVICE_NAME': '0', 12:20:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:20:17 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-_E1oiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:20:17 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-sCt0sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TCVRJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ne5UqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-4RaPvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GjXfs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-KYspOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-bbrh28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BBGrsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-xSZQmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-fJ0_u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:20:18 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-HetZAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:20:19 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ObB6so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:20:19 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vnfl4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:19 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-05ZCvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:20:19 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vtwJmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:20:19 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BRaJm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:20:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:20:19 INFO - Running setup.py install for manifestparser 12:20:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:20:19 INFO - Running setup.py install for mozcrash 12:20:19 INFO - Running setup.py install for mozdebug 12:20:19 INFO - Running setup.py install for mozdevice 12:20:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Running setup.py install for mozfile 12:20:20 INFO - Running setup.py install for mozhttpd 12:20:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Running setup.py install for mozinfo 12:20:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Running setup.py install for mozInstall 12:20:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:20:20 INFO - Running setup.py install for mozleak 12:20:20 INFO - Running setup.py install for mozlog 12:20:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:20:21 INFO - Running setup.py install for moznetwork 12:20:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:20:21 INFO - Running setup.py install for mozprocess 12:20:21 INFO - Running setup.py install for mozprofile 12:20:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:20:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:20:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:20:21 INFO - Running setup.py install for mozrunner 12:20:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:20:21 INFO - Running setup.py install for mozscreenshot 12:20:21 INFO - Running setup.py install for moztest 12:20:21 INFO - Running setup.py install for mozversion 12:20:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:20:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:20:22 INFO - Cleaning up... 12:20:22 INFO - Return code: 0 12:20:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20:22 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')]} 12:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:22 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')]} 12:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:22 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:22 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 12:20:22 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 12:20:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:22 INFO - 'HOME': '/Users/cltbld', 12:20:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:22 INFO - 'LOGNAME': 'cltbld', 12:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:22 INFO - 'MOZ_NO_REMOTE': '1', 12:20:22 INFO - 'NO_EM_RESTART': '1', 12:20:22 INFO - 'PAGER': '/bin/cat', 12:20:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:22 INFO - 'PWD': '/builds/slave/test', 12:20:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:22 INFO - 'SHELL': '/bin/bash', 12:20:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:22 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:22 INFO - 'USER': 'cltbld', 12:20:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:22 INFO - 'XPC_FLAGS': '0x0', 12:20:22 INFO - 'XPC_SERVICE_NAME': '0', 12:20:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:20:22 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-MJb8Pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:20:22 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)) 12:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:20:22 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-bl_e3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:20:22 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)) 12:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:20:22 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QVtRSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:20:22 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)) 12:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:20:22 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-JVgGzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:20:22 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)) 12:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:20:22 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-B62qOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:20:22 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)) 12:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:20:22 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-bO86N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GFVG9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-kShlBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-mjLGlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6hbHjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-PQOIsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QhvCP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NL3P1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-dxhDBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-lL3oN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:20:23 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)) 12:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:20:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Tvuvfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:20:24 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)) 12:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:20:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-iWEv_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:20:24 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)) 12:20:24 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)) 12:20:24 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)) 12:20:24 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)) 12:20:24 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)) 12:20:24 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)) 12:20:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:20:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:20:26 INFO - Downloading blessings-1.6.tar.gz 12:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:20:26 INFO - Installing collected packages: blessings 12:20:26 INFO - Running setup.py install for blessings 12:20:26 INFO - Successfully installed blessings 12:20:26 INFO - Cleaning up... 12:20:26 INFO - Return code: 0 12:20:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:20:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:20:27 INFO - Reading from file tmpfile_stdout 12:20:27 INFO - Current package versions: 12:20:27 INFO - blessings == 1.6 12:20:27 INFO - blobuploader == 1.2.4 12:20:27 INFO - docopt == 0.6.1 12:20:27 INFO - functools32 == 3.2.3-2 12:20:27 INFO - jsonschema == 2.5.1 12:20:27 INFO - manifestparser == 1.1 12:20:27 INFO - mozInstall == 1.12 12:20:27 INFO - mozcrash == 0.17 12:20:27 INFO - mozdebug == 0.1 12:20:27 INFO - mozdevice == 0.48 12:20:27 INFO - mozfile == 1.2 12:20:27 INFO - mozhttpd == 0.7 12:20:27 INFO - mozinfo == 0.9 12:20:27 INFO - mozleak == 0.1 12:20:27 INFO - mozlog == 3.2 12:20:27 INFO - moznetwork == 0.27 12:20:27 INFO - mozprocess == 0.23 12:20:27 INFO - mozprofile == 0.28 12:20:27 INFO - mozrunner == 6.12 12:20:27 INFO - mozscreenshot == 0.1 12:20:27 INFO - mozsystemmonitor == 0.3 12:20:27 INFO - moztest == 0.7 12:20:27 INFO - mozversion == 1.4 12:20:27 INFO - psutil == 3.1.1 12:20:27 INFO - requests == 1.2.3 12:20:27 INFO - wsgiref == 0.1.2 12:20:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:20: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')]} 12:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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')]} 12:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:27 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:27 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 12:20:27 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 12:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:27 INFO - 'HOME': '/Users/cltbld', 12:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:27 INFO - 'LOGNAME': 'cltbld', 12:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:27 INFO - 'MOZ_NO_REMOTE': '1', 12:20:27 INFO - 'NO_EM_RESTART': '1', 12:20:27 INFO - 'PAGER': '/bin/cat', 12:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:27 INFO - 'PWD': '/builds/slave/test', 12:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:27 INFO - 'SHELL': '/bin/bash', 12:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:27 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:27 INFO - 'USER': 'cltbld', 12:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:27 INFO - 'XPC_FLAGS': '0x0', 12:20:27 INFO - 'XPC_SERVICE_NAME': '0', 12:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 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)) 12:20:27 INFO - Cleaning up... 12:20:27 INFO - Return code: 0 12:20:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:20: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')]} 12:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20: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')]} 12:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:20:27 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 0x110144a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ebd5ce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, '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.vQr7Z3roia/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.pNRtgoys2p/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 12:20:27 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 12:20:27 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 12:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:27 INFO - 'HOME': '/Users/cltbld', 12:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:27 INFO - 'LOGNAME': 'cltbld', 12:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:27 INFO - 'MOZ_NO_REMOTE': '1', 12:20:27 INFO - 'NO_EM_RESTART': '1', 12:20:27 INFO - 'PAGER': '/bin/cat', 12:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:27 INFO - 'PWD': '/builds/slave/test', 12:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:27 INFO - 'SHELL': '/bin/bash', 12:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:20:27 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:27 INFO - 'USER': 'cltbld', 12:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:27 INFO - 'XPC_FLAGS': '0x0', 12:20:27 INFO - 'XPC_SERVICE_NAME': '0', 12:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:20:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:20:27 INFO - Cleaning up... 12:20:27 INFO - Return code: 0 12:20:27 INFO - Running post-action listener: _resource_record_post_action 12:20:27 INFO - Running post-action listener: _start_resource_monitoring 12:20:27 INFO - Starting resource monitoring. 12:20:27 INFO - ##### 12:20:27 INFO - ##### Finished create-virtualenv step (success) 12:20:27 INFO - ##### 12:20:27 INFO - ##### 12:20:27 INFO - ##### Running install step. 12:20:27 INFO - ##### 12:20:27 INFO - Running pre-action listener: _resource_record_pre_action 12:20:27 INFO - Running main action method: install 12:20:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:20:28 INFO - Reading from file tmpfile_stdout 12:20:28 INFO - Detecting whether we're running mozinstall >=1.0... 12:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:20:28 INFO - Reading from file tmpfile_stdout 12:20:28 INFO - Output received: 12:20:28 INFO - Usage: mozinstall [options] installer 12:20:28 INFO - Options: 12:20:28 INFO - -h, --help show this help message and exit 12:20:28 INFO - -d DEST, --destination=DEST 12:20:28 INFO - Directory to install application into. [default: 12:20:28 INFO - "/builds/slave/test"] 12:20:28 INFO - --app=APP Application being installed. [default: firefox] 12:20:28 INFO - mkdir: /builds/slave/test/build/application 12:20:28 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'] 12:20:28 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 12:21:03 INFO - Reading from file tmpfile_stdout 12:21:03 INFO - Output received: 12:21:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:21:03 INFO - Running post-action listener: _resource_record_post_action 12:21:03 INFO - ##### 12:21:03 INFO - ##### Finished install step (success) 12:21:03 INFO - ##### 12:21:03 INFO - ##### 12:21:03 INFO - ##### Running run-tests step. 12:21:03 INFO - ##### 12:21:03 INFO - Running pre-action listener: _resource_record_pre_action 12:21:03 INFO - Running main action method: run_tests 12:21:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:21:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:21:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:21:03 INFO - 2016-07-06 12:21:03.090 screenresolution[1636:7772] starting screenresolution argv=screenresolution get 12:21:03 INFO - 2016-07-06 12:21:03.093 screenresolution[1636:7772] Display 0: 1600x1200x32@60 12:21:03 INFO - 2016-07-06 12:21:03.098 screenresolution[1637:7775] starting screenresolution argv=screenresolution list 12:21:03 INFO - Available Modes on Display 0 12:21:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:21:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:21:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:21:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:21:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:21:03 INFO - Intel Iris: 12:21:03 INFO - Chipset Model: Intel Iris 12:21:03 INFO - Type: GPU 12:21:03 INFO - Bus: Built-In 12:21:03 INFO - VRAM (Dynamic, Max): 1536 MB 12:21:03 INFO - Vendor: Intel (0x8086) 12:21:03 INFO - Device ID: 0x0a2e 12:21:03 INFO - Revision ID: 0x0009 12:21:03 INFO - Displays: 12:21:03 INFO - Display: 12:21:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:21:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:21:03 INFO - Display Serial Number: 1600x1200 60 12:21:03 INFO - Main Display: Yes 12:21:03 INFO - Mirror: Off 12:21:03 INFO - Online: Yes 12:21:03 INFO - Rotation: Supported 12:21:03 INFO - Return code: 0 12:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:21:03 INFO - Python 2.7.3 12:21:03 INFO - Return code: 0 12:21:03 INFO - grabbing minidump binary from tooltool 12:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:03 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 0x1100a8d30>, '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 0x10fe0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:21:03 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 12:21:03 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 12:21:03 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 12:21:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:21:03 INFO - Return code: 0 12:21:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:21:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:21:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:21:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:21:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--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 12:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --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 12:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vQr7Z3roia/Render', 12:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:03 INFO - 'HOME': '/Users/cltbld', 12:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:03 INFO - 'LOGNAME': 'cltbld', 12:21:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:21:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:03 INFO - 'MOZ_NO_REMOTE': '1', 12:21:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:21:03 INFO - 'NO_EM_RESTART': '1', 12:21:03 INFO - 'PAGER': '/bin/cat', 12:21:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:03 INFO - 'PWD': '/builds/slave/test', 12:21:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:03 INFO - 'SHELL': '/bin/bash', 12:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNRtgoys2p/Listeners', 12:21:03 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 12:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:03 INFO - 'USER': 'cltbld', 12:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:03 INFO - 'XPC_FLAGS': '0x0', 12:21:03 INFO - 'XPC_SERVICE_NAME': '0', 12:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--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 12:21:03 INFO - mozversion application_buildid: 20160706101143 12:21:03 INFO - mozversion application_changeset: 372bb24e7acdd5d5baeaa548decc1f9a5c5504ba 12:21:03 INFO - mozversion application_display_name: Nightly 12:21:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:21:03 INFO - mozversion application_name: Firefox 12:21:03 INFO - mozversion application_remotingname: firefox 12:21:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:21:03 INFO - mozversion application_vendor: Mozilla 12:21:03 INFO - mozversion application_version: 50.0a1 12:21:03 INFO - mozversion platform_buildid: 20160706101143 12:21:03 INFO - mozversion platform_changeset: 372bb24e7acdd5d5baeaa548decc1f9a5c5504ba 12:21:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:21:03 INFO - mozversion platform_version: 50.0a1 12:21:03 INFO - using testdate: 1467832863 12:21:03 INFO - actual date: 1467832863 12:21:03 INFO - starting webserver on 'localhost:49275' 12:21:03 INFO - SUITE-START | Running 2 tests 12:21:03 INFO - TEST-START | dromaeo_css 12:21:03 INFO - operating with platform_type : mac_ 12:21:03 INFO - Initialising browser for dromaeo_css test... 12:21:03 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpS_rqWx/profile http://localhost:49275/getInfo.html) 12:21:06 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:21:06 INFO - PROCESS | 1647 | colorDepth:24 12:21:06 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:21:06 INFO - PROCESS | 1647 | __metrics 12:21:06 INFO - PROCESS | 1647 | 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" 12:21:06 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:21:06 INFO - TEST-INFO | 1647: exit 0 12:21:06 INFO - Browser initialized. 12:21:06 INFO - Running cycle 1/1 for dromaeo_css test... 12:21:06 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpS_rqWx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:18 INFO - PROCESS | 1650 | RSS: Main: 243843072 12:21:18 INFO - PROCESS | 1650 | 12:22:10 INFO - PROCESS | 1650 | 1467832930396 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] 12:24:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:24:22 INFO - PROCESS | 1650 | RSS: Main: 363646976 12:24:22 INFO - PROCESS | 1650 | 12:27:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:27:25 INFO - PROCESS | 1650 | RSS: Main: 372281344 12:27:25 INFO - PROCESS | 1650 | 12:30:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:30:28 INFO - PROCESS | 1650 | RSS: Main: 363995136 12:30:28 INFO - PROCESS | 1650 | 12:33:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:33:31 INFO - PROCESS | 1650 | RSS: Main: 369311744 12:33:31 INFO - PROCESS | 1650 | 12:36:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:36:35 INFO - PROCESS | 1650 | RSS: Main: 332210176 12:36:35 INFO - PROCESS | 1650 | 12:39:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:39:39 INFO - PROCESS | 1650 | RSS: Main: 392335360 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | __start_tp_report 12:39:39 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 12:39:39 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 12:39:39 INFO - PROCESS | 1650 | |i|pagename|runs| 12:39:39 INFO - PROCESS | 1650 | |0;dojo.html;3324.318514704486;3335.466325387937;3350;3366.720646154464;3404.319136172766;5510.752016159272;5613.915791263134;5641.802536911209;5699.658020518772;5748.396418376073;5634.690092044936;5694.202811606378;5736.541076713864;5755.136729490568;5785.045467497858;5087.949120508803;5096.413912400066;5154.716490593016;5157.071727089123;5196.272521846944;5166.819161329354;5205.037068142406;5221.321228240343;5243.842684719464;5277.340332458442;4977.129002424597;5026.371703537058;5037.345145131109;5085.262636917626;5159.329287192679;4195.832166713328;5066.797328106872;5149.871253218662;5228.111221092423;5247.921281180786;5066.113430149746;5122.795088196468;5143.048536020848;5177.094008548489;5191.506806853343;5489.478499542577;5610.410906854762;5674.744636491368;5681.034224181899;5719.28508936383;6130.325664176937;6139.723712432907;6155.169052177988;6171.660558669271;6179.5674302798825;5497.642653227527;6207.844803879894;6243.375662433766;6255.749770009194;6308.432241098323;5776.364599894008;5809.622374545641;5832.9708351457975;5833.620814647034;5837.328707198684;6099.2985806632805;6155.7688462307715;6259.405356491126;6287.968560157261;6291.653959032297;5464.754086066137;6145.508359331243;6151.415615516519;6156.353582873734;6165.568410211332;5871.383504732027;6203.0075187969705;6206.503479721611;6240.563160578807;6249.468795152372;5966.552508561876;6061.54538409621;6135.478484328793;6146.524834039798;6204.038374052029;6173.446336098777;6191.288001879751;6208.441240288395;6230.6884655767935;6232.968835155795;4727.172744769651;4814.735189564608;4854.68444551103;4894.094592500328;4921.729304888194;3613.837377318027;4263.381809637585;4294.785260737013;4341.327094300388;4362.083962367875;5693.28833895763;5709.257796486513;5714.05718056516;5735.799247026334;5763.6541807491685;6141.508679305645;6164.7842325518395;6243.75024999004;6281.842953926189;6281.968590157111;5605.243292155506;5633;5634.549236061105;5635.295588051494;5662.546996240291;5380;5385.138377859524;5387.515123638891;5389.407165211824;5402.081646120169;6209.0686397039;6225.81322560333;6232.25212974446;6259.655718935503;6399.200099987502;6227.844303892439;6245.594036387621;6257.342978987141;6279.96860015697;6282.654454004891;6324.905126423199;6333.018382150774;6350.555461117678;6353;6356.809295721136;5988.431099045583;6126.387361263838;6127.816165515042;6139.062858969336;6159.106929495288;6109.404666050415;6195.132681424694;6279.748810047547;6281.120643109877;6303.968480157753;5895.351511333835;6083.7870674526175;6113.908291375722;6123.418275263843;6127.479164271087;5869.148973398918;5879.059350503899;5986.191864098291;5993.730282137227;6049.818505444844;4447.377367168664;5846.766129354778;5933.1396947443245;5935.940640593539;5995.250593675791;5913.5564832636865;6080.939190608037;6093.6039157454625;6104.032647854634;6111.669269563957;5737.34020587621;5775.537956963517;5806.274215723035;5814.5639077068545;5873.530117590668 12:39:39 INFO - PROCESS | 1650 | |1;ext.html;522.2870781383411;527.3065918317415;539.6222644149096;546.6419495230625;564.5370795947325;24424.511509769793;42668.359974600404;42728.71813845586;43982.560174398284;44120.779396103055;44621.33068003983;45005.77497112518;45115.32327015098;45142.64572062835;45176.774116129454;8821.735347939572;9603.951980240088;9809.509524523744;9818.754531136707;9845.65540206093;8774.561271936409;9566.47384393858;11212.607558735404;12014.098942579341;12392.566260180896;12051.15641905067;12110.212836165665;12113.636590902288;12115;12115.151939364248;8818.955905220497;8994.325425593106;9005.504697241715;12048.879511204907;12166.878331216663;11584.247023943417;11864.288142711464;11902.702432439171;12140.696482587917;12165.51337946481;42872.142557148996;43388.78305608483;43488.56511434877;43513.78243108764;43516.782416088034;3570.767900086486;3580.5703315602036;3622.6920711739535;3689.6863766579872;4301.763403012833;32199;33545.6645433545;33761.6623833761;33868.49197262043;33914;3319.219983303946;3342.4317865962844;3369.3429781192676;3406.0463070340343;3451.637578054293;500174;500596;501531;501897;502167;34618.65381346205;63035.684821575596;63103.68448157822;63606;63620.04569931455;34620.13449663779;36510.81744591313;36548.63451365452;36627;36653.63346366555;15130.319135638923;15302.693946121015;15306.846931530765;15317.157556334509;15320.004199727206;11790.115741319436;11903.464344104535;12707;12845.293508857103;12873.742525149444;8007.07918589358;8019.759407217793;8046.356291496666;8072.354211663052;8136.837263254702;2836;3185.984070079635;3301.141703157148;3332.3002169544666;3344.7324214062814;932.6455946740195;1013.6806905824642;1081.6106201767357;1106.7272636467965;1107.6234080412698;28099.719002810118;31167.68832311648;31262.531062034086;31783.84108079491;32053.679463205535;1000.979980400388;1064.861567996171;1067.4289255248405;1082.723905404117;1238.68413554543;13499.865001350057;16444.424445144363;16532.338706451606;16557;16558.586035348973;606411;711442;717578;718672;719054;62228;62547.6872615634;62580.37419625746;62722;62918.0562291566;1269.6889262130837;1395.1001603965587;1409.0911362171341;1417.7660685986696;1438.6331485471137;32071.679283206868;32284.35431291408;32344.83827580847;32457.51313730249;33107.172320692174;53041.20438193353;53280.73359633177;53718;53867;53869.730651346494;563599;564284;565412;565861;566597;24808.875955620108;27445.862770686017;27725.029623963066;27766.889324426797;28810.27974300659;50184.74907625438;50311.74844125756;50677;51715.74142129265;51846.22230666468;41209.79395103005;41849.1630167401;41938.79030604827;42898;43149.78425107854;35756.46365304574;37267.881963541135;37770.05574860651;41505.58494415017;41854.79072604617;32609.391248700522;35250.294994100484;40261.39607905825;41327.793361033;41351;30197.547036794025;35053.64946350504;35871.82064089767;36819.63180368269;36981;20049.398518044483;24467.632985504875;26197.607035894856;26511.469770604865;27383.178504644893 12:39:39 INFO - PROCESS | 1650 | |2;jquery.html;351.386829981682;376.3620662976256;379.3569899021157;381.55739342362864;403.43921948491584;15188.848111518895;15916.204189790507;21191.682124768104;21589.136434542637;21606.675899861493;19966.600667986633;20705.275315363964;20793.272235471763;20845.791542084575;20851;4118.485189351331;5816.505597024247;6057.485113765313;6059.333473317932;6073.271207455112;4978.601711863061;6800.727970881158;6806.965965170167;6837.794866154023;6850.972354146868;6461.256955450113;6692.698828552702;6715.597064176165;6727.831804204885;6792.913133898577;5973.641581505123;6464.773732919325;6483.124778154959;6763.560368576027;6774.119364482585;5681.801136960228;5732.140178973145;5734.311882574076;5736.885262294731;5740.827775166752;22585.661215081785;24254.393640159138;26340.60489092665;26683.466330673276;26708;2192.47380628648;2283.703118594572;2414.348126005986;2899.753520950722;3227.2254658881698;14792.778108328384;15088.849111508855;15152.848471515365;15264.542063738105;15473.458428955046;1790.7045337519294;1845.6124213915093;1863.105709259563;1917.3864363403716;2098.6044280553415;122451.38774306161;126077.73922260752;127454;128714;128862.71137288601;11581.594644187413;11583.478743456564;11605.941970290263;11632.476538555786;11804.996575291196;14412.207328597027;14439;14459.566213013626;14473.986820922644;14486.492972746115;8597.355198360148;8656.567171641393;8668.049688819965;8694.82610347802;8765.693200737944;8691.826163476695;8704.738857834274;8721.389502734748;8774.28901364228;8841.204291613785;4533.682642215013;4579.129965306581;4592.311153326961;4604;4618.838340658061;1588.586158319097;1772.6011647379494;1826.1508398594717;1927.5277556998626;1927.8650494465253;808.7533302342842;856.4304737349718;906.5739102621757;925.9074092590687;1013.8479228115841;20600.690989635168;21032;21192.894035529724;21222.25722099719;21248.78751212499;933.4970349787961;964.9469279189711;971.8483596937651;977.5356705564942;996.6013594562088;10700.197485188643;11319.547218111184;12236.877631223573;12302.81545776814;12507.499700011898;544150;551487;554929;564488;564760;99353;136062;136257;140819;141529;995.4425521707867;1016.598443614768;1018.4093225928943;1020.9795804083876;1039.7920415916847;19209.252312830205;19686.01569921527;19945.80596134453;19950.90270035104;19963.900180499586;44043.55956440394;56652.09493582437;57189;57757.42242577689;58443.70778146082;82096.76854847302;82527;83501.16498835177;83634.58182709047;84555;7091.680874360563;7414.777556673308;7437.7396791114215;7703.576303303148;8180.263776260165;11641.825372619587;13366.866331336561;13454.46182152742;13557.79663305071;13663.9752018601;10013.699589012342;10566.524506397383;11591.652250432164;11766.411679416165;11911;11338.603148890079;11401.372924489233;11541.826872597087;11648.90394223215;11781.410929453321;11765.176437649283;11779.293242405483;11832.17174797756;11904.821427678418;11963.880361196276;7418.295261950106;7547.660355283915;7605.315521603082;7683.462157648911;7693.269139431745;10990.835137473105;11405.201635885409;11482.483288252215;11591.362475063952;11647.184697071123 12:39:39 INFO - PROCESS | 1650 | |3;mootools.html;1576.6137296362392;1607.0679006176415;1855.396995976308;1938.8739731917415;1985.3646833013433;8585.055643879175;8891.91108088919;9034.277257819374;9034.412763170389;9041.683541076063;7991.041075071;8253.834923301545;8591.527465989368;8803.515806630632;8807.691730789424;2351.412146963255;2392.294273189413;2393.8324317297797;2394.8443351182204;2394.916177933773;2367.95264094718;2371.9407014824683;2405.0620258099357;2408.771166739166;2409.6024156014237;1884.331062472823;2087.674326802476;2178.4880553070093;2178.9564208715815;2187.9890600546973;2150.6451435513122;2271.2958982715327;2317.073170731704;2324.058756203723;2326.7091613548305;2287.3709729824263;2305.5273668897835;2314.826388020905;2319.2694301295205;2324.6745455636224;8121.512709237465;8255.917440825575;8256.711015114503;8290.295324897392;8291.502509849428;5296.549793267577;5457.181422786574;5457.372402173761;5464.9453505464835;5476.205950137248;3563.875264365735;3645.143391303042;3726.7018638508853;3737.1591391936868;3738.3551237455704;1034.9793004139874;1061.3419679798574;1087.3747595132752;1089.6839916424228;1090.37303550458;12015.699607509794;12062.638120856389;12103.152779305452;12121.575744848977;12129.090318226168;4578.267477203632;4742.502037286035;4833.009233107205;4838.225883858566;4849.927251091236;10166.593336266615;10173.237007224488;10187.694369168938;10204.489775511194;10210.4384258865;5431.158170483582;5441.374241962146;5445.782168713286;5454.12733962572;5462.590305727086;4844.588210002119;4894.363732714724;4972.676776009548;4974.527419895104;4989.650724449254;3340.7828491148;3985.4221137934837;4062.878113656595;4089.141280331104;4099.549049604541;1291.4317700212064;1292.2698675248455;1294.1264646363668;1305.8106574546637;1307.4704744578462;844.7666407045659;856.2251162697769;870.4472659860908;914.0767824497306;922.2253307221965;5979.1928089707335;6099.420555047263;6102.359252278536;6115.724792384353;6181.690915454209;938.3900464697864;972.4019727867311;1009.268280496634;1063.0751246415514;1072.5012869015945;3905.9609403906165;3964.365701487748;3996.500437445319;4076.9184616307507;4108.36320370343;10657.573697052187;10910.181736369805;10943.288686235528;10997.395143267197;11034.889651103385;10560.47197640115;10923.235373523778;10927.125829933586;10950.616728414467;10955.50700218492;8326.12575679557;8396.32829373661;8461.619227134794;8469.788255293666;8484.024337201177;4969.627277954096;5839.8540036497725;5980.820575382745;6018.187544681412;6033.034714445667;10164.593416263267;10234.6417875374;10235.590576376862;10239.539220735234;10253.231007674305;9015.909840901508;10111.292209545261;10463.581456741646;10476.895231047592;10483.108935740547;7579.317861392501;7604;7716.074071111502;7717.035370578677;7738.574378409016;8163.346932245525;8291.33669306442;8319.916800831914;8335.666573337;8371.246587807127;7092.680829362588;7523.0972283326355;7582.35549978258;7606.239376062349;7720;6597.4392176663605;7567.243275672388;7576.3938884887975;7585.6965721370525;7607.581583012766;7098.29017098286;7118.8932166018585;7125.251848556034;7158.928410715826;7179.605121718356;5029.899402011866;5063.670861393997;5081.77132029067;5099.209622508517;5217.321748172713;4416.9116617668105;5070.681622778031;5146.562542183807;5188.636795424284;5192.844214673566 12:39:39 INFO - PROCESS | 1650 | |4;prototype.html;298.63119047975744;305.7645612878083;307.34842134674494;311.62293624714107;314.15492325644004;4867.513248675131;5056.8230111945995;5399.029354285687;5435.701036442999;5470.538257783993;5370.838874833761;5389.622726409153;5398.406175320722;5444.237806707054;5475.780968761254;3885.49488566486;3916.6279203475624;4065.085355794952;4114.855980040699;4116.897077573055;3070.416620842044;4025.3156963316305;4053.493313335833;4063.756174629517;4131.35963925592;3893.4549163117044;4078.5717499662555;4084.8570300039355;4100.671946244308;4109.321961876301;3643.362411577963;3991.44119823225;4018.4173294872226;4099.754014759124;4109.835606575733;3558.2527669189503;3563.7327200460068;3565.894572682451;3589.353916295066;3595.26297109092;3889.572147063821;4064.3497040473676;5013.29813826061;5068.6705364151585;5407.918881216746;4571.748553829538;4703.882402939919;4704.411948506437;4718.905621887543;4803.879903002453;5616.691081990489;5642.307307807641;5650.886982260332;5652.491275785199;5658.717064146817;3962.841486340543;3989.880303590897;3990.1820126874213;4010.3984402339593;4014.2537995971848;4877.658563900528;5734.566358410398;5936.39717276333;5948.702564871739;5957.4042595740075;4001.839466554725;4009.0578714002186;4037.7375470594316;4048.1701251243426;4078.838899852579;4120.773357465308;4122.979385103055;4136;4136.338185890243;4150.79246037703;4334.656256560476;4431.645468362523;4481.4174157359985;4744.667873248839;4810.7835147418455;5668.858278542987;5682.829515114553;5685.607026278588;5698.686572238566;5708.372079071299;1367.7743172376645;1396.0785881318484;1410.5063227870164;1416.4546649539795;1416.553785557546;812.480661163616;862.2498426368974;876.513534988075;882.2544949517647;884.8141890203129;4800.975995120002;5073.518015788494;5112.054269959995;5300.681959082467;5313.26366921036;5556.638818476786;5792.536597072224;5839.678817665069;5840.503557197686;5889.852753681106;5010.298558201855;5108.66793658411;5304.363476382859;5306.442823503554;5360.061989151883;2713.4030513287044;2922.7369536741794;3092.273315770837;3133.295008623087;3142.842857857144;5563.944360556424;5973.761049557969;6103.633781973096;6112.441327461469;6194.969025154845;4353.804078816461;4760.738159401266;5477.890442191134;5562.554995600423;6017.849553761191;5689.634487722917;6029.638221706711;6111.205543279345;6112.358202388863;6132.5707200495535;4847.733374664427;5386.946130538645;5518.034343989866;5912.438318359749;5918.526517878646;4692.812287508598;4969.701817890938;5742.425757424224;5771.942280577308;5787.247657804459;5604.411536788742;5815.82552523425;6065.302490213682;6109.2974307955155;6114.052321890114;3092.582501362287;3321.9237976264835;3339.949900751442;3920.725549211528;3949.7037722171526;5052.494750525065;5530.755579994469;5811.62224455426;5855.180274761452;5877.324107727666;5541.3904470509015;5737.647564498536;5748.48263656273;5760.711964401847;5782.959067367914;5121.718305493085;5175.792968281226;5389.27244821944;5500.669959802424;5673.375928647928;4921.753912304299;5063.367079115111;5157.252198431282;5335.67985920846;5578.9163162553405;4873.805917550223;5435.646682965595;5745.339285981999;5778.306603207642;5807.767689292381;5109.952459745818;5174.482551744795;5633.887322253614;5636.2954630671165;5645.774169033192 12:39:39 INFO - PROCESS | 1650 | |5;yui.html;721.3003386714885;724;732.2201855024399;736.6353654940805;858.4034096303072;17776.822231777678;18384.90807545962;18389.724154137697;18426.355077572287;18428.539286517862;17066.488005359828;17145.31418743252;17308.913455432707;17314.48056558302;17322.220500077496;614.8893199224137;896.9910300896989;902.7451841939676;925.1303774451993;927.0960813207137;737.634870738985;882.7395918204113;900.0234745301323;911.0525053943924;917.2157805076663;937.8358787212245;949.5347279832866;953.4422362917702;953.818774432859;955.44106697582;741.5624781379013;946.129560804055;946.5551190940281;948.369334392635;948.4830767231875;678.2166597579803;689.8723736108785;690.5028379566704;701.0430762009871;706.1244057368878;15461.845381546153;15506.069635821774;15528.301226444837;15529.611759705986;15544.533663990098;13841.215026073789;13848;14091.295435228198;14133.363998620087;14493.071150791448;12836.550720724823;13367.732645347136;13427.530036448776;13498.257595832225;13793.86206137946;4659.860204193879;4791.928121078186;4828.961773218774;4834.105690447279;4847.951520484785;113276.21737329784;115570;116144;117034;118206.99999999914;10944.507497162647;11093.611723589636;11281.266717663326;11440.656780296604;11451.36642387928;21864.453388665093;21984.120635174415;21988;22023.788691622114;22085;7876.330511906437;7936.841263174704;7938.6427610757655;7978.162292959272;8074.313683336864;4407.118576284756;4478.888027799266;4508;4535.138323718483;4717.9056418872115;2779.8880447821034;3120.921976950549;3224.4357237483346;3228.225225945756;3230.305484320835;676.1075380497696;680.1939701453794;680.6630717794625;681.2200030964617;681.3322943515383;606.617830766623;637.2766909557592;651.1404945472026;657.3590749019668;658.9505787065989;9087.409318394282;9090.909090909006;9119.452832830051;9125.589348479201;9212.309076819265;514.0977584339461;544.8011475811301;637.3435361577582;654.4731491149576;678.0879716780922;4328.09110086879;4358.4769827620885;4375.124975005012;4416.977915110404;4479.013007911998;75184.62407687926;98131.50934245283;115106;124717;154636.3095505925;84034.31931361339;105666;123938;127900;128268.35865820797;89707.65438518427;103824.48087759664;107276.46361768141;107692.99999999843;110767;8135;8605.268552173135;8612.224899759052;8843.986595507013;8941;48472;51347;59276.70361648164;65179;65475.67262163849;94971.05028949623;97324;97740.51129744305;99903.5014474769;101469.49265253627;13478.460861565427;13827.861721382658;13958.511452099128;13971.510997115458;14250;15213.695726085638;15326.463573774865;15384;15490.225488725744;15788.842111578737;13951.093178943336;14182;14201.431942722176;14642.853571464575;14871.884608654611;14077.296135193405;14187.290635468391;14255;14822.703545929236;14829.33268002964;13939.860601393857;14006.649833754238;14020.789688154482;14021.929890350482;14627.34176962018;11176.916327669864;11426.82859757121;11454.713632159262;11514.251573647685;12326.198797078161;9904.950475247866;11134.3876086816;11494.82757758651;11760.294382337088;11777.764444710887 12:39:39 INFO - PROCESS | 1650 | __end_tp_report 12:39:39 INFO - PROCESS | 1650 | __start_cc_report 12:39:39 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1243 12:39:39 INFO - PROCESS | 1650 | __end_cc_report 12:39:39 INFO - PROCESS | 1650 | __startTimestamp1467833979178__endTimestamp 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | ------- Summary: start ------- 12:39:39 INFO - PROCESS | 1650 | Number of tests: 6 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5678.23 Median:5846.77 stddev:671.67 (11.5%) stddev-sans-first:647.85 12:39:39 INFO - PROCESS | 1650 | Values: 3324.3 3335.5 3350.0 3366.7 3404.3 5510.8 5613.9 5641.8 5699.7 5748.4 5634.7 5694.2 5736.5 5755.1 5785.0 5087.9 5096.4 5154.7 5157.1 5196.3 5166.8 5205.0 5221.3 5243.8 5277.3 4977.1 5026.4 5037.3 5085.3 5159.3 4195.8 5066.8 5149.9 5228.1 5247.9 5066.1 5122.8 5143.0 5177.1 5191.5 5489.5 5610.4 5674.7 5681.0 5719.3 6130.3 6139.7 6155.2 6171.7 6179.6 5497.6 6207.8 6243.4 6255.7 6308.4 5776.4 5809.6 5833.0 5833.6 5837.3 6099.3 6155.8 6259.4 6288.0 6291.7 5464.8 6145.5 6151.4 6156.4 6165.6 5871.4 6203.0 6206.5 6240.6 6249.5 5966.6 6061.5 6135.5 6146.5 6204.0 6173.4 6191.3 6208.4 6230.7 6233.0 4727.2 4814.7 4854.7 4894.1 4921.7 3613.8 4263.4 4294.8 4341.3 4362.1 5693.3 5709.3 5714.1 5735.8 5763.7 6141.5 6164.8 6243.8 6281.8 6282.0 5605.2 5633.0 5634.5 5635.3 5662.5 5380.0 5385.1 5387.5 5389.4 5402.1 6209.1 6225.8 6232.3 6259.7 6399.2 6227.8 6245.6 6257.3 6280.0 6282.7 6324.9 6333.0 6350.6 6353.0 6356.8 5988.4 6126.4 6127.8 6139.1 6159.1 6109.4 6195.1 6279.7 6281.1 6304.0 5895.4 6083.8 6113.9 6123.4 6127.5 5869.1 5879.1 5986.2 5993.7 6049.8 4447.4 5846.8 5933.1 5935.9 5995.3 5913.6 6080.9 6093.6 6104.0 6111.7 5737.3 5775.5 5806.3 5814.6 5873.5 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:71493.98 Median:20066.75 stddev:158788.55 (791.3%) stddev-sans-first:159144.59 12:39:39 INFO - PROCESS | 1650 | Values: 522.3 527.3 539.6 546.6 564.5 24424.5 42668.4 42728.7 43982.6 44120.8 44621.3 45005.8 45115.3 45142.6 45176.8 8821.7 9604.0 9809.5 9818.8 9845.7 8774.6 9566.5 11212.6 12014.1 12392.6 12051.2 12110.2 12113.6 12115.0 12115.2 8819.0 8994.3 9005.5 12048.9 12166.9 11584.2 11864.3 11902.7 12140.7 12165.5 42872.1 43388.8 43488.6 43513.8 43516.8 3570.8 3580.6 3622.7 3689.7 4301.8 32199.0 33545.7 33761.7 33868.5 33914.0 3319.2 3342.4 3369.3 3406.0 3451.6 500174.0 500596.0 501531.0 501897.0 502167.0 34618.7 63035.7 63103.7 63606.0 63620.0 34620.1 36510.8 36548.6 36627.0 36653.6 15130.3 15302.7 15306.8 15317.2 15320.0 11790.1 11903.5 12707.0 12845.3 12873.7 8007.1 8019.8 8046.4 8072.4 8136.8 2836.0 3186.0 3301.1 3332.3 3344.7 932.6 1013.7 1081.6 1106.7 1107.6 28099.7 31167.7 31262.5 31783.8 32053.7 1001.0 1064.9 1067.4 1082.7 1238.7 13499.9 16444.4 16532.3 16557.0 16558.6 606411.0 711442.0 717578.0 718672.0 719054.0 62228.0 62547.7 62580.4 62722.0 62918.1 1269.7 1395.1 1409.1 1417.8 1438.6 32071.7 32284.4 32344.8 32457.5 33107.2 53041.2 53280.7 53718.0 53867.0 53869.7 563599.0 564284.0 565412.0 565861.0 566597.0 24808.9 27445.9 27725.0 27766.9 28810.3 50184.7 50311.7 50677.0 51715.7 51846.2 41209.8 41849.2 41938.8 42898.0 43149.8 35756.5 37267.9 37770.1 41505.6 41854.8 32609.4 35250.3 40261.4 41327.8 41351.0 30197.5 35053.6 35871.8 36819.6 36981.0 20049.4 24467.6 26197.6 26511.5 27383.2 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34647.84 Median:12434.68 stddev:93582.16 (752.6%) stddev-sans-first:93809.25 12:39:39 INFO - PROCESS | 1650 | Values: 351.4 376.4 379.4 381.6 403.4 15188.8 15916.2 21191.7 21589.1 21606.7 19966.6 20705.3 20793.3 20845.8 20851.0 4118.5 5816.5 6057.5 6059.3 6073.3 4978.6 6800.7 6807.0 6837.8 6851.0 6461.3 6692.7 6715.6 6727.8 6792.9 5973.6 6464.8 6483.1 6763.6 6774.1 5681.8 5732.1 5734.3 5736.9 5740.8 22585.7 24254.4 26340.6 26683.5 26708.0 2192.5 2283.7 2414.3 2899.8 3227.2 14792.8 15088.8 15152.8 15264.5 15473.5 1790.7 1845.6 1863.1 1917.4 2098.6 122451.4 126077.7 127454.0 128714.0 128862.7 11581.6 11583.5 11605.9 11632.5 11805.0 14412.2 14439.0 14459.6 14474.0 14486.5 8597.4 8656.6 8668.0 8694.8 8765.7 8691.8 8704.7 8721.4 8774.3 8841.2 4533.7 4579.1 4592.3 4604.0 4618.8 1588.6 1772.6 1826.2 1927.5 1927.9 808.8 856.4 906.6 925.9 1013.8 20600.7 21032.0 21192.9 21222.3 21248.8 933.5 964.9 971.8 977.5 996.6 10700.2 11319.5 12236.9 12302.8 12507.5 544150.0 551487.0 554929.0 564488.0 564760.0 99353.0 136062.0 136257.0 140819.0 141529.0 995.4 1016.6 1018.4 1021.0 1039.8 19209.3 19686.0 19945.8 19950.9 19963.9 44043.6 56652.1 57189.0 57757.4 58443.7 82096.8 82527.0 83501.2 83634.6 84555.0 7091.7 7414.8 7437.7 7703.6 8180.3 11641.8 13366.9 13454.5 13557.8 13664.0 10013.7 10566.5 11591.7 11766.4 11911.0 11338.6 11401.4 11541.8 11648.9 11781.4 11765.2 11779.3 11832.2 11904.8 11963.9 7418.3 7547.7 7605.3 7683.5 7693.3 10990.8 11405.2 11482.5 11591.4 11647.2 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5781.57 Median:4835.62 stddev:3251.19 (67.2%) stddev-sans-first:3244.95 12:39:39 INFO - PROCESS | 1650 | Values: 1576.6 1607.1 1855.4 1938.9 1985.4 8585.1 8891.9 9034.3 9034.4 9041.7 7991.0 8253.8 8591.5 8803.5 8807.7 2351.4 2392.3 2393.8 2394.8 2394.9 2368.0 2371.9 2405.1 2408.8 2409.6 1884.3 2087.7 2178.5 2179.0 2188.0 2150.6 2271.3 2317.1 2324.1 2326.7 2287.4 2305.5 2314.8 2319.3 2324.7 8121.5 8255.9 8256.7 8290.3 8291.5 5296.5 5457.2 5457.4 5464.9 5476.2 3563.9 3645.1 3726.7 3737.2 3738.4 1035.0 1061.3 1087.4 1089.7 1090.4 12015.7 12062.6 12103.2 12121.6 12129.1 4578.3 4742.5 4833.0 4838.2 4849.9 10166.6 10173.2 10187.7 10204.5 10210.4 5431.2 5441.4 5445.8 5454.1 5462.6 4844.6 4894.4 4972.7 4974.5 4989.7 3340.8 3985.4 4062.9 4089.1 4099.5 1291.4 1292.3 1294.1 1305.8 1307.5 844.8 856.2 870.4 914.1 922.2 5979.2 6099.4 6102.4 6115.7 6181.7 938.4 972.4 1009.3 1063.1 1072.5 3906.0 3964.4 3996.5 4076.9 4108.4 10657.6 10910.2 10943.3 10997.4 11034.9 10560.5 10923.2 10927.1 10950.6 10955.5 8326.1 8396.3 8461.6 8469.8 8484.0 4969.6 5839.9 5980.8 6018.2 6033.0 10164.6 10234.6 10235.6 10239.5 10253.2 9015.9 10111.3 10463.6 10476.9 10483.1 7579.3 7604.0 7716.1 7717.0 7738.6 8163.3 8291.3 8319.9 8335.7 8371.2 7092.7 7523.1 7582.4 7606.2 7720.0 6597.4 7567.2 7576.4 7585.7 7607.6 7098.3 7118.9 7125.3 7158.9 7179.6 5029.9 5063.7 5081.8 5099.2 5217.3 4416.9 5070.7 5146.6 5188.6 5192.8 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4596.93 Median:5111.00 stddev:1410.19 (27.6%) stddev-sans-first:1376.74 12:39:39 INFO - PROCESS | 1650 | Values: 298.6 305.8 307.3 311.6 314.2 4867.5 5056.8 5399.0 5435.7 5470.5 5370.8 5389.6 5398.4 5444.2 5475.8 3885.5 3916.6 4065.1 4114.9 4116.9 3070.4 4025.3 4053.5 4063.8 4131.4 3893.5 4078.6 4084.9 4100.7 4109.3 3643.4 3991.4 4018.4 4099.8 4109.8 3558.3 3563.7 3565.9 3589.4 3595.3 3889.6 4064.3 5013.3 5068.7 5407.9 4571.7 4703.9 4704.4 4718.9 4803.9 5616.7 5642.3 5650.9 5652.5 5658.7 3962.8 3989.9 3990.2 4010.4 4014.3 4877.7 5734.6 5936.4 5948.7 5957.4 4001.8 4009.1 4037.7 4048.2 4078.8 4120.8 4123.0 4136.0 4136.3 4150.8 4334.7 4431.6 4481.4 4744.7 4810.8 5668.9 5682.8 5685.6 5698.7 5708.4 1367.8 1396.1 1410.5 1416.5 1416.6 812.5 862.2 876.5 882.3 884.8 4801.0 5073.5 5112.1 5300.7 5313.3 5556.6 5792.5 5839.7 5840.5 5889.9 5010.3 5108.7 5304.4 5306.4 5360.1 2713.4 2922.7 3092.3 3133.3 3142.8 5563.9 5973.8 6103.6 6112.4 6195.0 4353.8 4760.7 5477.9 5562.6 6017.8 5689.6 6029.6 6111.2 6112.4 6132.6 4847.7 5386.9 5518.0 5912.4 5918.5 4692.8 4969.7 5742.4 5771.9 5787.2 5604.4 5815.8 6065.3 6109.3 6114.1 3092.6 3321.9 3339.9 3920.7 3949.7 5052.5 5530.8 5811.6 5855.2 5877.3 5541.4 5737.6 5748.5 5760.7 5783.0 5121.7 5175.8 5389.3 5500.7 5673.4 4921.8 5063.4 5157.3 5335.7 5578.9 4873.8 5435.6 5745.3 5778.3 5807.8 5110.0 5174.5 5633.9 5636.3 5645.8 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:23887.02 Median:3779.20 stddev:36304.47 (960.6%) stddev-sans-first:36364.64 12:39:39 INFO - PROCESS | 1650 | Values: 721.3 724.0 732.2 736.6 858.4 17776.8 18384.9 18389.7 18426.4 18428.5 17066.5 17145.3 17308.9 17314.5 17322.2 614.9 897.0 902.7 925.1 927.1 737.6 882.7 900.0 911.1 917.2 937.8 949.5 953.4 953.8 955.4 741.6 946.1 946.6 948.4 948.5 678.2 689.9 690.5 701.0 706.1 15461.8 15506.1 15528.3 15529.6 15544.5 13841.2 13848.0 14091.3 14133.4 14493.1 12836.6 13367.7 13427.5 13498.3 13793.9 4659.9 4791.9 4829.0 4834.1 4848.0 113276.2 115570.0 116144.0 117034.0 118207.0 10944.5 11093.6 11281.3 11440.7 11451.4 21864.5 21984.1 21988.0 22023.8 22085.0 7876.3 7936.8 7938.6 7978.2 8074.3 4407.1 4478.9 4508.0 4535.1 4717.9 2779.9 3120.9 3224.4 3228.2 3230.3 676.1 680.2 680.7 681.2 681.3 606.6 637.3 651.1 657.4 659.0 9087.4 9090.9 9119.5 9125.6 9212.3 514.1 544.8 637.3 654.5 678.1 4328.1 4358.5 4375.1 4417.0 4479.0 75184.6 98131.5 115106.0 124717.0 154636.3 84034.3 105666.0 123938.0 127900.0 128268.4 89707.7 103824.5 107276.5 107693.0 110767.0 8135.0 8605.3 8612.2 8844.0 8941.0 48472.0 51347.0 59276.7 65179.0 65475.7 94971.1 97324.0 97740.5 99903.5 101469.5 13478.5 13827.9 13958.5 13971.5 14250.0 15213.7 15326.5 15384.0 15490.2 15788.8 13951.1 14182.0 14201.4 14642.9 14871.9 14077.3 14187.3 14255.0 14822.7 14829.3 13939.9 14006.6 14020.8 14021.9 14627.3 11176.9 11426.8 11454.7 11514.3 12326.2 9905.0 11134.4 11494.8 11760.3 11777.8 12:39:39 INFO - PROCESS | 1650 | -------- Summary: end -------- 12:39:39 INFO - PROCESS | 1650 | 12:39:39 INFO - TEST-INFO | 1650: exit 0 12:39:39 INFO - TEST-OK | dromaeo_css | took 1116103ms 12:39:39 INFO - TEST-START | kraken 12:39:39 INFO - operating with platform_type : mac_ 12:39:39 INFO - Initialising browser for kraken test... 12:39:39 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpOYhtMd/profile http://localhost:49275/getInfo.html) 12:39:41 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 12:39:41 INFO - PROCESS | 1660 | colorDepth:24 12:39:41 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 12:39:41 INFO - PROCESS | 1660 | __metrics 12:39:42 INFO - PROCESS | 1660 | 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" 12:39:42 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:39:42 INFO - TEST-INFO | 1660: exit 0 12:39:42 INFO - Browser initialized. 12:39:42 INFO - Running cycle 1/1 for kraken test... 12:39:42 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpOYhtMd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:54 INFO - PROCESS | 1661 | RSS: Main: 243511296 12:39:54 INFO - PROCESS | 1661 | 12:39:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 12:39:58 INFO - PROCESS | 1661 | RSS: Main: 293924864 12:39:58 INFO - PROCESS | 1661 | 12:40:00 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 12:40:01 INFO - PROCESS | 1661 | RSS: Main: 304275456 12:40:01 INFO - PROCESS | 1661 | 12:40:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 12:40:05 INFO - PROCESS | 1661 | RSS: Main: 387694592 12:40:05 INFO - PROCESS | 1661 | 12:40:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 12:40:08 INFO - PROCESS | 1661 | RSS: Main: 289890304 12:40:08 INFO - PROCESS | 1661 | 12:40:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:40:10 INFO - PROCESS | 1661 | RSS: Main: 292769792 12:40:10 INFO - PROCESS | 1661 | 12:40:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 12:40:14 INFO - PROCESS | 1661 | RSS: Main: 328192000 12:40:14 INFO - PROCESS | 1661 | 12:40:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 12:40:17 INFO - PROCESS | 1661 | RSS: Main: 332406784 12:40:17 INFO - PROCESS | 1661 | 12:40:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 12:40:21 INFO - PROCESS | 1661 | RSS: Main: 329379840 12:40:21 INFO - PROCESS | 1661 | 12:40:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:40:23 INFO - PROCESS | 1661 | RSS: Main: 311693312 12:40:23 INFO - PROCESS | 1661 | 12:40:25 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:40:26 INFO - PROCESS | 1661 | RSS: Main: 318177280 12:40:26 INFO - PROCESS | 1661 | 12:40:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:40:29 INFO - PROCESS | 1661 | RSS: Main: 315453440 12:40:29 INFO - PROCESS | 1661 | 12:40:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:40:32 INFO - PROCESS | 1661 | RSS: Main: 324849664 12:40:32 INFO - PROCESS | 1661 | 12:40:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:40:36 INFO - PROCESS | 1661 | RSS: Main: 326795264 12:40:36 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 12:40:37 INFO - PROCESS | 1661 | RSS: Main: 370491392 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | __start_tp_report 12:40:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 12:40:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 12:40:37 INFO - PROCESS | 1661 | |i|pagename|runs| 12:40:37 INFO - PROCESS | 1661 | |0;ai-astar;102;113;112;112;113;112;114;112;111;113 12:40:37 INFO - PROCESS | 1661 | |1;audio-beat-detection;146;112;106;156;107;108;143;107;141;108 12:40:37 INFO - PROCESS | 1661 | |2;audio-dft;183;184;184;182;185;185;179;185;184;186 12:40:37 INFO - PROCESS | 1661 | |3;audio-fft;80;75;57;111;58;56;62;60;125;58 12:40:37 INFO - PROCESS | 1661 | |4;audio-oscillator;70;117;129;98;71;69;94;70;65;94 12:40:37 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;88;86;91;87;90;86;91;89;89;87 12:40:37 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;93;94;107;152;121;93;94;94;93 12:40:37 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;84;83;85;85;84;86;85;86;84 12:40:37 INFO - PROCESS | 1661 | |8;json-parse-financial;43;43;75;86;85;86;84;61;54;55 12:40:37 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;72;77;73;71;72;68;67;68;68 12:40:37 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;87;85;88;88;85;86;95;105;109;87 12:40:37 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;145;148;148;133;133;136;136;134;231;135 12:40:37 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;167;167;168;165;166;165;166;168;168;164 12:40:37 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;66;66;66;67;66;67;67;67;66 12:40:37 INFO - PROCESS | 1661 | __end_tp_report 12:40:37 INFO - PROCESS | 1661 | __start_cc_report 12:40:37 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1979 12:40:37 INFO - PROCESS | 1661 | __end_cc_report 12:40:37 INFO - PROCESS | 1661 | __startTimestamp1467834037958__endTimestamp 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | ------- Summary: start ------- 12:40:37 INFO - PROCESS | 1661 | Number of tests: 14 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:3.41 (3.0%) stddev-sans-first:0.88 12:40:37 INFO - PROCESS | 1661 | Values: 102.0 113.0 112.0 112.0 113.0 112.0 114.0 112.0 111.0 113.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:126.50 stddev:20.31 (16.1%) stddev-sans-first:19.83 12:40:37 INFO - PROCESS | 1661 | Values: 146.0 112.0 106.0 156.0 107.0 108.0 143.0 107.0 141.0 108.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:2.00 (1.1%) stddev-sans-first:2.11 12:40:37 INFO - PROCESS | 1661 | Values: 183.0 184.0 184.0 182.0 185.0 185.0 179.0 185.0 184.0 186.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:74.20 Median:59.00 stddev:24.66 (41.8%) stddev-sans-first:26.06 12:40:37 INFO - PROCESS | 1661 | Values: 80.0 75.0 57.0 111.0 58.0 56.0 62.0 60.0 125.0 58.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:70.50 stddev:22.41 (31.8%) stddev-sans-first:22.84 12:40:37 INFO - PROCESS | 1661 | Values: 70.0 117.0 129.0 98.0 71.0 69.0 94.0 70.0 65.0 94.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.00 stddev:1.90 (2.1%) stddev-sans-first:2.01 12:40:37 INFO - PROCESS | 1661 | Values: 88.0 86.0 91.0 87.0 90.0 86.0 91.0 89.0 89.0 87.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:93.00 stddev:19.39 (20.8%) stddev-sans-first:20.19 12:40:37 INFO - PROCESS | 1661 | Values: 93.0 93.0 94.0 107.0 152.0 121.0 93.0 94.0 94.0 93.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 12:40:37 INFO - PROCESS | 1661 | Values: 85.0 84.0 83.0 85.0 85.0 84.0 86.0 85.0 86.0 84.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:79.50 stddev:17.95 (22.6%) stddev-sans-first:16.77 12:40:37 INFO - PROCESS | 1661 | Values: 43.0 43.0 75.0 86.0 85.0 86.0 84.0 61.0 54.0 55.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.50 stddev:3.54 (5.0%) stddev-sans-first:3.24 12:40:37 INFO - PROCESS | 1661 | Values: 65.0 72.0 77.0 73.0 71.0 72.0 68.0 67.0 68.0 68.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:87.00 stddev:8.70 (10.0%) stddev-sans-first:9.07 12:40:37 INFO - PROCESS | 1661 | Values: 87.0 85.0 88.0 88.0 85.0 86.0 95.0 105.0 109.0 87.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:140.50 stddev:29.82 (21.2%) stddev-sans-first:31.61 12:40:37 INFO - PROCESS | 1661 | Values: 145.0 148.0 148.0 133.0 133.0 136.0 136.0 134.0 231.0 135.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:167.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 12:40:37 INFO - PROCESS | 1661 | Values: 167.0 167.0 168.0 165.0 166.0 165.0 166.0 168.0 168.0 164.0 12:40:37 INFO - PROCESS | 1661 | 12:40:37 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 12:40:37 INFO - PROCESS | 1661 | Values: 67.0 66.0 66.0 66.0 67.0 66.0 67.0 67.0 67.0 66.0 12:40:37 INFO - PROCESS | 1661 | -------- Summary: end -------- 12:40:37 INFO - PROCESS | 1661 | 12:40:38 INFO - TEST-INFO | 1661: exit 0 12:40:38 INFO - TEST-OK | kraken | took 58956ms 12:40:38 INFO - SUITE-END | took 1175s 12:40:39 INFO - Completed test suite (00:19:35) 12:40:39 INFO - javascript benchmark 12:40:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.318514704486, 3335.466325387937, 3350.0, 3366.720646154464, 3404.319136172766, 5510.752016159272, 5613.915791263134, 5641.802536911209, 5699.658020518772, 5748.396418376073, 5634.690092044936, 5694.202811606378, 5736.541076713864, 5755.136729490568, 5785.045467497858, 5087.949120508803, 5096.413912400066, 5154.716490593016, 5157.071727089123, 5196.272521846944, 5166.819161329354, 5205.037068142406, 5221.321228240343, 5243.842684719464, 5277.340332458442, 4977.129002424597, 5026.371703537058, 5037.345145131109, 5085.262636917626, 5159.329287192679, 4195.832166713328, 5066.797328106872, 5149.871253218662, 5228.111221092423, 5247.921281180786, 5066.113430149746, 5122.795088196468, 5143.048536020848, 5177.094008548489, 5191.506806853343, 5489.478499542577, 5610.410906854762, 5674.744636491368, 5681.034224181899, 5719.28508936383, 6130.325664176937, 6139.723712432907, 6155.169052177988, 6171.660558669271, 6179.5674302798825, 5497.642653227527, 6207.844803879894, 6243.375662433766, 6255.749770009194, 6308.432241098323, 5776.364599894008, 5809.622374545641, 5832.9708351457975, 5833.620814647034, 5837.328707198684, 6099.2985806632805, 6155.7688462307715, 6259.405356491126, 6287.968560157261, 6291.653959032297, 5464.754086066137, 6145.508359331243, 6151.415615516519, 6156.353582873734, 6165.568410211332, 5871.383504732027, 6203.0075187969705, 6206.503479721611, 6240.563160578807, 6249.468795152372, 5966.552508561876, 6061.54538409621, 6135.478484328793, 6146.524834039798, 6204.038374052029, 6173.446336098777, 6191.288001879751, 6208.441240288395, 6230.6884655767935, 6232.968835155795, 4727.172744769651, 4814.735189564608, 4854.68444551103, 4894.094592500328, 4921.729304888194, 3613.837377318027, 4263.381809637585, 4294.785260737013, 4341.327094300388, 4362.083962367875, 5693.28833895763, 5709.257796486513, 5714.05718056516, 5735.799247026334, 5763.6541807491685, 6141.508679305645, 6164.7842325518395, 6243.75024999004, 6281.842953926189, 6281.968590157111, 5605.243292155506, 5633.0, 5634.549236061105, 5635.295588051494, 5662.546996240291, 5380.0, 5385.138377859524, 5387.515123638891, 5389.407165211824, 5402.081646120169, 6209.0686397039, 6225.81322560333, 6232.25212974446, 6259.655718935503, 6399.200099987502, 6227.844303892439, 6245.594036387621, 6257.342978987141, 6279.96860015697, 6282.654454004891, 6324.905126423199, 6333.018382150774, 6350.555461117678, 6353.0, 6356.809295721136, 5988.431099045583, 6126.387361263838, 6127.816165515042, 6139.062858969336, 6159.106929495288, 6109.404666050415, 6195.132681424694, 6279.748810047547, 6281.120643109877, 6303.968480157753, 5895.351511333835, 6083.7870674526175, 6113.908291375722, 6123.418275263843, 6127.479164271087, 5869.148973398918, 5879.059350503899, 5986.191864098291, 5993.730282137227, 6049.818505444844, 4447.377367168664, 5846.766129354778, 5933.1396947443245, 5935.940640593539, 5995.250593675791, 5913.5564832636865, 6080.939190608037, 6093.6039157454625, 6104.032647854634, 6111.669269563957, 5737.34020587621, 5775.537956963517, 5806.274215723035, 5814.5639077068545, 5873.530117590668], "value": 5634.189505097545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.2870781383411, 527.3065918317415, 539.6222644149096, 546.6419495230625, 564.5370795947325, 24424.511509769793, 42668.359974600404, 42728.71813845586, 43982.560174398284, 44120.779396103055, 44621.33068003983, 45005.77497112518, 45115.32327015098, 45142.64572062835, 45176.774116129454, 8821.735347939572, 9603.951980240088, 9809.509524523744, 9818.754531136707, 9845.65540206093, 8774.561271936409, 9566.47384393858, 11212.607558735404, 12014.098942579341, 12392.566260180896, 12051.15641905067, 12110.212836165665, 12113.636590902288, 12115.0, 12115.151939364248, 8818.955905220497, 8994.325425593106, 9005.504697241715, 12048.879511204907, 12166.878331216663, 11584.247023943417, 11864.288142711464, 11902.702432439171, 12140.696482587917, 12165.51337946481, 42872.142557148996, 43388.78305608483, 43488.56511434877, 43513.78243108764, 43516.782416088034, 3570.767900086486, 3580.5703315602036, 3622.6920711739535, 3689.6863766579872, 4301.763403012833, 32199.0, 33545.6645433545, 33761.6623833761, 33868.49197262043, 33914.0, 3319.219983303946, 3342.4317865962844, 3369.3429781192676, 3406.0463070340343, 3451.637578054293, 500174.0, 500596.0, 501531.0, 501897.0, 502167.0, 34618.65381346205, 63035.684821575596, 63103.68448157822, 63606.0, 63620.04569931455, 34620.13449663779, 36510.81744591313, 36548.63451365452, 36627.0, 36653.63346366555, 15130.319135638923, 15302.693946121015, 15306.846931530765, 15317.157556334509, 15320.004199727206, 11790.115741319436, 11903.464344104535, 12707.0, 12845.293508857103, 12873.742525149444, 8007.07918589358, 8019.759407217793, 8046.356291496666, 8072.354211663052, 8136.837263254702, 2836.0, 3185.984070079635, 3301.141703157148, 3332.3002169544666, 3344.7324214062814, 932.6455946740195, 1013.6806905824642, 1081.6106201767357, 1106.7272636467965, 1107.6234080412698, 28099.719002810118, 31167.68832311648, 31262.531062034086, 31783.84108079491, 32053.679463205535, 1000.979980400388, 1064.861567996171, 1067.4289255248405, 1082.723905404117, 1238.68413554543, 13499.865001350057, 16444.424445144363, 16532.338706451606, 16557.0, 16558.586035348973, 606411.0, 711442.0, 717578.0, 718672.0, 719054.0, 62228.0, 62547.6872615634, 62580.37419625746, 62722.0, 62918.0562291566, 1269.6889262130837, 1395.1001603965587, 1409.0911362171341, 1417.7660685986696, 1438.6331485471137, 32071.679283206868, 32284.35431291408, 32344.83827580847, 32457.51313730249, 33107.172320692174, 53041.20438193353, 53280.73359633177, 53718.0, 53867.0, 53869.730651346494, 563599.0, 564284.0, 565412.0, 565861.0, 566597.0, 24808.875955620108, 27445.862770686017, 27725.029623963066, 27766.889324426797, 28810.27974300659, 50184.74907625438, 50311.74844125756, 50677.0, 51715.74142129265, 51846.22230666468, 41209.79395103005, 41849.1630167401, 41938.79030604827, 42898.0, 43149.78425107854, 35756.46365304574, 37267.881963541135, 37770.05574860651, 41505.58494415017, 41854.79072604617, 32609.391248700522, 35250.294994100484, 40261.39607905825, 41327.793361033, 41351.0, 30197.547036794025, 35053.64946350504, 35871.82064089767, 36819.63180368269, 36981.0, 20049.398518044483, 24467.632985504875, 26197.607035894856, 26511.469770604865, 27383.178504644893], "value": 19628.236029319432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.386829981682, 376.3620662976256, 379.3569899021157, 381.55739342362864, 403.43921948491584, 15188.848111518895, 15916.204189790507, 21191.682124768104, 21589.136434542637, 21606.675899861493, 19966.600667986633, 20705.275315363964, 20793.272235471763, 20845.791542084575, 20851.0, 4118.485189351331, 5816.505597024247, 6057.485113765313, 6059.333473317932, 6073.271207455112, 4978.601711863061, 6800.727970881158, 6806.965965170167, 6837.794866154023, 6850.972354146868, 6461.256955450113, 6692.698828552702, 6715.597064176165, 6727.831804204885, 6792.913133898577, 5973.641581505123, 6464.773732919325, 6483.124778154959, 6763.560368576027, 6774.119364482585, 5681.801136960228, 5732.140178973145, 5734.311882574076, 5736.885262294731, 5740.827775166752, 22585.661215081785, 24254.393640159138, 26340.60489092665, 26683.466330673276, 26708.0, 2192.47380628648, 2283.703118594572, 2414.348126005986, 2899.753520950722, 3227.2254658881698, 14792.778108328384, 15088.849111508855, 15152.848471515365, 15264.542063738105, 15473.458428955046, 1790.7045337519294, 1845.6124213915093, 1863.105709259563, 1917.3864363403716, 2098.6044280553415, 122451.38774306161, 126077.73922260752, 127454.0, 128714.0, 128862.71137288601, 11581.594644187413, 11583.478743456564, 11605.941970290263, 11632.476538555786, 11804.996575291196, 14412.207328597027, 14439.0, 14459.566213013626, 14473.986820922644, 14486.492972746115, 8597.355198360148, 8656.567171641393, 8668.049688819965, 8694.82610347802, 8765.693200737944, 8691.826163476695, 8704.738857834274, 8721.389502734748, 8774.28901364228, 8841.204291613785, 4533.682642215013, 4579.129965306581, 4592.311153326961, 4604.0, 4618.838340658061, 1588.586158319097, 1772.6011647379494, 1826.1508398594717, 1927.5277556998626, 1927.8650494465253, 808.7533302342842, 856.4304737349718, 906.5739102621757, 925.9074092590687, 1013.8479228115841, 20600.690989635168, 21032.0, 21192.894035529724, 21222.25722099719, 21248.78751212499, 933.4970349787961, 964.9469279189711, 971.8483596937651, 977.5356705564942, 996.6013594562088, 10700.197485188643, 11319.547218111184, 12236.877631223573, 12302.81545776814, 12507.499700011898, 544150.0, 551487.0, 554929.0, 564488.0, 564760.0, 99353.0, 136062.0, 136257.0, 140819.0, 141529.0, 995.4425521707867, 1016.598443614768, 1018.4093225928943, 1020.9795804083876, 1039.7920415916847, 19209.252312830205, 19686.01569921527, 19945.80596134453, 19950.90270035104, 19963.900180499586, 44043.55956440394, 56652.09493582437, 57189.0, 57757.42242577689, 58443.70778146082, 82096.76854847302, 82527.0, 83501.16498835177, 83634.58182709047, 84555.0, 7091.680874360563, 7414.777556673308, 7437.7396791114215, 7703.576303303148, 8180.263776260165, 11641.825372619587, 13366.866331336561, 13454.46182152742, 13557.79663305071, 13663.9752018601, 10013.699589012342, 10566.524506397383, 11591.652250432164, 11766.411679416165, 11911.0, 11338.603148890079, 11401.372924489233, 11541.826872597087, 11648.90394223215, 11781.410929453321, 11765.176437649283, 11779.293242405483, 11832.17174797756, 11904.821427678418, 11963.880361196276, 7418.295261950106, 7547.660355283915, 7605.315521603082, 7683.462157648911, 7693.269139431745, 10990.835137473105, 11405.201635885409, 11482.483288252215, 11591.362475063952, 11647.184697071123], "value": 9884.889089440398, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.6137296362392, 1607.0679006176415, 1855.396995976308, 1938.8739731917415, 1985.3646833013433, 8585.055643879175, 8891.91108088919, 9034.277257819374, 9034.412763170389, 9041.683541076063, 7991.041075071, 8253.834923301545, 8591.527465989368, 8803.515806630632, 8807.691730789424, 2351.412146963255, 2392.294273189413, 2393.8324317297797, 2394.8443351182204, 2394.916177933773, 2367.95264094718, 2371.9407014824683, 2405.0620258099357, 2408.771166739166, 2409.6024156014237, 1884.331062472823, 2087.674326802476, 2178.4880553070093, 2178.9564208715815, 2187.9890600546973, 2150.6451435513122, 2271.2958982715327, 2317.073170731704, 2324.058756203723, 2326.7091613548305, 2287.3709729824263, 2305.5273668897835, 2314.826388020905, 2319.2694301295205, 2324.6745455636224, 8121.512709237465, 8255.917440825575, 8256.711015114503, 8290.295324897392, 8291.502509849428, 5296.549793267577, 5457.181422786574, 5457.372402173761, 5464.9453505464835, 5476.205950137248, 3563.875264365735, 3645.143391303042, 3726.7018638508853, 3737.1591391936868, 3738.3551237455704, 1034.9793004139874, 1061.3419679798574, 1087.3747595132752, 1089.6839916424228, 1090.37303550458, 12015.699607509794, 12062.638120856389, 12103.152779305452, 12121.575744848977, 12129.090318226168, 4578.267477203632, 4742.502037286035, 4833.009233107205, 4838.225883858566, 4849.927251091236, 10166.593336266615, 10173.237007224488, 10187.694369168938, 10204.489775511194, 10210.4384258865, 5431.158170483582, 5441.374241962146, 5445.782168713286, 5454.12733962572, 5462.590305727086, 4844.588210002119, 4894.363732714724, 4972.676776009548, 4974.527419895104, 4989.650724449254, 3340.7828491148, 3985.4221137934837, 4062.878113656595, 4089.141280331104, 4099.549049604541, 1291.4317700212064, 1292.2698675248455, 1294.1264646363668, 1305.8106574546637, 1307.4704744578462, 844.7666407045659, 856.2251162697769, 870.4472659860908, 914.0767824497306, 922.2253307221965, 5979.1928089707335, 6099.420555047263, 6102.359252278536, 6115.724792384353, 6181.690915454209, 938.3900464697864, 972.4019727867311, 1009.268280496634, 1063.0751246415514, 1072.5012869015945, 3905.9609403906165, 3964.365701487748, 3996.500437445319, 4076.9184616307507, 4108.36320370343, 10657.573697052187, 10910.181736369805, 10943.288686235528, 10997.395143267197, 11034.889651103385, 10560.47197640115, 10923.235373523778, 10927.125829933586, 10950.616728414467, 10955.50700218492, 8326.12575679557, 8396.32829373661, 8461.619227134794, 8469.788255293666, 8484.024337201177, 4969.627277954096, 5839.8540036497725, 5980.820575382745, 6018.187544681412, 6033.034714445667, 10164.593416263267, 10234.6417875374, 10235.590576376862, 10239.539220735234, 10253.231007674305, 9015.909840901508, 10111.292209545261, 10463.581456741646, 10476.895231047592, 10483.108935740547, 7579.317861392501, 7604.0, 7716.074071111502, 7717.035370578677, 7738.574378409016, 8163.346932245525, 8291.33669306442, 8319.916800831914, 8335.666573337, 8371.246587807127, 7092.680829362588, 7523.0972283326355, 7582.35549978258, 7606.239376062349, 7720.0, 6597.4392176663605, 7567.243275672388, 7576.3938884887975, 7585.6965721370525, 7607.581583012766, 7098.29017098286, 7118.8932166018585, 7125.251848556034, 7158.928410715826, 7179.605121718356, 5029.899402011866, 5063.670861393997, 5081.77132029067, 5099.209622508517, 5217.321748172713, 4416.9116617668105, 5070.681622778031, 5146.562542183807, 5188.636795424284, 5192.844214673566], "value": 4643.741937374488, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.63119047975744, 305.7645612878083, 307.34842134674494, 311.62293624714107, 314.15492325644004, 4867.513248675131, 5056.8230111945995, 5399.029354285687, 5435.701036442999, 5470.538257783993, 5370.838874833761, 5389.622726409153, 5398.406175320722, 5444.237806707054, 5475.780968761254, 3885.49488566486, 3916.6279203475624, 4065.085355794952, 4114.855980040699, 4116.897077573055, 3070.416620842044, 4025.3156963316305, 4053.493313335833, 4063.756174629517, 4131.35963925592, 3893.4549163117044, 4078.5717499662555, 4084.8570300039355, 4100.671946244308, 4109.321961876301, 3643.362411577963, 3991.44119823225, 4018.4173294872226, 4099.754014759124, 4109.835606575733, 3558.2527669189503, 3563.7327200460068, 3565.894572682451, 3589.353916295066, 3595.26297109092, 3889.572147063821, 4064.3497040473676, 5013.29813826061, 5068.6705364151585, 5407.918881216746, 4571.748553829538, 4703.882402939919, 4704.411948506437, 4718.905621887543, 4803.879903002453, 5616.691081990489, 5642.307307807641, 5650.886982260332, 5652.491275785199, 5658.717064146817, 3962.841486340543, 3989.880303590897, 3990.1820126874213, 4010.3984402339593, 4014.2537995971848, 4877.658563900528, 5734.566358410398, 5936.39717276333, 5948.702564871739, 5957.4042595740075, 4001.839466554725, 4009.0578714002186, 4037.7375470594316, 4048.1701251243426, 4078.838899852579, 4120.773357465308, 4122.979385103055, 4136.0, 4136.338185890243, 4150.79246037703, 4334.656256560476, 4431.645468362523, 4481.4174157359985, 4744.667873248839, 4810.7835147418455, 5668.858278542987, 5682.829515114553, 5685.607026278588, 5698.686572238566, 5708.372079071299, 1367.7743172376645, 1396.0785881318484, 1410.5063227870164, 1416.4546649539795, 1416.553785557546, 812.480661163616, 862.2498426368974, 876.513534988075, 882.2544949517647, 884.8141890203129, 4800.975995120002, 5073.518015788494, 5112.054269959995, 5300.681959082467, 5313.26366921036, 5556.638818476786, 5792.536597072224, 5839.678817665069, 5840.503557197686, 5889.852753681106, 5010.298558201855, 5108.66793658411, 5304.363476382859, 5306.442823503554, 5360.061989151883, 2713.4030513287044, 2922.7369536741794, 3092.273315770837, 3133.295008623087, 3142.842857857144, 5563.944360556424, 5973.761049557969, 6103.633781973096, 6112.441327461469, 6194.969025154845, 4353.804078816461, 4760.738159401266, 5477.890442191134, 5562.554995600423, 6017.849553761191, 5689.634487722917, 6029.638221706711, 6111.205543279345, 6112.358202388863, 6132.5707200495535, 4847.733374664427, 5386.946130538645, 5518.034343989866, 5912.438318359749, 5918.526517878646, 4692.812287508598, 4969.701817890938, 5742.425757424224, 5771.942280577308, 5787.247657804459, 5604.411536788742, 5815.82552523425, 6065.302490213682, 6109.2974307955155, 6114.052321890114, 3092.582501362287, 3321.9237976264835, 3339.949900751442, 3920.725549211528, 3949.7037722171526, 5052.494750525065, 5530.755579994469, 5811.62224455426, 5855.180274761452, 5877.324107727666, 5541.3904470509015, 5737.647564498536, 5748.48263656273, 5760.711964401847, 5782.959067367914, 5121.718305493085, 5175.792968281226, 5389.27244821944, 5500.669959802424, 5673.375928647928, 4921.753912304299, 5063.367079115111, 5157.252198431282, 5335.67985920846, 5578.9163162553405, 4873.805917550223, 5435.646682965595, 5745.339285981999, 5778.306603207642, 5807.767689292381, 5109.952459745818, 5174.482551744795, 5633.887322253614, 5636.2954630671165, 5645.774169033192], "value": 4145.807332695918, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.3003386714885, 724.0, 732.2201855024399, 736.6353654940805, 858.4034096303072, 17776.822231777678, 18384.90807545962, 18389.724154137697, 18426.355077572287, 18428.539286517862, 17066.488005359828, 17145.31418743252, 17308.913455432707, 17314.48056558302, 17322.220500077496, 614.8893199224137, 896.9910300896989, 902.7451841939676, 925.1303774451993, 927.0960813207137, 737.634870738985, 882.7395918204113, 900.0234745301323, 911.0525053943924, 917.2157805076663, 937.8358787212245, 949.5347279832866, 953.4422362917702, 953.818774432859, 955.44106697582, 741.5624781379013, 946.129560804055, 946.5551190940281, 948.369334392635, 948.4830767231875, 678.2166597579803, 689.8723736108785, 690.5028379566704, 701.0430762009871, 706.1244057368878, 15461.845381546153, 15506.069635821774, 15528.301226444837, 15529.611759705986, 15544.533663990098, 13841.215026073789, 13848.0, 14091.295435228198, 14133.363998620087, 14493.071150791448, 12836.550720724823, 13367.732645347136, 13427.530036448776, 13498.257595832225, 13793.86206137946, 4659.860204193879, 4791.928121078186, 4828.961773218774, 4834.105690447279, 4847.951520484785, 113276.21737329784, 115570.0, 116144.0, 117034.0, 118206.99999999914, 10944.507497162647, 11093.611723589636, 11281.266717663326, 11440.656780296604, 11451.36642387928, 21864.453388665093, 21984.120635174415, 21988.0, 22023.788691622114, 22085.0, 7876.330511906437, 7936.841263174704, 7938.6427610757655, 7978.162292959272, 8074.313683336864, 4407.118576284756, 4478.888027799266, 4508.0, 4535.138323718483, 4717.9056418872115, 2779.8880447821034, 3120.921976950549, 3224.4357237483346, 3228.225225945756, 3230.305484320835, 676.1075380497696, 680.1939701453794, 680.6630717794625, 681.2200030964617, 681.3322943515383, 606.617830766623, 637.2766909557592, 651.1404945472026, 657.3590749019668, 658.9505787065989, 9087.409318394282, 9090.909090909006, 9119.452832830051, 9125.589348479201, 9212.309076819265, 514.0977584339461, 544.8011475811301, 637.3435361577582, 654.4731491149576, 678.0879716780922, 4328.09110086879, 4358.4769827620885, 4375.124975005012, 4416.977915110404, 4479.013007911998, 75184.62407687926, 98131.50934245283, 115106.0, 124717.0, 154636.3095505925, 84034.31931361339, 105666.0, 123938.0, 127900.0, 128268.35865820797, 89707.65438518427, 103824.48087759664, 107276.46361768141, 107692.99999999843, 110767.0, 8135.0, 8605.268552173135, 8612.224899759052, 8843.986595507013, 8941.0, 48472.0, 51347.0, 59276.70361648164, 65179.0, 65475.67262163849, 94971.05028949623, 97324.0, 97740.51129744305, 99903.5014474769, 101469.49265253627, 13478.460861565427, 13827.861721382658, 13958.511452099128, 13971.510997115458, 14250.0, 15213.695726085638, 15326.463573774865, 15384.0, 15490.225488725744, 15788.842111578737, 13951.093178943336, 14182.0, 14201.431942722176, 14642.853571464575, 14871.884608654611, 14077.296135193405, 14187.290635468391, 14255.0, 14822.703545929236, 14829.33268002964, 13939.860601393857, 14006.649833754238, 14020.789688154482, 14021.929890350482, 14627.34176962018, 11176.916327669864, 11426.82859757121, 11454.713632159262, 11514.251573647685, 12326.198797078161, 9904.950475247866, 11134.3876086816, 11494.82757758651, 11760.294382337088, 11777.764444710887], "value": 7962.903459095159, "unit": "score"}], "name": "dromaeo_css", "value": 7425.283339030793, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 112.0, 113.0, 112.0, 114.0, 112.0, 111.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 106.0, 156.0, 107.0, 108.0, 143.0, 107.0, 141.0, 108.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 182.0, 185.0, 185.0, 179.0, 185.0, 184.0, 186.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 57.0, 111.0, 58.0, 56.0, 62.0, 60.0, 125.0, 58.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 117.0, 129.0, 98.0, 71.0, 69.0, 94.0, 70.0, 65.0, 94.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 91.0, 87.0, 90.0, 86.0, 91.0, 89.0, 89.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 107.0, 152.0, 121.0, 93.0, 94.0, 94.0, 93.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 85.0, 85.0, 84.0, 86.0, 85.0, 86.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 75.0, 86.0, 85.0, 86.0, 84.0, 61.0, 54.0, 55.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 77.0, 73.0, 71.0, 72.0, 68.0, 67.0, 68.0, 68.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 88.0, 85.0, 86.0, 95.0, 105.0, 109.0, 87.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 148.0, 148.0, 133.0, 133.0, 136.0, 136.0, 134.0, 231.0, 135.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 168.0, 165.0, 166.0, 165.0, 166.0, 168.0, 168.0, 164.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1466.5000000000002, "alertThreshold": 2.0}]} 12:40:39 INFO - Return code: 0 12:40:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:40:39 INFO - # TBPL SUCCESS # 12:40:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:40:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:40:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:40:39 INFO - 2016-07-06 12:40:39.263 screenresolution[1665:10216] starting screenresolution argv=screenresolution get 12:40:39 INFO - 2016-07-06 12:40:39.265 screenresolution[1665:10216] Display 0: 1600x1200x32@60 12:40:39 INFO - 2016-07-06 12:40:39.270 screenresolution[1666:10219] starting screenresolution argv=screenresolution list 12:40:39 INFO - Available Modes on Display 0 12:40:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:40:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:40:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:40:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:40:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:40:39 INFO - Intel Iris: 12:40:39 INFO - Chipset Model: Intel Iris 12:40:39 INFO - Type: GPU 12:40:39 INFO - Bus: Built-In 12:40:39 INFO - VRAM (Dynamic, Max): 1536 MB 12:40:39 INFO - Vendor: Intel (0x8086) 12:40:39 INFO - Device ID: 0x0a2e 12:40:39 INFO - Revision ID: 0x0009 12:40:39 INFO - Displays: 12:40:39 INFO - Display: 12:40:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:40:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:40:39 INFO - Display Serial Number: 1600x1200 60 12:40:39 INFO - Main Display: Yes 12:40:39 INFO - Mirror: Off 12:40:39 INFO - Online: Yes 12:40:39 INFO - Rotation: Supported 12:40:39 INFO - Return code: 0 12:40:39 INFO - Running post-action listener: _resource_record_post_action 12:40:39 INFO - ##### 12:40:39 INFO - ##### Finished run-tests step (success) 12:40:39 INFO - ##### 12:40:39 INFO - Running post-run listener: _resource_record_post_run 12:40:39 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38123008; Write bytes: 706224640; Read time: 390; Write time: 1963 12:40:39 INFO - TinderboxPrint: CPU usage
53.5% 12:40:39 INFO - TinderboxPrint: I/O read bytes / time
38,123,008 / 390 12:40:39 INFO - TinderboxPrint: I/O write bytes / time
706,224,640 / 1,963 12:40:39 INFO - TinderboxPrint: CPU idle
2,254.9 (46.5%) 12:40:39 INFO - TinderboxPrint: CPU system
970.6 (20.0%) 12:40:39 INFO - TinderboxPrint: CPU user
1,620.1 (33.4%) 12:40:39 INFO - TinderboxPrint: Swap in / out
445,304,832 / 0 12:40:39 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 404761088; Write bytes: 398579200; Read time: 30899; Write time: 725 12:40:39 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 34522624; Write bytes: 295963648; Read time: 359; Write time: 1204 12:40:39 INFO - Running post-run listener: _upload_blobber_files 12:40:39 INFO - Blob upload gear active. 12:40:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:40:39 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 12:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:40:40 INFO - (blobuploader) - INFO - Open directory for files ... 12:40:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:40:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:40:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:41 INFO - (blobuploader) - INFO - Done attempting. 12:40:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:40:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:40:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:42 INFO - (blobuploader) - INFO - Done attempting. 12:40:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:40:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:40:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:45 INFO - (blobuploader) - INFO - Done attempting. 12:40:45 INFO - (blobuploader) - INFO - Iteration through files over. 12:40:45 INFO - Return code: 0 12:40:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:40:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:40:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9463211ecf9f5ccde9f366e885ac847e5ad48743493d051a07686b5ba25eebcf89a93cb0d79108a2f214ce1c06f19bc7af7512dc8fb13df203438618cb72c0d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7c5a0b878000f08e881410719cf08d5b4dde7d10c5a5b3aeea22b77f2c44c4be70738ab85e02974e35e1fee50b0ff30a8fa6b07dfab41223cc6f8502fa85a39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ccee0dd877f30b2634fb26873fcc397d8201ed7bd48a37334bdc2236fdc94359781d0430050eb9edb0aefa5b55145957c4f4e1df33b1892c7d9424957953e7d"} 12:40:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:40:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:40:45 INFO - Contents: 12:40:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9463211ecf9f5ccde9f366e885ac847e5ad48743493d051a07686b5ba25eebcf89a93cb0d79108a2f214ce1c06f19bc7af7512dc8fb13df203438618cb72c0d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7c5a0b878000f08e881410719cf08d5b4dde7d10c5a5b3aeea22b77f2c44c4be70738ab85e02974e35e1fee50b0ff30a8fa6b07dfab41223cc6f8502fa85a39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ccee0dd877f30b2634fb26873fcc397d8201ed7bd48a37334bdc2236fdc94359781d0430050eb9edb0aefa5b55145957c4f4e1df33b1892c7d9424957953e7d"} 12:40:45 INFO - Running post-run listener: copy_logs_to_upload_dir 12:40:45 INFO - Copying logs to upload dir... 12:40:45 INFO - mkdir: /builds/slave/test/build/upload/logs 12:40:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.964311 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-06 12:40:45.222250) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 12:40:45.225439) ========= 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.vQr7Z3roia/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.pNRtgoys2p/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9463211ecf9f5ccde9f366e885ac847e5ad48743493d051a07686b5ba25eebcf89a93cb0d79108a2f214ce1c06f19bc7af7512dc8fb13df203438618cb72c0d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7c5a0b878000f08e881410719cf08d5b4dde7d10c5a5b3aeea22b77f2c44c4be70738ab85e02974e35e1fee50b0ff30a8fa6b07dfab41223cc6f8502fa85a39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ccee0dd877f30b2634fb26873fcc397d8201ed7bd48a37334bdc2236fdc94359781d0430050eb9edb0aefa5b55145957c4f4e1df33b1892c7d9424957953e7d"} build_url:https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010950 build_url: 'https://queue.taskcluster.net/v1/task/O785NOX-Qg2PidnepmuYyw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9463211ecf9f5ccde9f366e885ac847e5ad48743493d051a07686b5ba25eebcf89a93cb0d79108a2f214ce1c06f19bc7af7512dc8fb13df203438618cb72c0d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7c5a0b878000f08e881410719cf08d5b4dde7d10c5a5b3aeea22b77f2c44c4be70738ab85e02974e35e1fee50b0ff30a8fa6b07dfab41223cc6f8502fa85a39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ccee0dd877f30b2634fb26873fcc397d8201ed7bd48a37334bdc2236fdc94359781d0430050eb9edb0aefa5b55145957c4f4e1df33b1892c7d9424957953e7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 12:40:45.257062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 12:40:45.257378) ========= 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.vQr7Z3roia/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.pNRtgoys2p/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 12:40:45.305931) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 12:40:45.306249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 12:40:45.306579) ========= ========= Total master_lag: 0.17 =========