builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0103 starttime: 1461524870.07 results: success (0) buildid: 20160424110357 builduid: b58a134547904beab7fff3c3f7564f40 revision: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.072130) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.072712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.073002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.093782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.094301) ========= 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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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-04-24 12:07:50-- 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% 28.5M=0s 2016-04-24 12:07:50 (28.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.444876 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.565644) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.565944) ========= 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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.026434 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.615095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:50.615437) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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-04-24 12:07:50,686 truncating revision to first 12 chars 2016-04-24 12:07:50,686 Setting DEBUG logging. 2016-04-24 12:07:50,687 attempt 1/10 2016-04-24 12:07:50,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/13cbfc180fe6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 12:07:51,412 unpacking tar archive at: fx-team-13cbfc180fe6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980645 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:51.614007) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:51.614472) ========= script_repo_revision: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:51.615024) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:51.615453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 12:07:51.630550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-24 12:07:51.631038) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:07:51 INFO - MultiFileLogger online at 20160424 12:07:51 in /builds/slave/test 12:07:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:07:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:51 INFO - {'append_to_log': False, 12:07:51 INFO - 'base_work_dir': '/builds/slave/test', 12:07:51 INFO - 'blob_upload_branch': 'Fx-Team', 12:07:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:51 INFO - 'branch': 'Fx-Team', 12:07:51 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:51 INFO - 'config_files': ('talos/mac_config.py',), 12:07:51 INFO - 'default_actions': ('clobber', 12:07:51 INFO - 'read-buildbot-config', 12:07:51 INFO - 'download-and-extract', 12:07:51 INFO - 'populate-webroot', 12:07:51 INFO - 'create-virtualenv', 12:07:51 INFO - 'install', 12:07:51 INFO - 'run-tests'), 12:07:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:51 INFO - 'download_minidump_stackwalk': True, 12:07:51 INFO - 'download_symbols': 'ondemand', 12:07:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:51 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:51 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:51 INFO - 'installer_path': 'installer.exe', 12:07:51 INFO - 'log_level': 'info', 12:07:51 INFO - 'log_name': 'talos', 12:07:51 INFO - 'log_to_console': True, 12:07:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:07:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:07:51 INFO - 'opt_config_files': (), 12:07:51 INFO - 'pip_index': False, 12:07:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:51 INFO - 'cmd': ('bash', 12:07:51 INFO - '-c', 12:07:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:51 INFO - 'enabled': True, 12:07:51 INFO - 'halt_on_failure': False, 12:07:51 INFO - 'name': 'check_screen_resolution'},), 12:07:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:51 INFO - 'cmd': ('bash', 12:07:51 INFO - '-c', 12:07:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:51 INFO - 'enabled': True, 12:07:51 INFO - 'halt_on_failure': False, 12:07:51 INFO - 'name': 'check_screen_resolution'},), 12:07:51 INFO - 'run_cmd_checks_enabled': True, 12:07:51 INFO - 'sps_profile': False, 12:07:51 INFO - 'sps_profile_interval': 0, 12:07:51 INFO - 'suite': 'dromaeojs', 12:07:51 INFO - 'system_bits': '32', 12:07:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:51 INFO - 'title': 't-yosemite-r7-0103', 12:07:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:51 INFO - 'use_talos_json': True, 12:07:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:51 INFO - 'work_dir': 'build'} 12:07:51 INFO - ##### 12:07:51 INFO - ##### Running clobber step. 12:07:51 INFO - ##### 12:07:51 INFO - Running pre-action listener: _resource_record_pre_action 12:07:51 INFO - Running main action method: clobber 12:07:51 INFO - rmtree: /builds/slave/test/build 12:07:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:53 INFO - Running post-action listener: _resource_record_post_action 12:07:53 INFO - ##### 12:07:53 INFO - ##### Running read-buildbot-config step. 12:07:53 INFO - ##### 12:07:53 INFO - Running pre-action listener: _resource_record_pre_action 12:07:53 INFO - Running main action method: read_buildbot_config 12:07:53 INFO - Using buildbot properties: 12:07:53 INFO - { 12:07:53 INFO - "project": "", 12:07:53 INFO - "product": "firefox", 12:07:53 INFO - "script_repo_revision": "production", 12:07:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:07:53 INFO - "repository": "", 12:07:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:07:53 INFO - "buildid": "20160424110357", 12:07:53 INFO - "pgo_build": "False", 12:07:53 INFO - "basedir": "/builds/slave/test", 12:07:53 INFO - "buildnumber": 713, 12:07:53 INFO - "slavename": "t-yosemite-r7-0103", 12:07:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:07:53 INFO - "platform": "yosemite_r7", 12:07:53 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:07:53 INFO - "branch": "fx-team", 12:07:53 INFO - "repo_path": "integration/fx-team", 12:07:53 INFO - "revision": "13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2", 12:07:53 INFO - "stage_platform": "macosx64", 12:07:53 INFO - "builduid": "b58a134547904beab7fff3c3f7564f40", 12:07:53 INFO - "slavebuilddir": "test" 12:07:53 INFO - } 12:07:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 12:07:53 INFO - Running post-action listener: _resource_record_post_action 12:07:53 INFO - ##### 12:07:53 INFO - ##### Running download-and-extract step. 12:07:53 INFO - ##### 12:07:53 INFO - Running pre-action listener: _resource_record_pre_action 12:07:53 INFO - Running main action method: download_and_extract 12:07:53 INFO - mkdir: /builds/slave/test/build/tests 12:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:53 INFO - https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:07:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:07:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:07:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 12:07:55 INFO - Downloaded 1199 bytes. 12:07:55 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:07:55 INFO - Using the following test package requirements: 12:07:55 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:07:55 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 12:07:55 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 12:07:55 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'jsshell-mac.zip'], 12:07:55 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 12:07:55 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:07:55 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 12:07:55 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 12:07:55 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 12:07:55 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:07:55 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 12:07:55 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:07:55 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:07:55 INFO - https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:07:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:07:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:07:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:07:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:07:57 INFO - Downloaded 18109566 bytes. 12:07:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:07:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:08:00 INFO - Return code: 0 12:08:00 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:00 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:08:00 INFO - https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:08:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:08:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:08:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:08:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:03 INFO - Downloaded 18109566 bytes. 12:08:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:08:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:08:06 INFO - Return code: 0 12:08: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:08:06 INFO - https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:08:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:08:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:08:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:08:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:08:07 INFO - Downloaded 10840145 bytes. 12:08:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:08:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:08:07 INFO - Return code: 0 12:08:07 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:08:07 INFO - https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:08:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:08:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:08:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 12:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 12:08:12 INFO - Downloaded 110094410 bytes. 12:08:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:08:12 INFO - mkdir: /builds/slave/test/properties 12:08:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:12 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:12 INFO - Contents: 12:08:12 INFO - build_url:https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:08:13 INFO - Running post-action listener: _resource_record_post_action 12:08:13 INFO - Running post-action listener: set_extra_try_arguments 12:08:13 INFO - ##### 12:08:13 INFO - ##### Running populate-webroot step. 12:08:13 INFO - ##### 12:08:13 INFO - Running pre-action listener: _resource_record_pre_action 12:08:13 INFO - Running main action method: populate_webroot 12:08:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:13 INFO - '--tppagecycles', 12:08:13 INFO - '7'], 12:08:13 INFO - 'tests': ['tsvgm']}}, 12:08:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:08:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['damp', 'tps']}, 12:08:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'tests': ['damp', 'tps']}, 12:08:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['dromaeo_dom']}, 12:08:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['a11yr', 12:08:13 INFO - 'ts_paint', 12:08:13 INFO - 'tpaint', 12:08:13 INFO - 'sessionrestore', 12:08:13 INFO - 'sessionrestore_no_auto_restore', 12:08:13 INFO - 'tabpaint']}, 12:08:13 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:13 INFO - 'ts_paint', 12:08:13 INFO - 'tpaint', 12:08:13 INFO - 'sessionrestore', 12:08:13 INFO - 'sessionrestore_no_auto_restore', 12:08:13 INFO - 'tabpaint']}, 12:08:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:13 INFO - 'ts_paint', 12:08:13 INFO - 'tpaint', 12:08:13 INFO - 'sessionrestore', 12:08:13 INFO - 'sessionrestore_no_auto_restore', 12:08:13 INFO - 'tabpaint']}, 12:08:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:13 INFO - 'ts_paint', 12:08:13 INFO - 'tpaint', 12:08:13 INFO - 'sessionrestore', 12:08:13 INFO - 'sessionrestore_no_auto_restore', 12:08:13 INFO - 'tabpaint']}, 12:08:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['a11yr', 12:08:13 INFO - 'ts_paint', 12:08:13 INFO - 'tpaint', 12:08:13 INFO - 'sessionrestore', 12:08:13 INFO - 'sessionrestore_no_auto_restore', 12:08:13 INFO - 'tabpaint']}, 12:08:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['a11yr', 12:08:13 INFO - 'ts_paint', 12:08:13 INFO - 'tpaint', 12:08:13 INFO - 'sessionrestore', 12:08:13 INFO - 'sessionrestore_no_auto_restore', 12:08:13 INFO - 'tabpaint']}, 12:08:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['tsvgx', 12:08:13 INFO - 'tsvgr_opacity', 12:08:13 INFO - 'tart', 12:08:13 INFO - 'tscrollx', 12:08:13 INFO - 'cart']}, 12:08:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:13 INFO - 'tsvgr_opacity', 12:08:13 INFO - 'tart', 12:08:13 INFO - 'tscrollx', 12:08:13 INFO - 'cart']}, 12:08:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'talos_options': ['--disable-e10s'], 12:08:13 INFO - 'tests': ['tp5o']}, 12:08:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'tests': ['tp5o']}, 12:08:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'talos_options': ['--disable-e10s', 12:08:13 INFO - '--xperf_path', 12:08:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:13 INFO - 'tests': ['tp5n']}, 12:08:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:13 INFO - 'talos_options': ['--xperf_path', 12:08:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:13 INFO - 'tests': ['tp5n']}}, 12:08:13 INFO - 'talos.zip': {'path': '', 12:08:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:13 INFO - Running post-action listener: _resource_record_post_action 12:08:13 INFO - ##### 12:08:13 INFO - ##### Running create-virtualenv step. 12:08:13 INFO - ##### 12:08:13 INFO - Running pre-action listener: _resource_record_pre_action 12:08:13 INFO - Running main action method: create_virtualenv 12:08:13 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:13 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:08:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:13 INFO - Using real prefix '/tools/python27' 12:08:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:14 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:17 INFO - Installing pip.................done. 12:08:17 INFO - Return code: 0 12:08:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08: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:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:17 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 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:17 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:08:17 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:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:17 INFO - 'HOME': '/Users/cltbld', 12:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:17 INFO - 'LOGNAME': 'cltbld', 12:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:17 INFO - 'MOZ_NO_REMOTE': '1', 12:08:17 INFO - 'NO_EM_RESTART': '1', 12:08:17 INFO - 'PAGER': '/bin/cat', 12:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:17 INFO - 'PWD': '/builds/slave/test', 12:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:17 INFO - 'SHELL': '/bin/bash', 12:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:17 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:17 INFO - 'USER': 'cltbld', 12:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:17 INFO - 'XPC_FLAGS': '0x0', 12:08:17 INFO - 'XPC_SERVICE_NAME': '0', 12:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:17 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:08:17 INFO - Cleaning up... 12:08:17 INFO - Return code: 0 12:08:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:08: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:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:17 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:17 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>=0.7.1'] in /builds/slave/test/build 12:08:17 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>=0.7.1 12:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:17 INFO - 'HOME': '/Users/cltbld', 12:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:17 INFO - 'LOGNAME': 'cltbld', 12:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:17 INFO - 'MOZ_NO_REMOTE': '1', 12:08:17 INFO - 'NO_EM_RESTART': '1', 12:08:17 INFO - 'PAGER': '/bin/cat', 12:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:17 INFO - 'PWD': '/builds/slave/test', 12:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:17 INFO - 'SHELL': '/bin/bash', 12:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:17 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:17 INFO - 'USER': 'cltbld', 12:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:17 INFO - 'XPC_FLAGS': '0x0', 12:08:17 INFO - 'XPC_SERVICE_NAME': '0', 12:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:17 INFO - Downloading/unpacking psutil>=0.7.1 12:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:08:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:21 INFO - Installing collected packages: psutil 12:08:21 INFO - Running setup.py install for psutil 12:08:21 INFO - building 'psutil._psutil_osx' extension 12:08:21 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:08:21 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:08:21 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:08:22 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:08:22 INFO - building 'psutil._psutil_posix' extension 12:08:22 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:08:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:08:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:08:22 INFO - ^ 12:08:22 INFO - 1 warning generated. 12:08:22 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:08:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:22 INFO - Successfully installed psutil 12:08:22 INFO - Cleaning up... 12:08:22 INFO - Return code: 0 12:08:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:08: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:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:22 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:22 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.0.0'] in /builds/slave/test/build 12:08:22 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.0.0 12:08:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:22 INFO - 'HOME': '/Users/cltbld', 12:08:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:22 INFO - 'LOGNAME': 'cltbld', 12:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:22 INFO - 'MOZ_NO_REMOTE': '1', 12:08:22 INFO - 'NO_EM_RESTART': '1', 12:08:22 INFO - 'PAGER': '/bin/cat', 12:08:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:22 INFO - 'PWD': '/builds/slave/test', 12:08:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:22 INFO - 'SHELL': '/bin/bash', 12:08:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:22 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:22 INFO - 'USER': 'cltbld', 12:08:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:22 INFO - 'XPC_FLAGS': '0x0', 12:08:22 INFO - 'XPC_SERVICE_NAME': '0', 12:08:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:08:22 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:08:22 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:08:22 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:08:22 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:08:22 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:08:22 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:08:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:08:25 INFO - Installing collected packages: mozsystemmonitor 12:08:25 INFO - Running setup.py install for mozsystemmonitor 12:08:25 INFO - Successfully installed mozsystemmonitor 12:08:25 INFO - Cleaning up... 12:08:25 INFO - Return code: 0 12:08:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:25 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 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:25 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:08:25 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:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:25 INFO - 'HOME': '/Users/cltbld', 12:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:25 INFO - 'LOGNAME': 'cltbld', 12:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:25 INFO - 'MOZ_NO_REMOTE': '1', 12:08:25 INFO - 'NO_EM_RESTART': '1', 12:08:25 INFO - 'PAGER': '/bin/cat', 12:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:25 INFO - 'PWD': '/builds/slave/test', 12:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:25 INFO - 'SHELL': '/bin/bash', 12:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:25 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:25 INFO - 'USER': 'cltbld', 12:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:25 INFO - 'XPC_FLAGS': '0x0', 12:08:25 INFO - 'XPC_SERVICE_NAME': '0', 12:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:25 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:25 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:08:25 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:08:25 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:08:25 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:08:25 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:08:25 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:08:28 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:28 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:08:28 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:08:28 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:08:28 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:08:28 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:08:28 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:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:28 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:08:28 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:08:28 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:08:28 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:08:28 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:08:28 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:08:29 INFO - Downloading docopt-0.6.1.tar.gz 12:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:29 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:29 INFO - Running setup.py install for blobuploader 12:08:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:29 INFO - Running setup.py install for requests 12:08:30 INFO - Running setup.py install for docopt 12:08:30 INFO - Successfully installed blobuploader requests docopt 12:08:30 INFO - Cleaning up... 12:08:30 INFO - Return code: 0 12:08:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:30 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 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:30 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:08:30 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:08:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:30 INFO - 'HOME': '/Users/cltbld', 12:08:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:30 INFO - 'LOGNAME': 'cltbld', 12:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:30 INFO - 'MOZ_NO_REMOTE': '1', 12:08:30 INFO - 'NO_EM_RESTART': '1', 12:08:30 INFO - 'PAGER': '/bin/cat', 12:08:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:30 INFO - 'PWD': '/builds/slave/test', 12:08:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:30 INFO - 'SHELL': '/bin/bash', 12:08:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:30 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:30 INFO - 'USER': 'cltbld', 12:08:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:30 INFO - 'XPC_FLAGS': '0x0', 12:08:30 INFO - 'XPC_SERVICE_NAME': '0', 12:08:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:30 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-fO3IH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:30 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-GLPzhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:30 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-hhp7rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:30 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-xgnez7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:30 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-I6hCOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-XpCLPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-uxNT3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5Fv1fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-nbr1Sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-vTjsSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-m1SMPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-hsAv3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-yz7cuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-aqokTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:31 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-MLlKw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:32 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Ew4r9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:32 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-elE9KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:32 INFO - Running setup.py install for manifestparser 12:08:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:32 INFO - Running setup.py install for mozcrash 12:08:32 INFO - Running setup.py install for mozdebug 12:08:32 INFO - Running setup.py install for mozdevice 12:08:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:08:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:32 INFO - Running setup.py install for mozfile 12:08:33 INFO - Running setup.py install for mozhttpd 12:08:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Running setup.py install for mozinfo 12:08:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Running setup.py install for mozInstall 12:08:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Running setup.py install for mozleak 12:08:33 INFO - Running setup.py install for mozlog 12:08:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Running setup.py install for moznetwork 12:08:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:33 INFO - Running setup.py install for mozprocess 12:08:34 INFO - Running setup.py install for mozprofile 12:08:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:34 INFO - Running setup.py install for mozrunner 12:08:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:34 INFO - Running setup.py install for mozscreenshot 12:08:34 INFO - Running setup.py install for moztest 12:08:34 INFO - Running setup.py install for mozversion 12:08:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:34 INFO - Cleaning up... 12:08:34 INFO - Return code: 0 12:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:34 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:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:34 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:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:34 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 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:34 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:08:34 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:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:34 INFO - 'HOME': '/Users/cltbld', 12:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:34 INFO - 'LOGNAME': 'cltbld', 12:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:34 INFO - 'MOZ_NO_REMOTE': '1', 12:08:34 INFO - 'NO_EM_RESTART': '1', 12:08:34 INFO - 'PAGER': '/bin/cat', 12:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:34 INFO - 'PWD': '/builds/slave/test', 12:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:34 INFO - 'SHELL': '/bin/bash', 12:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:34 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:34 INFO - 'USER': 'cltbld', 12:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:34 INFO - 'XPC_FLAGS': '0x0', 12:08:34 INFO - 'XPC_SERVICE_NAME': '0', 12:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-_U0zme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-X9qZyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-bPBNzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jNaSSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-MNU1Ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Bccc6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-J6pt_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rv7sfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:35 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:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-wN05MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-BAPJY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-1ZkiEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-59Dwv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-sdBnfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rC2ZmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-ZlpUhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-4omVUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:36 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:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-vtwrkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:36 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:08:36 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:08:36 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:08:36 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:08:36 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:08:36 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:08:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:39 INFO - Downloading blessings-1.6.tar.gz 12:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:08:39 INFO - Installing collected packages: blessings 12:08:39 INFO - Running setup.py install for blessings 12:08:39 INFO - Successfully installed blessings 12:08:39 INFO - Cleaning up... 12:08:39 INFO - Return code: 0 12:08:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:39 INFO - Reading from file tmpfile_stdout 12:08:39 INFO - Current package versions: 12:08:39 INFO - blessings == 1.6 12:08:39 INFO - blobuploader == 1.2.4 12:08:39 INFO - docopt == 0.6.1 12:08:39 INFO - manifestparser == 1.1 12:08:39 INFO - mozInstall == 1.12 12:08:39 INFO - mozcrash == 0.17 12:08:39 INFO - mozdebug == 0.1 12:08:39 INFO - mozdevice == 0.48 12:08:39 INFO - mozfile == 1.2 12:08:39 INFO - mozhttpd == 0.7 12:08:39 INFO - mozinfo == 0.9 12:08:39 INFO - mozleak == 0.1 12:08:39 INFO - mozlog == 3.1 12:08:39 INFO - moznetwork == 0.27 12:08:39 INFO - mozprocess == 0.22 12:08:39 INFO - mozprofile == 0.28 12:08:39 INFO - mozrunner == 6.11 12:08:39 INFO - mozscreenshot == 0.1 12:08:39 INFO - mozsystemmonitor == 0.0 12:08:39 INFO - moztest == 0.7 12:08:39 INFO - mozversion == 1.4 12:08:39 INFO - psutil == 3.1.1 12:08:39 INFO - requests == 1.2.3 12:08:39 INFO - wsgiref == 0.1.2 12:08:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:39 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 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:39 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:08:39 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:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:39 INFO - 'HOME': '/Users/cltbld', 12:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:39 INFO - 'LOGNAME': 'cltbld', 12:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:39 INFO - 'MOZ_NO_REMOTE': '1', 12:08:39 INFO - 'NO_EM_RESTART': '1', 12:08:39 INFO - 'PAGER': '/bin/cat', 12:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:39 INFO - 'PWD': '/builds/slave/test', 12:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:39 INFO - 'SHELL': '/bin/bash', 12:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:39 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:39 INFO - 'USER': 'cltbld', 12:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:39 INFO - 'XPC_FLAGS': '0x0', 12:08:39 INFO - 'XPC_SERVICE_NAME': '0', 12:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 INFO - Cleaning up... 12:08:40 INFO - Return code: 0 12:08:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:08:40 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:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:40 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:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:40 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 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:40 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:08:40 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:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:40 INFO - 'HOME': '/Users/cltbld', 12:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:40 INFO - 'LOGNAME': 'cltbld', 12:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:40 INFO - 'MOZ_NO_REMOTE': '1', 12:08:40 INFO - 'NO_EM_RESTART': '1', 12:08:40 INFO - 'PAGER': '/bin/cat', 12:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:40 INFO - 'PWD': '/builds/slave/test', 12:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:40 INFO - 'SHELL': '/bin/bash', 12:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:40 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:40 INFO - 'USER': 'cltbld', 12:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:40 INFO - 'XPC_FLAGS': '0x0', 12:08:40 INFO - 'XPC_SERVICE_NAME': '0', 12:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:40 INFO - Downloading/unpacking jsonschema 12:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:40 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:08:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:42 INFO - Downloading/unpacking functools32 (from jsonschema) 12:08:42 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:08:42 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:08:42 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:08:42 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:08:42 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:08:42 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:08:43 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:43 INFO - warning: no files found matching '*.txt' 12:08:43 INFO - no previously-included directories found matching 'build' 12:08:43 INFO - no previously-included directories found matching 'dist' 12:08:43 INFO - no previously-included directories found matching '.git*' 12:08:43 INFO - Installing collected packages: jsonschema, functools32 12:08:43 INFO - Running setup.py install for functools32 12:08:43 INFO - warning: no files found matching '*.txt' 12:08:43 INFO - no previously-included directories found matching 'build' 12:08:43 INFO - no previously-included directories found matching 'dist' 12:08:43 INFO - no previously-included directories found matching '.git*' 12:08:43 INFO - Successfully installed jsonschema functools32 12:08:43 INFO - Cleaning up... 12:08:43 INFO - Return code: 0 12:08:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1023baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b05a6870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, '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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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:08:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:43 INFO - 'HOME': '/Users/cltbld', 12:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:43 INFO - 'LOGNAME': 'cltbld', 12:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:43 INFO - 'MOZ_NO_REMOTE': '1', 12:08:43 INFO - 'NO_EM_RESTART': '1', 12:08:43 INFO - 'PAGER': '/bin/cat', 12:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:43 INFO - 'PWD': '/builds/slave/test', 12:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:43 INFO - 'SHELL': '/bin/bash', 12:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:08:43 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:43 INFO - 'USER': 'cltbld', 12:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:43 INFO - 'XPC_FLAGS': '0x0', 12:08:43 INFO - 'XPC_SERVICE_NAME': '0', 12:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:43 INFO - Downloading/unpacking flake8 12:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:08:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:08:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:08:47 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:08:47 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:08:47 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:08:47 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:08:47 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:08:47 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:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:08:47 INFO - no previously-included directories found matching 'docs/_build' 12:08:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:08:47 INFO - Running setup.py install for pep8 12:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:08:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:08:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:08:47 INFO - no previously-included directories found matching 'docs/_build' 12:08:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:08:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:08:47 INFO - Cleaning up... 12:08:47 INFO - Return code: 0 12:08:47 INFO - Running post-action listener: _resource_record_post_action 12:08:47 INFO - Running post-action listener: _start_resource_monitoring 12:08:47 INFO - Starting resource monitoring. 12:08:47 INFO - ##### 12:08:47 INFO - ##### Running install step. 12:08:47 INFO - ##### 12:08:47 INFO - Running pre-action listener: _resource_record_pre_action 12:08:47 INFO - Running main action method: install 12:08:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:48 INFO - Reading from file tmpfile_stdout 12:08:48 INFO - Detecting whether we're running mozinstall >=1.0... 12:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:08:48 INFO - Reading from file tmpfile_stdout 12:08:48 INFO - Output received: 12:08:48 INFO - Usage: mozinstall [options] installer 12:08:48 INFO - Options: 12:08:48 INFO - -h, --help show this help message and exit 12:08:48 INFO - -d DEST, --destination=DEST 12:08:48 INFO - Directory to install application into. [default: 12:08:48 INFO - "/builds/slave/test"] 12:08:48 INFO - --app=APP Application being installed. [default: firefox] 12:08:48 INFO - mkdir: /builds/slave/test/build/application 12:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:09:21 INFO - Reading from file tmpfile_stdout 12:09:21 INFO - Output received: 12:09:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:09:21 INFO - Running post-action listener: _resource_record_post_action 12:09:21 INFO - ##### 12:09:21 INFO - ##### Running run-tests step. 12:09:21 INFO - ##### 12:09:21 INFO - Running pre-action listener: _resource_record_pre_action 12:09:21 INFO - Running main action method: run_tests 12:09:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:21 INFO - 2016-04-24 12:09:21.816 screenresolution[1658:10422] starting screenresolution argv=screenresolution get 12:09:21 INFO - 2016-04-24 12:09:21.819 screenresolution[1658:10422] Display 0: 1600x1200x32@60 12:09:21 INFO - 2016-04-24 12:09:21.825 screenresolution[1659:10424] starting screenresolution argv=screenresolution list 12:09:21 INFO - Available Modes on Display 0 12:09:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:22 INFO - Intel Iris: 12:09:22 INFO - Chipset Model: Intel Iris 12:09:22 INFO - Type: GPU 12:09:22 INFO - Bus: Built-In 12:09:22 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:22 INFO - Vendor: Intel (0x8086) 12:09:22 INFO - Device ID: 0x0a2e 12:09:22 INFO - Revision ID: 0x0009 12:09:22 INFO - Displays: 12:09:22 INFO - Display: 12:09:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:22 INFO - Display Serial Number: 1600x1200 60 12:09:22 INFO - Main Display: Yes 12:09:22 INFO - Mirror: Off 12:09:22 INFO - Online: Yes 12:09:22 INFO - Rotation: Supported 12:09:22 INFO - Return code: 0 12:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:22 INFO - Python 2.7.3 12:09:22 INFO - Return code: 0 12:09:22 INFO - grabbing minidump binary from tooltool 12:09: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:09:22 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102259e00>, '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 0x102082db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102254b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:22 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:09:22 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:09:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:22 INFO - Return code: 0 12:09:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:09:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:09:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:09:23 INFO - Return code: 0 12:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --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:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xlfHKL8spx/Render', 12:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:23 INFO - 'HOME': '/Users/cltbld', 12:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:23 INFO - 'LOGNAME': 'cltbld', 12:09:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:23 INFO - 'MOZ_NO_REMOTE': '1', 12:09:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:23 INFO - 'NO_EM_RESTART': '1', 12:09:23 INFO - 'PAGER': '/bin/cat', 12:09:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:23 INFO - 'PWD': '/builds/slave/test', 12:09:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:23 INFO - 'SHELL': '/bin/bash', 12:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ag6l6lOYKZ/Listeners', 12:09:23 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 12:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:23 INFO - 'USER': 'cltbld', 12:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:23 INFO - 'XPC_FLAGS': '0x0', 12:09:23 INFO - 'XPC_SERVICE_NAME': '0', 12:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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:09:23 INFO - mozversion application_buildid: 20160424110357 12:09:23 INFO - mozversion application_changeset: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 12:09:23 INFO - mozversion application_display_name: Nightly 12:09:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:23 INFO - mozversion application_name: Firefox 12:09:23 INFO - mozversion application_remotingname: firefox 12:09:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:09:23 INFO - mozversion application_vendor: Mozilla 12:09:23 INFO - mozversion application_version: 48.0a1 12:09:23 INFO - mozversion platform_buildid: 20160424110357 12:09:23 INFO - mozversion platform_changeset: 13cbfc180fe6ea8ba7ad99eb5da126e1b5b7fdf2 12:09:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:09:23 INFO - mozversion platform_version: 48.0a1 12:09:23 INFO - using testdate: 1461524963 12:09:23 INFO - actual date: 1461524963 12:09:23 INFO - starting webserver on 'localhost:49275' 12:09:23 INFO - SUITE-START | Running 2 tests 12:09:23 INFO - TEST-START | dromaeo_css 12:09:23 INFO - operating with platform_type : mac_ 12:09:23 INFO - Initialising browser for dromaeo_css test... 12:09:23 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpSRaDps/profile http://localhost:49275/getInfo.html) 12:09:26 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 12:09:26 INFO - PROCESS | 1677 | colorDepth:24 12:09:26 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 12:09:26 INFO - PROCESS | 1677 | __metrics 12:09:26 INFO - PROCESS | 1677 | 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:09:26 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:09:26 INFO - TEST-INFO | 1677: exit 0 12:09:26 INFO - Browser initialized. 12:09:26 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:26 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpSRaDps/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:38 INFO - PROCESS | 1680 | RSS: Main: 239259648 12:09:38 INFO - PROCESS | 1680 | 12:10:30 INFO - PROCESS | 1680 | 1461525030143 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:12:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:12:41 INFO - PROCESS | 1680 | RSS: Main: 360181760 12:12:41 INFO - PROCESS | 1680 | 12:15:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:15:45 INFO - PROCESS | 1680 | RSS: Main: 377737216 12:15:45 INFO - PROCESS | 1680 | 12:18:48 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:18:48 INFO - PROCESS | 1680 | RSS: Main: 376381440 12:18:48 INFO - PROCESS | 1680 | 12:21:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:21:51 INFO - PROCESS | 1680 | RSS: Main: 377556992 12:21:51 INFO - PROCESS | 1680 | 12:24:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:24:55 INFO - PROCESS | 1680 | RSS: Main: 349003776 12:24:55 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:27:58 INFO - PROCESS | 1680 | RSS: Main: 412323840 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | __start_tp_report 12:27:58 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 12:27:58 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 12:27:58 INFO - PROCESS | 1680 | |i|pagename|runs| 12:27:58 INFO - PROCESS | 1680 | |0;dojo.html;2867.6845546989825;3472.305538892221;3473.7568370214094;3500.352434799561;3533.929321413572;5645.379008309083;5714.371419143891;5722.685252311121;5741.397153298901;5745.368009518955;5667.518260947814;5737.225474560933;5746.25298711168;5749.942500574993;5759.10733836255;5183;5203.427622961472;5230.110881150214;5241.42344342122;5294.258803767476;5303.098473259555;5304.177852432879;5306.522412982831;5311.7344132793205;5311.893762124755;5220.817271395502;5268.973655131738;5293.497117773805;5302;5358.866028349303;5175.974120129394;5200.713960732158;5205.505476979681;5206.114960456694;5251.894962100775;5128.640995130342;5130.281760553526;5149.588032957355;5155.9742201288755;5160.587153027749;5553.972230138824;5571;5602.971985140089;5611;5637.7181140942785;6197.473214776749;6231.563790534665;6243.406876346739;6243.937560624427;6251.156093927307;4987.426445958725;6159.87680246397;6200.689965501752;6269.843253918689;6292.056191571301;4827.468978412372;5796.275465566804;5822.301323841166;5831.679257640785;5895.941040589625;6252.296812768778;6301.842453938688;6307.148534947723;6309.463695585835;6311.495080393649;6072.635641861502;6113;6131.233595800525;6188.133661287423;6219.129321894939;6228.377162283826;6263.404976527314;6267.1539342188225;6276.717547710364;6283.6858157091965;6156.26124865019;6214.658193799294;6223.315435302113;6232.968835155795;6248.968755156195;6090;6152.723127459275;6158.107074474176;6241.78153764616;6272.372762723691;4905.632077594123;4919;4919.704817710949;4951.430585482643;4953.108440480748;4378.014946637045;4436.578525040116;4464.910701785946;4471.06107717383;4530.932036019462;5674.716264186774;5678.290213723285;5678.829635110953;5680.318361796528;5704.258446401941;6236.906446403307;6283.08895210192;6288.685565721696;6289.685515724195;6289.7484100635465;5522.972385138129;5611;5625.437456254342;5687.914681279784;5707.143928410772;5377.623566350318;5383.515483606494;5396.568274538027;5404.675719456845;5406.243125962368;6308.842278943063;6325.683715814191;6349.587276826992;6358.300586935341;6361.904571431525;6093.897179118431;6156.29202641702;6245.28179259391;6278.0269058296035;6278.686065696788;6301.0863424804065;6307.085472606354;6320.336364681826;6348.71430785625;6354.1104245406605;6055.7577696893395;6187.288461826767;6202.34875338083;6209.565330426827;6213.782517611862;6175.166352542529;6238.251409830849;6285.49716022708;6291.370862913672;6303.275123360872;6119.694015299307;6121.8469538260115;6169.660668663267;6175.567710260419;6178.567500275118;5935.614185077956;6053.092036194606;6073.5748497604745;6121.84695382619;6126.938730612636;5884;5888.440598143169;5898.262717160355;5905.35041145465;5937.020391635332;4776.8566942991765;6078.665673388006;6094.300975544442;6098.146259523759;6120.234970628671;5823.970880145742;5882.941170588239;5894.823155305347;5896.262967129109;5908.3500814911185 12:27:58 INFO - PROCESS | 1680 | |1;ext.html;508.45341258147477;521.2962500624157;524.3655177235545;541.247665744615;583.0991118721574;25112.49775004499;31388.372232555335;31445.842770786116;31470.527942080887;38591;34317;43194.7041588752;43299.56700432995;43320.7003789886;43436;7930.9293245412;8573.842531258279;9587.328886977913;9619.711408657717;9629.422234665943;8196.221358970888;8657.480551166918;9562.474063926482;9693.806123877519;11285.717857053558;11623;11749.118816088827;11755.118366122531;11760.235584686969;11771.293722376684;11598.072154227684;11713.648590542254;11720.120990925714;11723.355215463145;11727.765444691058;8669.00306464752;11533.538658453652;11831.99428048617;11897.263629365209;11899.643010709693;39093.60906390928;39778.403323950166;39810.800945995376;39827.20345593072;39827.80086099551;3273.3289675616443;3367.7137443317347;3746.4754934309217;3813.523309586302;3916;29286;29350.436303623737;29487.852560737272;29747.702522974927;30227.546586801214;2713.4844379568017;2715.511207982562;2735.4529094181244;2833.6032955386263;2876.532660320746;504887;505502;507588;508214;508218;35954.28091438157;61957;62173;62628.37371626226;62759.37240627535;36024.639753602656;36030.459543106874;36152.457713134325;36155.457668135;36179.81910090433;14858.2570871456;15053.473128440452;15055.54833355001;15065.849341506664;15077.623059423602;11625.941870290595;12801.871981280254;12858.0999330046;12859.935700321439;12889.806652900214;7652.923470765332;8105.75682729519;8268.462549934235;8322.62548185333;8356.164383561596;3331.51693004513;3412.77816941898;3414.6414626463866;3416.4021296273054;3442.7417943653827;959.4099628728281;990.3513198854762;1007.0483393193362;1040.193849766437;1075.8547596074586;26486.822167573264;28699.56950645742;29371.825126995107;29634.703652963624;29833.85083074614;1009.3388830316155;1087.6628245243999;1097.9404874296183;1098;1169.2107827216594;14885.70228595422;14957.775633365509;15087.849121508863;15208.695826083416;15336.386544538316;652622;654165;662141;663408;664112;61863.6906815472;62676;62787.372126279064;62941.68529157325;63016;1343.7111021130504;1374.7456720506739;1381.2196109198271;1510.7582786754285;1592.824789273179;28803.56794648124;29036.12891613255;29271.85364073166;29620.703792961795;29664.961726339476;48594.75702621464;49109.2633610489;49588.51234462972;49591;49643.00713985628;438024;441308;442222;442303;443815;23771.52456950886;26175.869120654275;26438.603420948315;27112.72887271102;27478.313042174104;43003.784981074896;43387.78306108449;43510.12979740324;44319.44881929117;44556.55443445524;34177.31645367129;35388.82305588455;35802;36081.45877811783;36335.90992270236;34672;36486.27027459383;36543.81728091449;36741.81629091944;37357.43963840599;37207.25585488221;37594.87215383543;38527.229455410175;39403.80298098606;39405.21189576135;30470.84764576163;32209.51685724669;32251.35497290088;32264.67735322617;32379.838100810288;20578.971051447068;23740.52518949646;23857.642135367638;24007.399815004766;24748.257552273462 12:27:58 INFO - PROCESS | 1680 | |2;jquery.html;399.926013687468;400.8737247766953;401.67464353873356;402.2277227722772;412.6471866554096;14917.552473425787;20292.391228263157;20887.37337879866;21029.789702102993;21048.36854894352;15830.84169158308;17881.7317740234;20069.498262543442;20271.08780104895;20755.06602202901;5847.298324201101;6037.275526936752;6069.544784141207;6070.514358851281;6075.17985072014;5869.941300587004;5898.8230353089475;5947.40525947404;5951.702414879261;6001.309849367313;5769.16347129666;5941.7029148542615;5976.402359764011;5981.0729336952845;5982.401759824026;5850.561207909403;5936.910946335765;5955.791547295824;5966.67183304918;5969.611975221618;5030.647854650176;5187.19598462239;5209.244659524347;5211.557017653504;5217.843464696065;12661.36693165338;13397.330133493286;24197;25621.359466013313;25668.486630267478;1910.4650697804636;2023.7571491420977;2034.7660019097846;2111.092230340953;2277.089164334263;9358.906410935871;9774.951125244328;9966.551505182284;10209;10349.741256468573;1643.7041332560134;1678.202853644509;1696.6352234269568;1720.3290716620402;1806.349714102922;120611;125491;127159.36420317931;131162.34418827845;132860;9280.814383712423;9469.526523673789;11475.483603237873;11838.940805295919;11857.644270671894;14858.2570871456;14932.62668433298;14938.925305373403;14963.850361496463;14996.925015374853;7529.171791102975;7568.394528437711;8108.75673729789;8700.216980471789;8722.694705685397;8400.831983360298;8482.491050536988;8515.74452766418;8606.827863442697;8619.353548483889;4474.351219073216;4545.499995000548;4561.064981678748;4603.930941035887;4604.815807367735;1705.7100292950229;1820.3980497162413;1834.1779769335285;1851.6944704123669;1988.7613486381726;903.6656437118309;906.207068814787;909.1681111782644;989.4811464402109;1002.6390499420203;14693.853061469248;18764.81235187658;19805.801941980684;19810.70283945742;20141.395758126986;945.7068308824149;954.9092836180552;965.2037069417759;1005;1044.9738756531146;8476.957615211884;10432.374057556572;10435.68692939213;10441.389652586853;10447.268691191544;580136;582614;594945;601896;604297;128380;129482;132543;135371;136180;929.5445231836352;994.0904072773332;1010.7321559786662;1079.3577821196532;1082.2099867097063;14311.212883291519;16810.915945420194;17263.96416215031;17270.704697148016;17502.33139546452;53510.1973470406;53528;53816.730916345165;54179.72910135424;55056;78222.6088869552;81968.18031819606;82540.58729706313;83043.58478207812;88694.55652721695;8044.798880028046;8135.877961830459;8245.752627421187;8248.876266856121;8273.793155170928;8071.677132914618;10705.87588303248;12197.878021219674;12373.814392783936;12666.176698514568;10725.30285531438;10985.945070274598;11055.778884422105;11311.490982905954;11418;10737.946310268399;10866.511007004861;10915.563377464812;11113.333200008025;11128.777424451628;10963.945180274048;11296.096312294832;11324.433778310886;11358.26171298863;11640.708982275512;7981.3614910808155;8108.9594552026865;8121.431499795194;8344.83310333802;8485.915140848512;8264.297534709616;9959.502024898871;10989.285696429706;11100.5559777608;11259.549618015188 12:27:58 INFO - PROCESS | 1680 | |3;mootools.html;1280.3342262023755;1603.0221564845447;1603.2945503978249;1882.7552418185635;2001.3195513525407;7816.218378162188;8506.234438900505;8769.210771030606;8770.254528365096;8828.8675669865;8418.116097809718;8513.616887240074;8594.355423343242;8601.95699021504;8617.698380556682;2154.687570302305;2429.854208747472;2458.4222707663685;2465.704115506142;2472.0606169655507;2081.739782527184;2112.6514125169406;2413.263954493879;2509.473010667762;2514.069794176148;2425.248173066346;2455.4597988442515;2463.75362463754;2464.4331803685163;2474.3195621204222;2150.6989021536997;2362.7282862470693;2450.6324051392257;2457.164564048232;2467.2721547143638;2465.3097132802845;2482.2801387597574;2489.0914816092177;2492.140211626986;2496.0265496456395;8485.278751306147;8552.61513231906;8559.27246184075;8564.571771411467;8565.614547345385;5075.568576670988;5269.86825329366;5288.312519372495;5302.204669299597;5310.468953104697;3446.207372304384;3500.527428797105;3605.549306336708;3616.9095772605633;3621.112827357288;1019.9031092046243;1028.7685270814081;1029.1663752360612;1038.1176000399676;1044.300318786415;11434.256773309708;11524.250923689933;11525.827112593317;11537.307761534335;11553.53785848565;4075.3683179107284;4123.835046598102;4484.955150448519;4649.883752906137;4867.294242334841;9783.706488805346;9808.607655693835;9835.655752048644;9844.803103938024;9869;5333.013392522395;5348.893022139536;5355.116405793001;5375.838724838261;5380.650257733313;4024.778637174925;4331.566843315643;4895.951040489621;4908;4925.581325587294;3941.724079314478;3970.5235371755575;4070.6336429720873;4074.959250407458;4081.6326530611796;1174.0138283841613;1306.3141850528548;1320.9009324300523;1322.5966080345586;1347.1378317876568;778.8598052350632;797.8763291689797;867.4621734524635;950.7337945375306;978.1245785022365;5638.154276858422;5895.7052147393315;5947.04847224442;6024.939750602525;6123.695066196068;448.57833636381696;469.53516019140807;473.3302377136382;474.18913657645123;476.37832628420244;3902.5316961964745;4021.8190181441905;4071.185762847442;4076.184763047343;4084.509858816961;10113.949430252802;10182.694519164437;10612.09797167234;10638.574457021787;10639.627613033506;10511.211659125596;10534.262601617813;10615.893841061645;10647.988441098238;10648.574057037787;7685.346745526581;8048.959755201186;8056.919430805734;8200.507969521848;8201.22088401601;5823.7379317931645;5927.970360148344;5970.134330521963;6005;6041.546883983631;9553.18797902186;9829.852552211865;9838.75403114928;9840.3603765755;9889.208863291064;9575.760605984906;9733.756656083655;9829.655962041294;10073.244506662177;10076.647317343857;6852.246252912076;7096.467764917548;7277.854442911218;7440.03279573652;7556.131044929683;8266.875996860172;8387.83224335522;8400.327973762207;8404.87392689098;8411.495310281403;7173.605451700207;7366.63166841645;7384.741534046282;7498.437617178624;7544.132424771222;7262.527935684164;7264.527805692613;7299.124105107421;7379;7402.481826272325;6969.512134150764;7077.504574679723;7120.074390329224;7127.501074924706;7138.46461515378;4725.740084295397;4845.055214233191;4894.95105048945;5073.543381095571;5253.789848406021;4459.821607135678;4901.264810278487;5038.798448062036;5081.796728130981;5112.514311140436 12:27:58 INFO - PROCESS | 1680 | |4;prototype.html;276.83528300661106;319.80811513092146;322.63219929280626;331.7793667211305;335.6660123177439;4932.80268789248;5000.924986125202;5939.88120237595;5964;6059.454649081582;5397.866448045915;5766.654000759957;5955.255593050869;5989.777207069017;5994.310654274749;4278.716385084478;4367.672424568154;4461.416197249978;4544.363789069516;4547.204239258133;4455.554444555551;4503.414556107701;4521.389612402317;4530.343100250462;4540.818367265306;4494.483134439549;4497.527759585246;4521.344405061281;4536.478304994935;4561.657875659323;4059.269331520325;4169;4244.734042287481;4547.408836851221;4568.451785785727;3835.789031603261;3886.9222615547815;3905.8047097645003;3915.8629447969183;3929.017745563609;5258.027264955957;5784.595078344518;5793.333766616851;5795.391483894173;5813.883722325572;4791.401074865642;4871.512848715136;5128.769205385766;5129.794808207667;5144.897102057976;5851.239338885917;6332.461740752042;6346.460550853137;6347.174867267226;6356.71394787231;3980.8208630611693;4222.5566315536735;4228.809703563347;4236.894077648052;4245.426867372896;5586.776528938878;6558.2458017328145;6663.966680166616;6721.865562688768;6802.251752307242;3749.2688925659236;4205.201011807747;4301.763403012865;4303.289957157097;4307.590778876001;3738.831752571141;4076;4351.12977404514;4364;4385.2897370025075;5096.0827051131155;5100.795968161232;5110.3426218366985;5111.667741596784;5164.664296820694;6402.423781859655;6404.423601875854;6408.814369341687;6414.8717025659225;6422.6467544285515;1466.8606482384157;1479.2825479642365;1497.5507347795617;1498.0038274547303;1500.761871456053;911.6535716427715;940.1397721085131;964.768455570658;971.7424882406168;988.9208863290919;4452.198604251201;5547.195656629847;5880.176775251468;5899.764009439575;6055.515558755289;6355.809325720235;6539.967300163468;6586.80239592813;6587.275399706028;6609.876321025437;5728.51307638847;5877.089051197071;5946.940530594638;5958.553108516879;5983.19226904371;3458.472358194678;3466.9317653875996;3467.8439470223902;3494.790312581253;3495.1961048958374;6036.8188954331445;6470.223573171155;6584.275729669733;6846.281140480178;6903.5167538271835;4952.761809547613;6078.1186727924205;6516.771912983023;6745.494087943423;6788.626625535643;5671.8865622688145;6500.869982600321;6792.72829086831;6858;6883.242843287235;6470.126532917996;6528.347165283433;6529.216494020748;6563.245226798788;6574.276829548841;6122.173641474079;6466.224053113657;6484.5460817742305;6489.8702025960165;6569.37590928861;5924.9703751480965;6584.242812076673;6639.6680165992475;6741;6767.120274364498;4255.255330317244;4270.658347332268;4283.464566929134;4283.914321713486;4295.463067116611;5345.572354211576;5933;6151.938480615316;6464.644444555594;6490.902636460552;5240.633155679066;5529.889402212014;5636.408177141341;6358.586691865014;6370.6814659267775;5075.670081444694;6002.8199154023705;6178.165947597017;6335.1447554579545;6406.391392817675;5235.528802407802;5731.996900542305;6097.359777223328;6176.934913370993;6213.565050446426;5166.3800343958965;6445;6495.935040649533;6500.707468164039;6536.248331441755;5126.179811230185;6259.906101408392;6322.873542529215;6351;6373.298937117006 12:27:58 INFO - PROCESS | 1680 | |5;yui.html;659.4295934017075;732.2531018361271;760.4182800157879;761.4403413491086;785.2304741353473;14911.32899019544;14921.955463117587;15074.170920599363;15080.396784128636;15101.697966040672;11821.406654592796;13691.657708557292;13754.931225343884;13756.449742010333;13763.65590860229;729.1068441159564;890.5547226386807;928.7863791327999;930.3115694386173;934.5560858592182;893.9955300223489;896.090468174803;898.2813749000806;898.371140201858;899.8470260055774;963.0610155098793;973.5910917414703;979.0894468144622;980.9803803923917;981.1807141037207;958.8801399825022;970.4032020307495;971.2812518736155;971.8493633486821;972.8929817720045;556.476911703002;570.1191658887029;571.1090698510295;572.7565784541553;724.3480867219492;14497.492587759483;14896.255187240595;14905.850941490555;14912.403503859832;14917.925410372987;12417.254964702146;12522.937385313106;12640.05199610033;12656.430460629294;12928.935355323163;7854.607269636553;9639.614415423375;9652.90347096534;9729.70810875675;9938.204943604494;3435.587729472454;3480.564929383827;3511.262634846685;3536.1513236822975;3731.8320675569666;80060;80194.59902700449;80864;81040.18959810444;82621;9486.620535178654;9651.469169195621;9664.468454235084;9740.756481087888;9966.20270378368;18207.81792182088;22068;22134;22136.66794998076;22149.889250553642;7477.1775104738435;7595.506292090997;7616.961915190499;7643.770686879402;7700.730474433368;4061.1877624475223;4108.424820525129;4152.460180176618;4171.958280417218;4310.396544483775;2920.28453029005;3061.219389055821;3157.447446696865;3225.4033003894356;3226.596675415573;534.8636097795115;648.1379764912655;652.1424327009967;666.8132922781629;668.762589280808;519.3767479025184;527.454085022008;547.5564792518073;550.9146082357241;554.260062816137;6438.420542151135;6658.234303055114;6681.265060843303;6775.119234499483;7797.376209903194;534.3854567247696;544.6595877576515;571;601.8585632376388;603.0953569645532;3286.917827054295;3763.9435408468894;3788.431735239736;3857.459955606161;3871.457995880579;84024.15975840285;84283.57858210792;85667.57166214129;88873;89366.10633893577;76060.23939760642;76123;76125.61937190278;76860;77321.61339193268;67862.98205526921;68424.31575684279;68573.97139042919;68690;69133.70395552466;7365.767407766917;7390.334869861738;7437.553746775211;7672.769816905502;7836.177201393772;42711.78644106884;43616;43688.344674830536;44448;44512.777436112614;60070.39929600648;60468.69765651144;61056.69471652613;61653.38346616476;61983.690081549306;9791.461469618955;10149.644762433574;10287.897121028695;10501.31741436804;10663.46682665879;10888.727781805519;10894.291871028674;11032.779344412906;11238.66284011481;11494.712632184262;10454.320469169785;10509.31689440184;10691.984261495145;10781.838272426077;10810.972957569018;8659.826803464022;10136.493175341056;10583.735406614896;10588.047075763217;11165.827588103131;10047.246456515937;10064.597416103567;10248.180145588483;10252.231082668979;10283.94858025705;8152.429329946846;8354;8774.429662071945;9038.954805225932;9060.320475964198;8092.514449133071;8598.226159645661;8799.12008799115;8845.955770221108;8894.4663320201 12:27:58 INFO - PROCESS | 1680 | __end_tp_report 12:27:58 INFO - PROCESS | 1680 | __start_cc_report 12:27:58 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1103 12:27:58 INFO - PROCESS | 1680 | __end_cc_report 12:27:58 INFO - PROCESS | 1680 | __startTimestamp1461526078756__endTimestamp 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | ------- Summary: start ------- 12:27:58 INFO - PROCESS | 1680 | Number of tests: 6 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | [#0] dojo.html Cycles:165 Average:5729.05 Median:5896.26 stddev:645.06 (10.9%) stddev-sans-first:606.72 12:27:58 INFO - PROCESS | 1680 | Values: 2867.7 3472.3 3473.8 3500.4 3533.9 5645.4 5714.4 5722.7 5741.4 5745.4 5667.5 5737.2 5746.3 5749.9 5759.1 5183.0 5203.4 5230.1 5241.4 5294.3 5303.1 5304.2 5306.5 5311.7 5311.9 5220.8 5269.0 5293.5 5302.0 5358.9 5176.0 5200.7 5205.5 5206.1 5251.9 5128.6 5130.3 5149.6 5156.0 5160.6 5554.0 5571.0 5603.0 5611.0 5637.7 6197.5 6231.6 6243.4 6243.9 6251.2 4987.4 6159.9 6200.7 6269.8 6292.1 4827.5 5796.3 5822.3 5831.7 5895.9 6252.3 6301.8 6307.1 6309.5 6311.5 6072.6 6113.0 6131.2 6188.1 6219.1 6228.4 6263.4 6267.2 6276.7 6283.7 6156.3 6214.7 6223.3 6233.0 6249.0 6090.0 6152.7 6158.1 6241.8 6272.4 4905.6 4919.0 4919.7 4951.4 4953.1 4378.0 4436.6 4464.9 4471.1 4530.9 5674.7 5678.3 5678.8 5680.3 5704.3 6236.9 6283.1 6288.7 6289.7 6289.7 5523.0 5611.0 5625.4 5687.9 5707.1 5377.6 5383.5 5396.6 5404.7 5406.2 6308.8 6325.7 6349.6 6358.3 6361.9 6093.9 6156.3 6245.3 6278.0 6278.7 6301.1 6307.1 6320.3 6348.7 6354.1 6055.8 6187.3 6202.3 6209.6 6213.8 6175.2 6238.3 6285.5 6291.4 6303.3 6119.7 6121.8 6169.7 6175.6 6178.6 5935.6 6053.1 6073.6 6121.8 6126.9 5884.0 5888.4 5898.3 5905.4 5937.0 4776.9 6078.7 6094.3 6098.1 6120.2 5824.0 5882.9 5894.8 5896.3 5908.4 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | [#1] ext.html Cycles:180 Average:65745.82 Median:19057.37 stddev:145550.00 (763.7%) stddev-sans-first:145875.89 12:27:58 INFO - PROCESS | 1680 | Values: 508.5 521.3 524.4 541.2 583.1 25112.5 31388.4 31445.8 31470.5 38591.0 34317.0 43194.7 43299.6 43320.7 43436.0 7930.9 8573.8 9587.3 9619.7 9629.4 8196.2 8657.5 9562.5 9693.8 11285.7 11623.0 11749.1 11755.1 11760.2 11771.3 11598.1 11713.6 11720.1 11723.4 11727.8 8669.0 11533.5 11832.0 11897.3 11899.6 39093.6 39778.4 39810.8 39827.2 39827.8 3273.3 3367.7 3746.5 3813.5 3916.0 29286.0 29350.4 29487.9 29747.7 30227.5 2713.5 2715.5 2735.5 2833.6 2876.5 504887.0 505502.0 507588.0 508214.0 508218.0 35954.3 61957.0 62173.0 62628.4 62759.4 36024.6 36030.5 36152.5 36155.5 36179.8 14858.3 15053.5 15055.5 15065.8 15077.6 11625.9 12801.9 12858.1 12859.9 12889.8 7652.9 8105.8 8268.5 8322.6 8356.2 3331.5 3412.8 3414.6 3416.4 3442.7 959.4 990.4 1007.0 1040.2 1075.9 26486.8 28699.6 29371.8 29634.7 29833.9 1009.3 1087.7 1097.9 1098.0 1169.2 14885.7 14957.8 15087.8 15208.7 15336.4 652622.0 654165.0 662141.0 663408.0 664112.0 61863.7 62676.0 62787.4 62941.7 63016.0 1343.7 1374.7 1381.2 1510.8 1592.8 28803.6 29036.1 29271.9 29620.7 29665.0 48594.8 49109.3 49588.5 49591.0 49643.0 438024.0 441308.0 442222.0 442303.0 443815.0 23771.5 26175.9 26438.6 27112.7 27478.3 43003.8 43387.8 43510.1 44319.4 44556.6 34177.3 35388.8 35802.0 36081.5 36335.9 34672.0 36486.3 36543.8 36741.8 37357.4 37207.3 37594.9 38527.2 39403.8 39405.2 30470.8 32209.5 32251.4 32264.7 32379.8 20579.0 23740.5 23857.6 24007.4 24748.3 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | [#2] jquery.html Cycles:180 Average:34996.47 Median:25644.92 stddev:99502.94 (388.0%) stddev-sans-first:99748.17 12:27:58 INFO - PROCESS | 1680 | Values: 399.9 400.9 401.7 402.2 412.6 14917.6 20292.4 20887.4 21029.8 21048.4 15830.8 17881.7 20069.5 20271.1 20755.1 5847.3 6037.3 6069.5 6070.5 6075.2 5869.9 5898.8 5947.4 5951.7 6001.3 5769.2 5941.7 5976.4 5981.1 5982.4 5850.6 5936.9 5955.8 5966.7 5969.6 5030.6 5187.2 5209.2 5211.6 5217.8 12661.4 13397.3 24197.0 25621.4 25668.5 1910.5 2023.8 2034.8 2111.1 2277.1 9358.9 9775.0 9966.6 10209.0 10349.7 1643.7 1678.2 1696.6 1720.3 1806.3 120611.0 125491.0 127159.4 131162.3 132860.0 9280.8 9469.5 11475.5 11838.9 11857.6 14858.3 14932.6 14938.9 14963.9 14996.9 7529.2 7568.4 8108.8 8700.2 8722.7 8400.8 8482.5 8515.7 8606.8 8619.4 4474.4 4545.5 4561.1 4603.9 4604.8 1705.7 1820.4 1834.2 1851.7 1988.8 903.7 906.2 909.2 989.5 1002.6 14693.9 18764.8 19805.8 19810.7 20141.4 945.7 954.9 965.2 1005.0 1045.0 8477.0 10432.4 10435.7 10441.4 10447.3 580136.0 582614.0 594945.0 601896.0 604297.0 128380.0 129482.0 132543.0 135371.0 136180.0 929.5 994.1 1010.7 1079.4 1082.2 14311.2 16810.9 17264.0 17270.7 17502.3 53510.2 53528.0 53816.7 54179.7 55056.0 78222.6 81968.2 82540.6 83043.6 88694.6 8044.8 8135.9 8245.8 8248.9 8273.8 8071.7 10705.9 12197.9 12373.8 12666.2 10725.3 10985.9 11055.8 11311.5 11418.0 10737.9 10866.5 10915.6 11113.3 11128.8 10963.9 11296.1 11324.4 11358.3 11640.7 7981.4 8109.0 8121.4 8344.8 8485.9 8264.3 9959.5 10989.3 11100.6 11259.5 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | [#3] mootools.html Cycles:180 Average:5653.81 Median:4916.79 stddev:3151.42 (64.1%) stddev-sans-first:3143.12 12:27:58 INFO - PROCESS | 1680 | Values: 1280.3 1603.0 1603.3 1882.8 2001.3 7816.2 8506.2 8769.2 8770.3 8828.9 8418.1 8513.6 8594.4 8602.0 8617.7 2154.7 2429.9 2458.4 2465.7 2472.1 2081.7 2112.7 2413.3 2509.5 2514.1 2425.2 2455.5 2463.8 2464.4 2474.3 2150.7 2362.7 2450.6 2457.2 2467.3 2465.3 2482.3 2489.1 2492.1 2496.0 8485.3 8552.6 8559.3 8564.6 8565.6 5075.6 5269.9 5288.3 5302.2 5310.5 3446.2 3500.5 3605.5 3616.9 3621.1 1019.9 1028.8 1029.2 1038.1 1044.3 11434.3 11524.3 11525.8 11537.3 11553.5 4075.4 4123.8 4485.0 4649.9 4867.3 9783.7 9808.6 9835.7 9844.8 9869.0 5333.0 5348.9 5355.1 5375.8 5380.7 4024.8 4331.6 4896.0 4908.0 4925.6 3941.7 3970.5 4070.6 4075.0 4081.6 1174.0 1306.3 1320.9 1322.6 1347.1 778.9 797.9 867.5 950.7 978.1 5638.2 5895.7 5947.0 6024.9 6123.7 448.6 469.5 473.3 474.2 476.4 3902.5 4021.8 4071.2 4076.2 4084.5 10113.9 10182.7 10612.1 10638.6 10639.6 10511.2 10534.3 10615.9 10648.0 10648.6 7685.3 8049.0 8056.9 8200.5 8201.2 5823.7 5928.0 5970.1 6005.0 6041.5 9553.2 9829.9 9838.8 9840.4 9889.2 9575.8 9733.8 9829.7 10073.2 10076.6 6852.2 7096.5 7277.9 7440.0 7556.1 8266.9 8387.8 8400.3 8404.9 8411.5 7173.6 7366.6 7384.7 7498.4 7544.1 7262.5 7264.5 7299.1 7379.0 7402.5 6969.5 7077.5 7120.1 7127.5 7138.5 4725.7 4845.1 4895.0 5073.5 5253.8 4459.8 4901.3 5038.8 5081.8 5112.5 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | [#4] prototype.html Cycles:180 Average:5111.46 Median:5788.96 stddev:1593.08 (27.5%) stddev-sans-first:1555.68 12:27:58 INFO - PROCESS | 1680 | Values: 276.8 319.8 322.6 331.8 335.7 4932.8 5000.9 5939.9 5964.0 6059.5 5397.9 5766.7 5955.3 5989.8 5994.3 4278.7 4367.7 4461.4 4544.4 4547.2 4455.6 4503.4 4521.4 4530.3 4540.8 4494.5 4497.5 4521.3 4536.5 4561.7 4059.3 4169.0 4244.7 4547.4 4568.5 3835.8 3886.9 3905.8 3915.9 3929.0 5258.0 5784.6 5793.3 5795.4 5813.9 4791.4 4871.5 5128.8 5129.8 5144.9 5851.2 6332.5 6346.5 6347.2 6356.7 3980.8 4222.6 4228.8 4236.9 4245.4 5586.8 6558.2 6664.0 6721.9 6802.3 3749.3 4205.2 4301.8 4303.3 4307.6 3738.8 4076.0 4351.1 4364.0 4385.3 5096.1 5100.8 5110.3 5111.7 5164.7 6402.4 6404.4 6408.8 6414.9 6422.6 1466.9 1479.3 1497.6 1498.0 1500.8 911.7 940.1 964.8 971.7 988.9 4452.2 5547.2 5880.2 5899.8 6055.5 6355.8 6540.0 6586.8 6587.3 6609.9 5728.5 5877.1 5946.9 5958.6 5983.2 3458.5 3466.9 3467.8 3494.8 3495.2 6036.8 6470.2 6584.3 6846.3 6903.5 4952.8 6078.1 6516.8 6745.5 6788.6 5671.9 6500.9 6792.7 6858.0 6883.2 6470.1 6528.3 6529.2 6563.2 6574.3 6122.2 6466.2 6484.5 6489.9 6569.4 5925.0 6584.2 6639.7 6741.0 6767.1 4255.3 4270.7 4283.5 4283.9 4295.5 5345.6 5933.0 6151.9 6464.6 6490.9 5240.6 5529.9 5636.4 6358.6 6370.7 5075.7 6002.8 6178.2 6335.1 6406.4 5235.5 5732.0 6097.4 6176.9 6213.6 5166.4 6445.0 6495.9 6500.7 6536.2 5126.2 6259.9 6322.9 6351.0 6373.3 12:27:58 INFO - PROCESS | 1680 | 12:27:58 INFO - PROCESS | 1680 | [#5] yui.html Cycles:180 Average:17406.63 Median:61355.04 stddev:24601.29 (40.1%) stddev-sans-first:24638.16 12:27:58 INFO - PROCESS | 1680 | Values: 659.4 732.3 760.4 761.4 785.2 14911.3 14922.0 15074.2 15080.4 15101.7 11821.4 13691.7 13754.9 13756.4 13763.7 729.1 890.6 928.8 930.3 934.6 894.0 896.1 898.3 898.4 899.8 963.1 973.6 979.1 981.0 981.2 958.9 970.4 971.3 971.8 972.9 556.5 570.1 571.1 572.8 724.3 14497.5 14896.3 14905.9 14912.4 14917.9 12417.3 12522.9 12640.1 12656.4 12928.9 7854.6 9639.6 9652.9 9729.7 9938.2 3435.6 3480.6 3511.3 3536.2 3731.8 80060.0 80194.6 80864.0 81040.2 82621.0 9486.6 9651.5 9664.5 9740.8 9966.2 18207.8 22068.0 22134.0 22136.7 22149.9 7477.2 7595.5 7617.0 7643.8 7700.7 4061.2 4108.4 4152.5 4172.0 4310.4 2920.3 3061.2 3157.4 3225.4 3226.6 534.9 648.1 652.1 666.8 668.8 519.4 527.5 547.6 550.9 554.3 6438.4 6658.2 6681.3 6775.1 7797.4 534.4 544.7 571.0 601.9 603.1 3286.9 3763.9 3788.4 3857.5 3871.5 84024.2 84283.6 85667.6 88873.0 89366.1 76060.2 76123.0 76125.6 76860.0 77321.6 67863.0 68424.3 68574.0 68690.0 69133.7 7365.8 7390.3 7437.6 7672.8 7836.2 42711.8 43616.0 43688.3 44448.0 44512.8 60070.4 60468.7 61056.7 61653.4 61983.7 9791.5 10149.6 10287.9 10501.3 10663.5 10888.7 10894.3 11032.8 11238.7 11494.7 10454.3 10509.3 10692.0 10781.8 10811.0 8659.8 10136.5 10583.7 10588.0 11165.8 10047.2 10064.6 10248.2 10252.2 10283.9 8152.4 8354.0 8774.4 9039.0 9060.3 8092.5 8598.2 8799.1 8846.0 8894.5 12:27:58 INFO - PROCESS | 1680 | -------- Summary: end -------- 12:27:58 INFO - PROCESS | 1680 | 12:27:59 INFO - TEST-INFO | 1680: exit 0 12:27:59 INFO - TEST-OK | dromaeo_css | took 1115865ms 12:27:59 INFO - TEST-START | kraken 12:27:59 INFO - operating with platform_type : mac_ 12:27:59 INFO - Initialising browser for kraken test... 12:27:59 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp9cjP5B/profile http://localhost:49275/getInfo.html) 12:28:01 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 12:28:01 INFO - PROCESS | 1684 | colorDepth:24 12:28:01 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 12:28:01 INFO - PROCESS | 1684 | __metrics 12:28:01 INFO - PROCESS | 1684 | 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:28:01 ERROR - PROCESS | 1684 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:28:02 INFO - TEST-INFO | 1684: exit 0 12:28:02 INFO - Browser initialized. 12:28:02 INFO - Running cycle 1/1 for kraken test... 12:28:02 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp9cjP5B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:14 INFO - PROCESS | 1686 | RSS: Main: 239095808 12:28:14 INFO - PROCESS | 1686 | 12:28:16 INFO - PROCESS | 1686 | 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:28:17 INFO - PROCESS | 1686 | RSS: Main: 286154752 12:28:17 INFO - PROCESS | 1686 | 12:28:20 INFO - PROCESS | 1686 | 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:28:21 INFO - PROCESS | 1686 | RSS: Main: 299700224 12:28:21 INFO - PROCESS | 1686 | 12:28:24 INFO - PROCESS | 1686 | 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:28:25 INFO - PROCESS | 1686 | RSS: Main: 385589248 12:28:25 INFO - PROCESS | 1686 | 12:28:27 INFO - PROCESS | 1686 | 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:28:27 INFO - PROCESS | 1686 | RSS: Main: 287227904 12:28:27 INFO - PROCESS | 1686 | 12:28:29 INFO - PROCESS | 1686 | 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:28:30 INFO - PROCESS | 1686 | RSS: Main: 290889728 12:28:30 INFO - PROCESS | 1686 | 12:28:33 INFO - PROCESS | 1686 | 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:28:33 INFO - PROCESS | 1686 | RSS: Main: 330096640 12:28:33 INFO - PROCESS | 1686 | 12:28:36 INFO - PROCESS | 1686 | 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:28:37 INFO - PROCESS | 1686 | RSS: Main: 328708096 12:28:37 INFO - PROCESS | 1686 | 12:28:39 INFO - PROCESS | 1686 | 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:28:40 INFO - PROCESS | 1686 | RSS: Main: 330096640 12:28:40 INFO - PROCESS | 1686 | 12:28:42 INFO - PROCESS | 1686 | 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:28:42 INFO - PROCESS | 1686 | RSS: Main: 321179648 12:28:42 INFO - PROCESS | 1686 | 12:28:44 INFO - PROCESS | 1686 | 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:28:45 INFO - PROCESS | 1686 | RSS: Main: 327958528 12:28:45 INFO - PROCESS | 1686 | 12:28:47 INFO - PROCESS | 1686 | 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:28:48 INFO - PROCESS | 1686 | RSS: Main: 330158080 12:28:48 INFO - PROCESS | 1686 | 12:28:50 INFO - PROCESS | 1686 | 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:28:51 INFO - PROCESS | 1686 | RSS: Main: 334557184 12:28:51 INFO - PROCESS | 1686 | 12:28:54 INFO - PROCESS | 1686 | 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:28:55 INFO - PROCESS | 1686 | RSS: Main: 332050432 12:28:55 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | 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:28:57 INFO - PROCESS | 1686 | RSS: Main: 396382208 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | __start_tp_report 12:28:57 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 12:28:57 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 12:28:57 INFO - PROCESS | 1686 | |i|pagename|runs| 12:28:57 INFO - PROCESS | 1686 | |0;ai-astar;112;113;110;111;113;112;112;112;106;114 12:28:57 INFO - PROCESS | 1686 | |1;audio-beat-detection;157;123;144;171;120;142;130;137;203;113 12:28:57 INFO - PROCESS | 1686 | |2;audio-dft;183;185;187;182;185;185;182;183;172;183 12:28:57 INFO - PROCESS | 1686 | |3;audio-fft;76;73;78;145;55;54;63;64;132;55 12:28:57 INFO - PROCESS | 1686 | |4;audio-oscillator;92;89;137;76;80;84;127;79;80;122 12:28:57 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;88;84;87;84;87;84;87;85;86;85 12:28:57 INFO - PROCESS | 1686 | |6;imaging-darkroom;92;92;93;94;96;92;93;93;94;92 12:28:57 INFO - PROCESS | 1686 | |7;imaging-desaturate;85;86;84;86;85;84;84;85;80;83 12:28:57 INFO - PROCESS | 1686 | |8;json-parse-financial;60;63;60;58;58;58;61;80;58;57 12:28:57 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;61;64;61;72;70;73;69;65;66;67 12:28:57 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;83;86;81;81;82;81;83;82;81;83 12:28:57 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;142;142;152;152;154;135;135;142;179;141 12:28:57 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;182;171;170;170;169;169;219;228;179;168 12:28:57 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;69;68;69;70;69;69;71;92;96;97 12:28:57 INFO - PROCESS | 1686 | __end_tp_report 12:28:57 INFO - PROCESS | 1686 | __start_cc_report 12:28:57 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,1993 12:28:57 INFO - PROCESS | 1686 | __end_cc_report 12:28:57 INFO - PROCESS | 1686 | __startTimestamp1461526137260__endTimestamp 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | ------- Summary: start ------- 12:28:57 INFO - PROCESS | 1686 | Number of tests: 14 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:2.22 (2.0%) stddev-sans-first:2.35 12:28:57 INFO - PROCESS | 1686 | Values: 112.0 113.0 110.0 111.0 113.0 112.0 112.0 112.0 106.0 114.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:143.00 stddev:27.09 (18.9%) stddev-sans-first:28.32 12:28:57 INFO - PROCESS | 1686 | Values: 157.0 123.0 144.0 171.0 120.0 142.0 130.0 137.0 203.0 113.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:182.70 Median:184.00 stddev:4.08 (2.2%) stddev-sans-first:4.33 12:28:57 INFO - PROCESS | 1686 | Values: 183.0 185.0 187.0 182.0 185.0 185.0 182.0 183.0 172.0 183.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:79.50 Median:63.50 stddev:32.43 (51.1%) stddev-sans-first:34.37 12:28:57 INFO - PROCESS | 1686 | Values: 76.0 73.0 78.0 145.0 55.0 54.0 63.0 64.0 132.0 55.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:80.00 stddev:22.91 (28.6%) stddev-sans-first:24.24 12:28:57 INFO - PROCESS | 1686 | Values: 92.0 89.0 137.0 76.0 80.0 84.0 127.0 79.0 80.0 122.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.49 (1.7%) stddev-sans-first:1.33 12:28:57 INFO - PROCESS | 1686 | Values: 88.0 84.0 87.0 84.0 87.0 84.0 87.0 85.0 86.0 85.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.29 (1.4%) stddev-sans-first:1.30 12:28:57 INFO - PROCESS | 1686 | Values: 92.0 92.0 93.0 94.0 96.0 92.0 93.0 93.0 94.0 92.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:85.00 stddev:1.75 (2.1%) stddev-sans-first:1.83 12:28:57 INFO - PROCESS | 1686 | Values: 85.0 86.0 84.0 86.0 85.0 84.0 84.0 85.0 80.0 83.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:60.00 stddev:6.82 (11.4%) stddev-sans-first:7.21 12:28:57 INFO - PROCESS | 1686 | Values: 60.0 63.0 60.0 58.0 58.0 58.0 61.0 80.0 58.0 57.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:68.00 stddev:4.21 (6.2%) stddev-sans-first:3.91 12:28:57 INFO - PROCESS | 1686 | Values: 61.0 64.0 61.0 72.0 70.0 73.0 69.0 65.0 66.0 67.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:1.57 (1.9%) stddev-sans-first:1.64 12:28:57 INFO - PROCESS | 1686 | Values: 83.0 86.0 81.0 81.0 82.0 81.0 83.0 82.0 81.0 83.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:147.00 stddev:13.00 (8.8%) stddev-sans-first:13.64 12:28:57 INFO - PROCESS | 1686 | Values: 142.0 142.0 152.0 152.0 154.0 135.0 135.0 142.0 179.0 141.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.50 Median:175.00 stddev:22.20 (12.7%) stddev-sans-first:23.54 12:28:57 INFO - PROCESS | 1686 | Values: 182.0 171.0 170.0 170.0 169.0 169.0 219.0 228.0 179.0 168.0 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:70.50 stddev:12.51 (17.7%) stddev-sans-first:12.93 12:28:57 INFO - PROCESS | 1686 | Values: 69.0 68.0 69.0 70.0 69.0 69.0 71.0 92.0 96.0 97.0 12:28:57 INFO - PROCESS | 1686 | -------- Summary: end -------- 12:28:57 INFO - PROCESS | 1686 | 12:28:57 INFO - TEST-INFO | 1686: exit 0 12:28:58 INFO - TEST-OK | kraken | took 58710ms 12:28:58 INFO - SUITE-END | took 1174s 12:28:58 INFO - Completed test suite (00:19:34) 12:28:58 INFO - javascript benchmark 12:28:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2867.6845546989825, 3472.305538892221, 3473.7568370214094, 3500.352434799561, 3533.929321413572, 5645.379008309083, 5714.371419143891, 5722.685252311121, 5741.397153298901, 5745.368009518955, 5667.518260947814, 5737.225474560933, 5746.25298711168, 5749.942500574993, 5759.10733836255, 5183.0, 5203.427622961472, 5230.110881150214, 5241.42344342122, 5294.258803767476, 5303.098473259555, 5304.177852432879, 5306.522412982831, 5311.7344132793205, 5311.893762124755, 5220.817271395502, 5268.973655131738, 5293.497117773805, 5302.0, 5358.866028349303, 5175.974120129394, 5200.713960732158, 5205.505476979681, 5206.114960456694, 5251.894962100775, 5128.640995130342, 5130.281760553526, 5149.588032957355, 5155.9742201288755, 5160.587153027749, 5553.972230138824, 5571.0, 5602.971985140089, 5611.0, 5637.7181140942785, 6197.473214776749, 6231.563790534665, 6243.406876346739, 6243.937560624427, 6251.156093927307, 4987.426445958725, 6159.87680246397, 6200.689965501752, 6269.843253918689, 6292.056191571301, 4827.468978412372, 5796.275465566804, 5822.301323841166, 5831.679257640785, 5895.941040589625, 6252.296812768778, 6301.842453938688, 6307.148534947723, 6309.463695585835, 6311.495080393649, 6072.635641861502, 6113.0, 6131.233595800525, 6188.133661287423, 6219.129321894939, 6228.377162283826, 6263.404976527314, 6267.1539342188225, 6276.717547710364, 6283.6858157091965, 6156.26124865019, 6214.658193799294, 6223.315435302113, 6232.968835155795, 6248.968755156195, 6090.0, 6152.723127459275, 6158.107074474176, 6241.78153764616, 6272.372762723691, 4905.632077594123, 4919.0, 4919.704817710949, 4951.430585482643, 4953.108440480748, 4378.014946637045, 4436.578525040116, 4464.910701785946, 4471.06107717383, 4530.932036019462, 5674.716264186774, 5678.290213723285, 5678.829635110953, 5680.318361796528, 5704.258446401941, 6236.906446403307, 6283.08895210192, 6288.685565721696, 6289.685515724195, 6289.7484100635465, 5522.972385138129, 5611.0, 5625.437456254342, 5687.914681279784, 5707.143928410772, 5377.623566350318, 5383.515483606494, 5396.568274538027, 5404.675719456845, 5406.243125962368, 6308.842278943063, 6325.683715814191, 6349.587276826992, 6358.300586935341, 6361.904571431525, 6093.897179118431, 6156.29202641702, 6245.28179259391, 6278.0269058296035, 6278.686065696788, 6301.0863424804065, 6307.085472606354, 6320.336364681826, 6348.71430785625, 6354.1104245406605, 6055.7577696893395, 6187.288461826767, 6202.34875338083, 6209.565330426827, 6213.782517611862, 6175.166352542529, 6238.251409830849, 6285.49716022708, 6291.370862913672, 6303.275123360872, 6119.694015299307, 6121.8469538260115, 6169.660668663267, 6175.567710260419, 6178.567500275118, 5935.614185077956, 6053.092036194606, 6073.5748497604745, 6121.84695382619, 6126.938730612636, 5884.0, 5888.440598143169, 5898.262717160355, 5905.35041145465, 5937.020391635332, 4776.8566942991765, 6078.665673388006, 6094.300975544442, 6098.146259523759, 6120.234970628671, 5823.970880145742, 5882.941170588239, 5894.823155305347, 5896.262967129109, 5908.3500814911185], "value": 5688.671078666222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.45341258147477, 521.2962500624157, 524.3655177235545, 541.247665744615, 583.0991118721574, 25112.49775004499, 31388.372232555335, 31445.842770786116, 31470.527942080887, 38591.0, 34317.0, 43194.7041588752, 43299.56700432995, 43320.7003789886, 43436.0, 7930.9293245412, 8573.842531258279, 9587.328886977913, 9619.711408657717, 9629.422234665943, 8196.221358970888, 8657.480551166918, 9562.474063926482, 9693.806123877519, 11285.717857053558, 11623.0, 11749.118816088827, 11755.118366122531, 11760.235584686969, 11771.293722376684, 11598.072154227684, 11713.648590542254, 11720.120990925714, 11723.355215463145, 11727.765444691058, 8669.00306464752, 11533.538658453652, 11831.99428048617, 11897.263629365209, 11899.643010709693, 39093.60906390928, 39778.403323950166, 39810.800945995376, 39827.20345593072, 39827.80086099551, 3273.3289675616443, 3367.7137443317347, 3746.4754934309217, 3813.523309586302, 3916.0, 29286.0, 29350.436303623737, 29487.852560737272, 29747.702522974927, 30227.546586801214, 2713.4844379568017, 2715.511207982562, 2735.4529094181244, 2833.6032955386263, 2876.532660320746, 504887.0, 505502.0, 507588.0, 508214.0, 508218.0, 35954.28091438157, 61957.0, 62173.0, 62628.37371626226, 62759.37240627535, 36024.639753602656, 36030.459543106874, 36152.457713134325, 36155.457668135, 36179.81910090433, 14858.2570871456, 15053.473128440452, 15055.54833355001, 15065.849341506664, 15077.623059423602, 11625.941870290595, 12801.871981280254, 12858.0999330046, 12859.935700321439, 12889.806652900214, 7652.923470765332, 8105.75682729519, 8268.462549934235, 8322.62548185333, 8356.164383561596, 3331.51693004513, 3412.77816941898, 3414.6414626463866, 3416.4021296273054, 3442.7417943653827, 959.4099628728281, 990.3513198854762, 1007.0483393193362, 1040.193849766437, 1075.8547596074586, 26486.822167573264, 28699.56950645742, 29371.825126995107, 29634.703652963624, 29833.85083074614, 1009.3388830316155, 1087.6628245243999, 1097.9404874296183, 1098.0, 1169.2107827216594, 14885.70228595422, 14957.775633365509, 15087.849121508863, 15208.695826083416, 15336.386544538316, 652622.0, 654165.0, 662141.0, 663408.0, 664112.0, 61863.6906815472, 62676.0, 62787.372126279064, 62941.68529157325, 63016.0, 1343.7111021130504, 1374.7456720506739, 1381.2196109198271, 1510.7582786754285, 1592.824789273179, 28803.56794648124, 29036.12891613255, 29271.85364073166, 29620.703792961795, 29664.961726339476, 48594.75702621464, 49109.2633610489, 49588.51234462972, 49591.0, 49643.00713985628, 438024.0, 441308.0, 442222.0, 442303.0, 443815.0, 23771.52456950886, 26175.869120654275, 26438.603420948315, 27112.72887271102, 27478.313042174104, 43003.784981074896, 43387.78306108449, 43510.12979740324, 44319.44881929117, 44556.55443445524, 34177.31645367129, 35388.82305588455, 35802.0, 36081.45877811783, 36335.90992270236, 34672.0, 36486.27027459383, 36543.81728091449, 36741.81629091944, 37357.43963840599, 37207.25585488221, 37594.87215383543, 38527.229455410175, 39403.80298098606, 39405.21189576135, 30470.84764576163, 32209.51685724669, 32251.35497290088, 32264.67735322617, 32379.838100810288, 20578.971051447068, 23740.52518949646, 23857.642135367638, 24007.399815004766, 24748.257552273462], "value": 18561.904896472694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.926013687468, 400.8737247766953, 401.67464353873356, 402.2277227722772, 412.6471866554096, 14917.552473425787, 20292.391228263157, 20887.37337879866, 21029.789702102993, 21048.36854894352, 15830.84169158308, 17881.7317740234, 20069.498262543442, 20271.08780104895, 20755.06602202901, 5847.298324201101, 6037.275526936752, 6069.544784141207, 6070.514358851281, 6075.17985072014, 5869.941300587004, 5898.8230353089475, 5947.40525947404, 5951.702414879261, 6001.309849367313, 5769.16347129666, 5941.7029148542615, 5976.402359764011, 5981.0729336952845, 5982.401759824026, 5850.561207909403, 5936.910946335765, 5955.791547295824, 5966.67183304918, 5969.611975221618, 5030.647854650176, 5187.19598462239, 5209.244659524347, 5211.557017653504, 5217.843464696065, 12661.36693165338, 13397.330133493286, 24197.0, 25621.359466013313, 25668.486630267478, 1910.4650697804636, 2023.7571491420977, 2034.7660019097846, 2111.092230340953, 2277.089164334263, 9358.906410935871, 9774.951125244328, 9966.551505182284, 10209.0, 10349.741256468573, 1643.7041332560134, 1678.202853644509, 1696.6352234269568, 1720.3290716620402, 1806.349714102922, 120611.0, 125491.0, 127159.36420317931, 131162.34418827845, 132860.0, 9280.814383712423, 9469.526523673789, 11475.483603237873, 11838.940805295919, 11857.644270671894, 14858.2570871456, 14932.62668433298, 14938.925305373403, 14963.850361496463, 14996.925015374853, 7529.171791102975, 7568.394528437711, 8108.75673729789, 8700.216980471789, 8722.694705685397, 8400.831983360298, 8482.491050536988, 8515.74452766418, 8606.827863442697, 8619.353548483889, 4474.351219073216, 4545.499995000548, 4561.064981678748, 4603.930941035887, 4604.815807367735, 1705.7100292950229, 1820.3980497162413, 1834.1779769335285, 1851.6944704123669, 1988.7613486381726, 903.6656437118309, 906.207068814787, 909.1681111782644, 989.4811464402109, 1002.6390499420203, 14693.853061469248, 18764.81235187658, 19805.801941980684, 19810.70283945742, 20141.395758126986, 945.7068308824149, 954.9092836180552, 965.2037069417759, 1005.0, 1044.9738756531146, 8476.957615211884, 10432.374057556572, 10435.68692939213, 10441.389652586853, 10447.268691191544, 580136.0, 582614.0, 594945.0, 601896.0, 604297.0, 128380.0, 129482.0, 132543.0, 135371.0, 136180.0, 929.5445231836352, 994.0904072773332, 1010.7321559786662, 1079.3577821196532, 1082.2099867097063, 14311.212883291519, 16810.915945420194, 17263.96416215031, 17270.704697148016, 17502.33139546452, 53510.1973470406, 53528.0, 53816.730916345165, 54179.72910135424, 55056.0, 78222.6088869552, 81968.18031819606, 82540.58729706313, 83043.58478207812, 88694.55652721695, 8044.798880028046, 8135.877961830459, 8245.752627421187, 8248.876266856121, 8273.793155170928, 8071.677132914618, 10705.87588303248, 12197.878021219674, 12373.814392783936, 12666.176698514568, 10725.30285531438, 10985.945070274598, 11055.778884422105, 11311.490982905954, 11418.0, 10737.946310268399, 10866.511007004861, 10915.563377464812, 11113.333200008025, 11128.777424451628, 10963.945180274048, 11296.096312294832, 11324.433778310886, 11358.26171298863, 11640.708982275512, 7981.3614910808155, 8108.9594552026865, 8121.431499795194, 8344.83310333802, 8485.915140848512, 8264.297534709616, 9959.502024898871, 10989.285696429706, 11100.5559777608, 11259.549618015188], "value": 9360.033753659061, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1280.3342262023755, 1603.0221564845447, 1603.2945503978249, 1882.7552418185635, 2001.3195513525407, 7816.218378162188, 8506.234438900505, 8769.210771030606, 8770.254528365096, 8828.8675669865, 8418.116097809718, 8513.616887240074, 8594.355423343242, 8601.95699021504, 8617.698380556682, 2154.687570302305, 2429.854208747472, 2458.4222707663685, 2465.704115506142, 2472.0606169655507, 2081.739782527184, 2112.6514125169406, 2413.263954493879, 2509.473010667762, 2514.069794176148, 2425.248173066346, 2455.4597988442515, 2463.75362463754, 2464.4331803685163, 2474.3195621204222, 2150.6989021536997, 2362.7282862470693, 2450.6324051392257, 2457.164564048232, 2467.2721547143638, 2465.3097132802845, 2482.2801387597574, 2489.0914816092177, 2492.140211626986, 2496.0265496456395, 8485.278751306147, 8552.61513231906, 8559.27246184075, 8564.571771411467, 8565.614547345385, 5075.568576670988, 5269.86825329366, 5288.312519372495, 5302.204669299597, 5310.468953104697, 3446.207372304384, 3500.527428797105, 3605.549306336708, 3616.9095772605633, 3621.112827357288, 1019.9031092046243, 1028.7685270814081, 1029.1663752360612, 1038.1176000399676, 1044.300318786415, 11434.256773309708, 11524.250923689933, 11525.827112593317, 11537.307761534335, 11553.53785848565, 4075.3683179107284, 4123.835046598102, 4484.955150448519, 4649.883752906137, 4867.294242334841, 9783.706488805346, 9808.607655693835, 9835.655752048644, 9844.803103938024, 9869.0, 5333.013392522395, 5348.893022139536, 5355.116405793001, 5375.838724838261, 5380.650257733313, 4024.778637174925, 4331.566843315643, 4895.951040489621, 4908.0, 4925.581325587294, 3941.724079314478, 3970.5235371755575, 4070.6336429720873, 4074.959250407458, 4081.6326530611796, 1174.0138283841613, 1306.3141850528548, 1320.9009324300523, 1322.5966080345586, 1347.1378317876568, 778.8598052350632, 797.8763291689797, 867.4621734524635, 950.7337945375306, 978.1245785022365, 5638.154276858422, 5895.7052147393315, 5947.04847224442, 6024.939750602525, 6123.695066196068, 448.57833636381696, 469.53516019140807, 473.3302377136382, 474.18913657645123, 476.37832628420244, 3902.5316961964745, 4021.8190181441905, 4071.185762847442, 4076.184763047343, 4084.509858816961, 10113.949430252802, 10182.694519164437, 10612.09797167234, 10638.574457021787, 10639.627613033506, 10511.211659125596, 10534.262601617813, 10615.893841061645, 10647.988441098238, 10648.574057037787, 7685.346745526581, 8048.959755201186, 8056.919430805734, 8200.507969521848, 8201.22088401601, 5823.7379317931645, 5927.970360148344, 5970.134330521963, 6005.0, 6041.546883983631, 9553.18797902186, 9829.852552211865, 9838.75403114928, 9840.3603765755, 9889.208863291064, 9575.760605984906, 9733.756656083655, 9829.655962041294, 10073.244506662177, 10076.647317343857, 6852.246252912076, 7096.467764917548, 7277.854442911218, 7440.03279573652, 7556.131044929683, 8266.875996860172, 8387.83224335522, 8400.327973762207, 8404.87392689098, 8411.495310281403, 7173.605451700207, 7366.63166841645, 7384.741534046282, 7498.437617178624, 7544.132424771222, 7262.527935684164, 7264.527805692613, 7299.124105107421, 7379.0, 7402.481826272325, 6969.512134150764, 7077.504574679723, 7120.074390329224, 7127.501074924706, 7138.46461515378, 4725.740084295397, 4845.055214233191, 4894.95105048945, 5073.543381095571, 5253.789848406021, 4459.821607135678, 4901.264810278487, 5038.798448062036, 5081.796728130981, 5112.514311140436], "value": 4489.1490931571025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.83528300661106, 319.80811513092146, 322.63219929280626, 331.7793667211305, 335.6660123177439, 4932.80268789248, 5000.924986125202, 5939.88120237595, 5964.0, 6059.454649081582, 5397.866448045915, 5766.654000759957, 5955.255593050869, 5989.777207069017, 5994.310654274749, 4278.716385084478, 4367.672424568154, 4461.416197249978, 4544.363789069516, 4547.204239258133, 4455.554444555551, 4503.414556107701, 4521.389612402317, 4530.343100250462, 4540.818367265306, 4494.483134439549, 4497.527759585246, 4521.344405061281, 4536.478304994935, 4561.657875659323, 4059.269331520325, 4169.0, 4244.734042287481, 4547.408836851221, 4568.451785785727, 3835.789031603261, 3886.9222615547815, 3905.8047097645003, 3915.8629447969183, 3929.017745563609, 5258.027264955957, 5784.595078344518, 5793.333766616851, 5795.391483894173, 5813.883722325572, 4791.401074865642, 4871.512848715136, 5128.769205385766, 5129.794808207667, 5144.897102057976, 5851.239338885917, 6332.461740752042, 6346.460550853137, 6347.174867267226, 6356.71394787231, 3980.8208630611693, 4222.5566315536735, 4228.809703563347, 4236.894077648052, 4245.426867372896, 5586.776528938878, 6558.2458017328145, 6663.966680166616, 6721.865562688768, 6802.251752307242, 3749.2688925659236, 4205.201011807747, 4301.763403012865, 4303.289957157097, 4307.590778876001, 3738.831752571141, 4076.0, 4351.12977404514, 4364.0, 4385.2897370025075, 5096.0827051131155, 5100.795968161232, 5110.3426218366985, 5111.667741596784, 5164.664296820694, 6402.423781859655, 6404.423601875854, 6408.814369341687, 6414.8717025659225, 6422.6467544285515, 1466.8606482384157, 1479.2825479642365, 1497.5507347795617, 1498.0038274547303, 1500.761871456053, 911.6535716427715, 940.1397721085131, 964.768455570658, 971.7424882406168, 988.9208863290919, 4452.198604251201, 5547.195656629847, 5880.176775251468, 5899.764009439575, 6055.515558755289, 6355.809325720235, 6539.967300163468, 6586.80239592813, 6587.275399706028, 6609.876321025437, 5728.51307638847, 5877.089051197071, 5946.940530594638, 5958.553108516879, 5983.19226904371, 3458.472358194678, 3466.9317653875996, 3467.8439470223902, 3494.790312581253, 3495.1961048958374, 6036.8188954331445, 6470.223573171155, 6584.275729669733, 6846.281140480178, 6903.5167538271835, 4952.761809547613, 6078.1186727924205, 6516.771912983023, 6745.494087943423, 6788.626625535643, 5671.8865622688145, 6500.869982600321, 6792.72829086831, 6858.0, 6883.242843287235, 6470.126532917996, 6528.347165283433, 6529.216494020748, 6563.245226798788, 6574.276829548841, 6122.173641474079, 6466.224053113657, 6484.5460817742305, 6489.8702025960165, 6569.37590928861, 5924.9703751480965, 6584.242812076673, 6639.6680165992475, 6741.0, 6767.120274364498, 4255.255330317244, 4270.658347332268, 4283.464566929134, 4283.914321713486, 4295.463067116611, 5345.572354211576, 5933.0, 6151.938480615316, 6464.644444555594, 6490.902636460552, 5240.633155679066, 5529.889402212014, 5636.408177141341, 6358.586691865014, 6370.6814659267775, 5075.670081444694, 6002.8199154023705, 6178.165947597017, 6335.1447554579545, 6406.391392817675, 5235.528802407802, 5731.996900542305, 6097.359777223328, 6176.934913370993, 6213.565050446426, 5166.3800343958965, 6445.0, 6495.935040649533, 6500.707468164039, 6536.248331441755, 5126.179811230185, 6259.906101408392, 6322.873542529215, 6351.0, 6373.298937117006], "value": 4593.174695302185, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.4295934017075, 732.2531018361271, 760.4182800157879, 761.4403413491086, 785.2304741353473, 14911.32899019544, 14921.955463117587, 15074.170920599363, 15080.396784128636, 15101.697966040672, 11821.406654592796, 13691.657708557292, 13754.931225343884, 13756.449742010333, 13763.65590860229, 729.1068441159564, 890.5547226386807, 928.7863791327999, 930.3115694386173, 934.5560858592182, 893.9955300223489, 896.090468174803, 898.2813749000806, 898.371140201858, 899.8470260055774, 963.0610155098793, 973.5910917414703, 979.0894468144622, 980.9803803923917, 981.1807141037207, 958.8801399825022, 970.4032020307495, 971.2812518736155, 971.8493633486821, 972.8929817720045, 556.476911703002, 570.1191658887029, 571.1090698510295, 572.7565784541553, 724.3480867219492, 14497.492587759483, 14896.255187240595, 14905.850941490555, 14912.403503859832, 14917.925410372987, 12417.254964702146, 12522.937385313106, 12640.05199610033, 12656.430460629294, 12928.935355323163, 7854.607269636553, 9639.614415423375, 9652.90347096534, 9729.70810875675, 9938.204943604494, 3435.587729472454, 3480.564929383827, 3511.262634846685, 3536.1513236822975, 3731.8320675569666, 80060.0, 80194.59902700449, 80864.0, 81040.18959810444, 82621.0, 9486.620535178654, 9651.469169195621, 9664.468454235084, 9740.756481087888, 9966.20270378368, 18207.81792182088, 22068.0, 22134.0, 22136.66794998076, 22149.889250553642, 7477.1775104738435, 7595.506292090997, 7616.961915190499, 7643.770686879402, 7700.730474433368, 4061.1877624475223, 4108.424820525129, 4152.460180176618, 4171.958280417218, 4310.396544483775, 2920.28453029005, 3061.219389055821, 3157.447446696865, 3225.4033003894356, 3226.596675415573, 534.8636097795115, 648.1379764912655, 652.1424327009967, 666.8132922781629, 668.762589280808, 519.3767479025184, 527.454085022008, 547.5564792518073, 550.9146082357241, 554.260062816137, 6438.420542151135, 6658.234303055114, 6681.265060843303, 6775.119234499483, 7797.376209903194, 534.3854567247696, 544.6595877576515, 571.0, 601.8585632376388, 603.0953569645532, 3286.917827054295, 3763.9435408468894, 3788.431735239736, 3857.459955606161, 3871.457995880579, 84024.15975840285, 84283.57858210792, 85667.57166214129, 88873.0, 89366.10633893577, 76060.23939760642, 76123.0, 76125.61937190278, 76860.0, 77321.61339193268, 67862.98205526921, 68424.31575684279, 68573.97139042919, 68690.0, 69133.70395552466, 7365.767407766917, 7390.334869861738, 7437.553746775211, 7672.769816905502, 7836.177201393772, 42711.78644106884, 43616.0, 43688.344674830536, 44448.0, 44512.777436112614, 60070.39929600648, 60468.69765651144, 61056.69471652613, 61653.38346616476, 61983.690081549306, 9791.461469618955, 10149.644762433574, 10287.897121028695, 10501.31741436804, 10663.46682665879, 10888.727781805519, 10894.291871028674, 11032.779344412906, 11238.66284011481, 11494.712632184262, 10454.320469169785, 10509.31689440184, 10691.984261495145, 10781.838272426077, 10810.972957569018, 8659.826803464022, 10136.493175341056, 10583.735406614896, 10588.047075763217, 11165.827588103131, 10047.246456515937, 10064.597416103567, 10248.180145588483, 10252.231082668979, 10283.94858025705, 8152.429329946846, 8354.0, 8774.429662071945, 9038.954805225932, 9060.320475964198, 8092.514449133071, 8598.226159645661, 8799.12008799115, 8845.955770221108, 8894.4663320201], "value": 6588.021722636214, "unit": "score"}], "name": "dromaeo_css", "value": 7155.890176704353, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 110.0, 111.0, 113.0, 112.0, 112.0, 112.0, 106.0, 114.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 144.0, 171.0, 120.0, 142.0, 130.0, 137.0, 203.0, 113.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 187.0, 182.0, 185.0, 185.0, 182.0, 183.0, 172.0, 183.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 78.0, 145.0, 55.0, 54.0, 63.0, 64.0, 132.0, 55.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 137.0, 76.0, 80.0, 84.0, 127.0, 79.0, 80.0, 122.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 85.0, 86.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 94.0, 96.0, 92.0, 93.0, 93.0, 94.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 86.0, 85.0, 84.0, 84.0, 85.0, 80.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 60.0, 58.0, 58.0, 58.0, 61.0, 80.0, 58.0, 57.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 61.0, 72.0, 70.0, 73.0, 69.0, 65.0, 66.0, 67.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 81.0, 81.0, 82.0, 81.0, 83.0, 82.0, 81.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 152.0, 152.0, 154.0, 135.0, 135.0, 142.0, 179.0, 141.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 171.0, 170.0, 170.0, 169.0, 169.0, 219.0, 228.0, 179.0, 168.0], "value": 182.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 70.0, 69.0, 69.0, 71.0, 92.0, 96.0, 97.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1494.6000000000001, "alertThreshold": 2.0}]} 12:28:58 INFO - Return code: 0 12:28:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:28:58 ERROR - # TBPL SUCCESS # 12:28:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:28:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:28:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:28:58 INFO - 2016-04-24 12:28:58.513 screenresolution[1690:12616] starting screenresolution argv=screenresolution get 12:28:58 INFO - 2016-04-24 12:28:58.516 screenresolution[1690:12616] Display 0: 1600x1200x32@60 12:28:58 INFO - 2016-04-24 12:28:58.522 screenresolution[1691:12619] starting screenresolution argv=screenresolution list 12:28:58 INFO - Available Modes on Display 0 12:28:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:28:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:28:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:28:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:28:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:28:58 INFO - Intel Iris: 12:28:58 INFO - Chipset Model: Intel Iris 12:28:58 INFO - Type: GPU 12:28:58 INFO - Bus: Built-In 12:28:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:28:58 INFO - Vendor: Intel (0x8086) 12:28:58 INFO - Device ID: 0x0a2e 12:28:58 INFO - Revision ID: 0x0009 12:28:58 INFO - Displays: 12:28:58 INFO - Display: 12:28:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:28:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:28:58 INFO - Display Serial Number: 1600x1200 60 12:28:58 INFO - Main Display: Yes 12:28:58 INFO - Mirror: Off 12:28:58 INFO - Online: Yes 12:28:58 INFO - Rotation: Supported 12:28:58 INFO - Return code: 0 12:28:58 INFO - Running post-action listener: _resource_record_post_action 12:28:58 INFO - Running post-run listener: _resource_record_post_run 12:28:59 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 32345600; Write bytes: 698162176; Read time: 319; Write time: 2068 12:28:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378225152; Write bytes: 373501440; Read time: 28202; Write time: 835 12:28:59 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31424000; Write bytes: 299412992; Read time: 305; Write time: 1184 12:28:59 INFO - Running post-run listener: _upload_blobber_files 12:28:59 INFO - Blob upload gear active. 12:28:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:28:59 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:28:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:00 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:29:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:29:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:01 INFO - (blobuploader) - INFO - Done attempting. 12:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:29:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:02 INFO - (blobuploader) - INFO - Done attempting. 12:29:02 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:02 INFO - Return code: 0 12:29:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d985189b2d19eb602406421c6ce6743198908d824640e43ba2e0377f947a3b5582cda10c054905df91495b8b736468cc67b8d8aa4533189a50e3ccd1ed60e96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cb6cfca2ab8e06b75f80e42d92843e24bd0ae1a77b09d5a63e23d94f012e5f78af6d60a65db9393c6a475e6c5172c86d794ef0c64c7e82cefb1f5c78f422ecc"} 12:29:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:02 INFO - Contents: 12:29:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d985189b2d19eb602406421c6ce6743198908d824640e43ba2e0377f947a3b5582cda10c054905df91495b8b736468cc67b8d8aa4533189a50e3ccd1ed60e96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cb6cfca2ab8e06b75f80e42d92843e24bd0ae1a77b09d5a63e23d94f012e5f78af6d60a65db9393c6a475e6c5172c86d794ef0c64c7e82cefb1f5c78f422ecc"} 12:29:02 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:02 INFO - Copying logs to upload dir... 12:29:02 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.605450 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-24 12:29:02.275446) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 12:29:02.280689) ========= 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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d985189b2d19eb602406421c6ce6743198908d824640e43ba2e0377f947a3b5582cda10c054905df91495b8b736468cc67b8d8aa4533189a50e3ccd1ed60e96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cb6cfca2ab8e06b75f80e42d92843e24bd0ae1a77b09d5a63e23d94f012e5f78af6d60a65db9393c6a475e6c5172c86d794ef0c64c7e82cefb1f5c78f422ecc"} build_url:https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011383 build_url: 'https://queue.taskcluster.net/v1/task/ZVvZ-jBoQf-BTA2wULqb_Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d985189b2d19eb602406421c6ce6743198908d824640e43ba2e0377f947a3b5582cda10c054905df91495b8b736468cc67b8d8aa4533189a50e3ccd1ed60e96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cb6cfca2ab8e06b75f80e42d92843e24bd0ae1a77b09d5a63e23d94f012e5f78af6d60a65db9393c6a475e6c5172c86d794ef0c64c7e82cefb1f5c78f422ecc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 12:29:02.313869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:29:02.314282) ========= 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.xlfHKL8spx/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.Ag6l6lOYKZ/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005275 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 12:29:02.369789) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 12:29:02.372487) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 12:29:02.372945) ========= ========= Total master_lag: 0.18 =========