builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1462865807.75 results: success (0) buildid: 20160509234605 builduid: 201d40c881f64458b8fb0d78f2ac5344 revision: e34254bca3f447e27e5ee5586ce57eb37691bfa2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:47.746231) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:47.747012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:47.747335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:47.783860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:47.784256) ========= 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.1oo0Q1boJB/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.fxsE92OGc6/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-05-10 00:36:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-05-10 00:36:47 (14.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.187234 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.026723) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.027043) ========= 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.1oo0Q1boJB/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.fxsE92OGc6/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.026052 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.082504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.082851) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e34254bca3f447e27e5ee5586ce57eb37691bfa2 --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 e34254bca3f447e27e5ee5586ce57eb37691bfa2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1oo0Q1boJB/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.fxsE92OGc6/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-05-10 00:36:48,121 truncating revision to first 12 chars 2016-05-10 00:36:48,121 Setting DEBUG logging. 2016-05-10 00:36:48,121 attempt 1/10 2016-05-10 00:36:48,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e34254bca3f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 00:36:48,754 unpacking tar archive at: fx-team-e34254bca3f4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.879846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.983328) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.983801) ========= script_repo_revision: e34254bca3f447e27e5ee5586ce57eb37691bfa2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.984293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:48.984618) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 00:36:49.000260) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-10 00:36:49.000692) ========= /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.1oo0Q1boJB/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.fxsE92OGc6/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:36:49 INFO - MultiFileLogger online at 20160510 00:36:49 in /builds/slave/test 00:36:49 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 00:36:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:36:49 INFO - {'append_to_log': False, 00:36:49 INFO - 'base_work_dir': '/builds/slave/test', 00:36:49 INFO - 'blob_upload_branch': 'Fx-Team', 00:36:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:36:49 INFO - 'branch': 'Fx-Team', 00:36:49 INFO - 'buildbot_json_path': 'buildprops.json', 00:36:49 INFO - 'config_files': ('talos/mac_config.py',), 00:36:49 INFO - 'default_actions': ('clobber', 00:36:49 INFO - 'read-buildbot-config', 00:36:49 INFO - 'download-and-extract', 00:36:49 INFO - 'populate-webroot', 00:36:49 INFO - 'create-virtualenv', 00:36:49 INFO - 'install', 00:36:49 INFO - 'run-tests'), 00:36:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:36:49 INFO - 'download_minidump_stackwalk': True, 00:36:49 INFO - 'download_symbols': 'ondemand', 00:36:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:36:49 INFO - 'tooltool.py': '/tools/tooltool.py', 00:36:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:36:49 INFO - '/tools/misc-python/virtualenv.py')}, 00:36:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:36:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:36:49 INFO - 'installer_path': 'installer.exe', 00:36:49 INFO - 'log_level': 'info', 00:36:49 INFO - 'log_name': 'talos', 00:36:49 INFO - 'log_to_console': True, 00:36:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:36:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:36:49 INFO - 'opt_config_files': (), 00:36:49 INFO - 'pip_index': False, 00:36:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:36:49 INFO - 'cmd': ('bash', 00:36:49 INFO - '-c', 00:36:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:36:49 INFO - 'enabled': True, 00:36:49 INFO - 'halt_on_failure': False, 00:36:49 INFO - 'name': 'check_screen_resolution'},), 00:36:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:36:49 INFO - 'cmd': ('bash', 00:36:49 INFO - '-c', 00:36:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:36:49 INFO - 'enabled': True, 00:36:49 INFO - 'halt_on_failure': False, 00:36:49 INFO - 'name': 'check_screen_resolution'},), 00:36:49 INFO - 'run_cmd_checks_enabled': True, 00:36:49 INFO - 'sps_profile': False, 00:36:49 INFO - 'sps_profile_interval': 0, 00:36:49 INFO - 'suite': 'dromaeojs', 00:36:49 INFO - 'system_bits': '32', 00:36:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:36:49 INFO - 'title': 't-yosemite-r7-0147', 00:36:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:36:49 INFO - 'use_talos_json': True, 00:36:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:36:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:36:49 INFO - 'work_dir': 'build'} 00:36:49 INFO - ##### 00:36:49 INFO - ##### Running clobber step. 00:36:49 INFO - ##### 00:36:49 INFO - Running pre-action listener: _resource_record_pre_action 00:36:49 INFO - Running main action method: clobber 00:36:49 INFO - rmtree: /builds/slave/test/build 00:36:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:36:50 INFO - Running post-action listener: _resource_record_post_action 00:36:50 INFO - ##### 00:36:50 INFO - ##### Finished clobber step (success) 00:36:50 INFO - ##### 00:36:50 INFO - ##### 00:36:50 INFO - ##### Running read-buildbot-config step. 00:36:50 INFO - ##### 00:36:50 INFO - Running pre-action listener: _resource_record_pre_action 00:36:50 INFO - Running main action method: read_buildbot_config 00:36:50 INFO - Using buildbot properties: 00:36:50 INFO - { 00:36:50 INFO - "project": "", 00:36:50 INFO - "product": "firefox", 00:36:50 INFO - "script_repo_revision": "production", 00:36:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 00:36:50 INFO - "repository": "", 00:36:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 00:36:50 INFO - "buildid": "20160509234605", 00:36:50 INFO - "pgo_build": "False", 00:36:50 INFO - "basedir": "/builds/slave/test", 00:36:50 INFO - "buildnumber": 813, 00:36:50 INFO - "slavename": "t-yosemite-r7-0147", 00:36:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 00:36:50 INFO - "platform": "yosemite_r7", 00:36:50 INFO - "scheduler": "tests-fx-team-macosx64-talos", 00:36:50 INFO - "branch": "fx-team", 00:36:50 INFO - "repo_path": "integration/fx-team", 00:36:50 INFO - "revision": "e34254bca3f447e27e5ee5586ce57eb37691bfa2", 00:36:50 INFO - "stage_platform": "macosx64", 00:36:50 INFO - "builduid": "201d40c881f64458b8fb0d78f2ac5344", 00:36:50 INFO - "slavebuilddir": "test" 00:36:50 INFO - } 00:36:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 00:36:50 INFO - Running post-action listener: _resource_record_post_action 00:36:50 INFO - ##### 00:36:50 INFO - ##### Finished read-buildbot-config step (success) 00:36:50 INFO - ##### 00:36:50 INFO - ##### 00:36:50 INFO - ##### Running download-and-extract step. 00:36:50 INFO - ##### 00:36:50 INFO - Running pre-action listener: _resource_record_pre_action 00:36:50 INFO - Running main action method: download_and_extract 00:36:50 INFO - mkdir: /builds/slave/test/build/tests 00:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:36:50 INFO - https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:36:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:36:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 00:36:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 00:36:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 00:36:52 INFO - Downloaded 1199 bytes. 00:36:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 00:36:52 INFO - Using the following test package requirements: 00:36:52 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:36:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 00:36:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 00:36:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'jsshell-mac.zip'], 00:36:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 00:36:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 00:36:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 00:36:52 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 00:36:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 00:36:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 00:36:52 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 00:36:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:36:52 INFO - https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:36:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:36:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:36:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:36:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:36:53 INFO - Downloaded 18146499 bytes. 00:36:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:36:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 00:36:57 INFO - Return code: 0 00:36:57 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 00:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:36:57 INFO - https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:36:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:36:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:36:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 00:36:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:37:02 INFO - Downloaded 18146499 bytes. 00:37:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:37:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 00:37:05 INFO - Return code: 0 00:37:05 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')]} 00:37:05 INFO - https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:37:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:37:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:37:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 00:37:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:37:07 INFO - Downloaded 10840152 bytes. 00:37:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 00:37:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 00:37:07 INFO - Return code: 0 00:37: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')]} 00:37:07 INFO - https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 00:37:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:37:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:37:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 00:37:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 00:37:10 INFO - Downloaded 110418444 bytes. 00:37:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:37:10 INFO - mkdir: /builds/slave/test/properties 00:37:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:37:10 INFO - Writing to file /builds/slave/test/properties/build_url 00:37:10 INFO - Contents: 00:37:10 INFO - build_url:https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 00:37:12 INFO - Running post-action listener: _resource_record_post_action 00:37:12 INFO - Running post-action listener: set_extra_try_arguments 00:37:12 INFO - ##### 00:37:12 INFO - ##### Finished download-and-extract step (success) 00:37:12 INFO - ##### 00:37:12 INFO - ##### 00:37:12 INFO - ##### Running populate-webroot step. 00:37:12 INFO - ##### 00:37:12 INFO - Running pre-action listener: _resource_record_pre_action 00:37:12 INFO - Running main action method: populate_webroot 00:37:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:37:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:37:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:37:12 INFO - '--tppagecycles', 00:37:12 INFO - '7'], 00:37:12 INFO - 'tests': ['tsvgm']}}, 00:37:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:37:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:37:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:37:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:37:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:37:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:37:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['damp', 'tps']}, 00:37:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'tests': ['damp', 'tps']}, 00:37:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['dromaeo_dom']}, 00:37:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:37:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['a11yr', 00:37:12 INFO - 'ts_paint', 00:37:12 INFO - 'tpaint', 00:37:12 INFO - 'sessionrestore', 00:37:12 INFO - 'sessionrestore_no_auto_restore', 00:37:12 INFO - 'tabpaint']}, 00:37:12 INFO - 'other-e10s': {'tests': ['a11yr', 00:37:12 INFO - 'ts_paint', 00:37:12 INFO - 'tpaint', 00:37:12 INFO - 'sessionrestore', 00:37:12 INFO - 'sessionrestore_no_auto_restore', 00:37:12 INFO - 'tabpaint']}, 00:37:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:37:12 INFO - 'ts_paint', 00:37:12 INFO - 'tpaint', 00:37:12 INFO - 'sessionrestore', 00:37:12 INFO - 'sessionrestore_no_auto_restore', 00:37:12 INFO - 'tabpaint']}, 00:37:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:37:12 INFO - 'ts_paint', 00:37:12 INFO - 'tpaint', 00:37:12 INFO - 'sessionrestore', 00:37:12 INFO - 'sessionrestore_no_auto_restore', 00:37:12 INFO - 'tabpaint']}, 00:37:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['a11yr', 00:37:12 INFO - 'ts_paint', 00:37:12 INFO - 'tpaint', 00:37:12 INFO - 'sessionrestore', 00:37:12 INFO - 'sessionrestore_no_auto_restore', 00:37:12 INFO - 'tabpaint']}, 00:37:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['a11yr', 00:37:12 INFO - 'ts_paint', 00:37:12 INFO - 'tpaint', 00:37:12 INFO - 'sessionrestore', 00:37:12 INFO - 'sessionrestore_no_auto_restore', 00:37:12 INFO - 'tabpaint']}, 00:37:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['tsvgx', 00:37:12 INFO - 'tsvgr_opacity', 00:37:12 INFO - 'tart', 00:37:12 INFO - 'tscrollx', 00:37:12 INFO - 'cart']}, 00:37:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:37:12 INFO - 'tsvgr_opacity', 00:37:12 INFO - 'tart', 00:37:12 INFO - 'tscrollx', 00:37:12 INFO - 'cart']}, 00:37:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'talos_options': ['--disable-e10s'], 00:37:12 INFO - 'tests': ['tp5o']}, 00:37:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'tests': ['tp5o']}, 00:37:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'talos_options': ['--disable-e10s', 00:37:12 INFO - '--xperf_path', 00:37:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:37:12 INFO - 'tests': ['tp5n']}, 00:37:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:37:12 INFO - 'talos_options': ['--xperf_path', 00:37:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:37:12 INFO - 'tests': ['tp5n']}}, 00:37:12 INFO - 'talos.zip': {'path': '', 00:37:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:37:12 INFO - Running post-action listener: _resource_record_post_action 00:37:12 INFO - ##### 00:37:12 INFO - ##### Finished populate-webroot step (success) 00:37:12 INFO - ##### 00:37:12 INFO - ##### 00:37:12 INFO - ##### Running create-virtualenv step. 00:37:12 INFO - ##### 00:37:12 INFO - Running pre-action listener: _resource_record_pre_action 00:37:12 INFO - Running main action method: create_virtualenv 00:37:12 INFO - Creating virtualenv /builds/slave/test/build/venv 00:37:12 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 00:37:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:37:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:37:12 INFO - Using real prefix '/tools/python27' 00:37:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:37:13 INFO - Installing distribute.............................................................................................................................................................................................done. 00:37:15 INFO - Installing pip.................done. 00:37:15 INFO - Return code: 0 00:37:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:37:15 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')]} 00:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:15 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')]} 00:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:15 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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:15 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 00:37:15 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 00:37:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:15 INFO - 'HOME': '/Users/cltbld', 00:37:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:15 INFO - 'LOGNAME': 'cltbld', 00:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:15 INFO - 'MOZ_NO_REMOTE': '1', 00:37:15 INFO - 'NO_EM_RESTART': '1', 00:37:15 INFO - 'PAGER': '/bin/cat', 00:37:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:15 INFO - 'PWD': '/builds/slave/test', 00:37:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:15 INFO - 'SHELL': '/bin/bash', 00:37:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:15 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:15 INFO - 'USER': 'cltbld', 00:37:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:15 INFO - 'XPC_FLAGS': '0x0', 00:37:15 INFO - 'XPC_SERVICE_NAME': '0', 00:37:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:16 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 00:37:16 INFO - Cleaning up... 00:37:16 INFO - Return code: 0 00:37:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 00:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 00:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:16 INFO - 'HOME': '/Users/cltbld', 00:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:16 INFO - 'LOGNAME': 'cltbld', 00:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:16 INFO - 'MOZ_NO_REMOTE': '1', 00:37:16 INFO - 'NO_EM_RESTART': '1', 00:37:16 INFO - 'PAGER': '/bin/cat', 00:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:16 INFO - 'PWD': '/builds/slave/test', 00:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:16 INFO - 'SHELL': '/bin/bash', 00:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:16 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:16 INFO - 'USER': 'cltbld', 00:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:16 INFO - 'XPC_FLAGS': '0x0', 00:37:16 INFO - 'XPC_SERVICE_NAME': '0', 00:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:16 INFO - Downloading/unpacking psutil>=0.7.1 00:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:37:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:37:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:37:19 INFO - Installing collected packages: psutil 00:37:19 INFO - Running setup.py install for psutil 00:37:19 INFO - building 'psutil._psutil_osx' extension 00:37:19 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 00:37:19 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 00:37:20 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 00:37:20 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 00:37:20 INFO - building 'psutil._psutil_posix' extension 00:37:20 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 00:37:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:37:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:37:20 INFO - ^ 00:37:20 INFO - 1 warning generated. 00:37:20 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 00:37:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:37:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:37:20 INFO - Successfully installed psutil 00:37:20 INFO - Cleaning up... 00:37:20 INFO - Return code: 0 00:37:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 00:37:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 00:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 00:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:20 INFO - 'HOME': '/Users/cltbld', 00:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:20 INFO - 'LOGNAME': 'cltbld', 00:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:20 INFO - 'MOZ_NO_REMOTE': '1', 00:37:20 INFO - 'NO_EM_RESTART': '1', 00:37:20 INFO - 'PAGER': '/bin/cat', 00:37:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:20 INFO - 'PWD': '/builds/slave/test', 00:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:20 INFO - 'SHELL': '/bin/bash', 00:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:20 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:20 INFO - 'USER': 'cltbld', 00:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:20 INFO - 'XPC_FLAGS': '0x0', 00:37:20 INFO - 'XPC_SERVICE_NAME': '0', 00:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:37:23 INFO - Installing collected packages: mozsystemmonitor 00:37:23 INFO - Running setup.py install for mozsystemmonitor 00:37:23 INFO - Successfully installed mozsystemmonitor 00:37:23 INFO - Cleaning up... 00:37:23 INFO - Return code: 0 00:37:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:37:23 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')]} 00:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:23 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')]} 00:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:23 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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:23 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 00:37:23 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 00:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:23 INFO - 'HOME': '/Users/cltbld', 00:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:23 INFO - 'LOGNAME': 'cltbld', 00:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:23 INFO - 'MOZ_NO_REMOTE': '1', 00:37:23 INFO - 'NO_EM_RESTART': '1', 00:37:23 INFO - 'PAGER': '/bin/cat', 00:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:23 INFO - 'PWD': '/builds/slave/test', 00:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:23 INFO - 'SHELL': '/bin/bash', 00:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:23 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:23 INFO - 'USER': 'cltbld', 00:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:23 INFO - 'XPC_FLAGS': '0x0', 00:37:23 INFO - 'XPC_SERVICE_NAME': '0', 00:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:23 INFO - Downloading/unpacking blobuploader==1.2.4 00:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:26 INFO - Downloading blobuploader-1.2.4.tar.gz 00:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:37:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:37:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 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 00:37:26 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 00:37:27 INFO - Downloading docopt-0.6.1.tar.gz 00:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:37:27 INFO - Installing collected packages: blobuploader, requests, docopt 00:37:27 INFO - Running setup.py install for blobuploader 00:37:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:37:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:37:27 INFO - Running setup.py install for requests 00:37:28 INFO - Running setup.py install for docopt 00:37:28 INFO - Successfully installed blobuploader requests docopt 00:37:28 INFO - Cleaning up... 00:37:28 INFO - Return code: 0 00:37:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:28 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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:28 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 00:37:28 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 00:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:28 INFO - 'HOME': '/Users/cltbld', 00:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:28 INFO - 'LOGNAME': 'cltbld', 00:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:28 INFO - 'MOZ_NO_REMOTE': '1', 00:37:28 INFO - 'NO_EM_RESTART': '1', 00:37:28 INFO - 'PAGER': '/bin/cat', 00:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:28 INFO - 'PWD': '/builds/slave/test', 00:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:28 INFO - 'SHELL': '/bin/bash', 00:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:28 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:28 INFO - 'USER': 'cltbld', 00:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:28 INFO - 'XPC_FLAGS': '0x0', 00:37:28 INFO - 'XPC_SERVICE_NAME': '0', 00:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:37:28 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-c3WKtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:37:28 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-r9uVXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:37:28 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-m7xqlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:37:28 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-bwqUG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-an294u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xG27G9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-BusRJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6xQdpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Xn1wnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nYIgVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-oLdDZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-SYz5Ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-SingBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:37:29 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-mm7hhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:37:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nxVnAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:37:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-m2f1xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:37:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-U18P0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:37:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:37:30 INFO - Running setup.py install for manifestparser 00:37:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:37:30 INFO - Running setup.py install for mozcrash 00:37:30 INFO - Running setup.py install for mozdebug 00:37:30 INFO - Running setup.py install for mozdevice 00:37:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:37:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:37:30 INFO - Running setup.py install for mozfile 00:37:31 INFO - Running setup.py install for mozhttpd 00:37:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Running setup.py install for mozinfo 00:37:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Running setup.py install for mozInstall 00:37:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Running setup.py install for mozleak 00:37:31 INFO - Running setup.py install for mozlog 00:37:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Running setup.py install for moznetwork 00:37:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:37:31 INFO - Running setup.py install for mozprocess 00:37:32 INFO - Running setup.py install for mozprofile 00:37:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:37:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:37:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:37:32 INFO - Running setup.py install for mozrunner 00:37:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:37:32 INFO - Running setup.py install for mozscreenshot 00:37:32 INFO - Running setup.py install for moztest 00:37:32 INFO - Running setup.py install for mozversion 00:37:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:37:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:37:32 INFO - Cleaning up... 00:37:32 INFO - Return code: 0 00:37:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:37:32 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')]} 00:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:32 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')]} 00:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:32 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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:32 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 00:37:32 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 00:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:32 INFO - 'HOME': '/Users/cltbld', 00:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:32 INFO - 'LOGNAME': 'cltbld', 00:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:32 INFO - 'MOZ_NO_REMOTE': '1', 00:37:32 INFO - 'NO_EM_RESTART': '1', 00:37:32 INFO - 'PAGER': '/bin/cat', 00:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:32 INFO - 'PWD': '/builds/slave/test', 00:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:32 INFO - 'SHELL': '/bin/bash', 00:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:32 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:32 INFO - 'USER': 'cltbld', 00:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:32 INFO - 'XPC_FLAGS': '0x0', 00:37:32 INFO - 'XPC_SERVICE_NAME': '0', 00:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-mkhRgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-kTzK64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fV8d7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-uKq9Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-GhBBJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-aNxXLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-otYDhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:37:33 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)) 00:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:37:33 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qJuPXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-d6suRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-5Kc6jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-D27TiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-QiKRS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xyUvQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UcqTQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-dx1ynC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-KBf_zZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:37:34 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)) 00:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:37:34 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_fic_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:37:34 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)) 00:37:34 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)) 00:37:34 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)) 00:37:34 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)) 00:37:34 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)) 00:37:34 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)) 00:37:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:37:34 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 00:37:34 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 00:37:34 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 00:37:34 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 00:37:34 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 00:37:34 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 00:37:37 INFO - Downloading blessings-1.6.tar.gz 00:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:37:37 INFO - Installing collected packages: blessings 00:37:37 INFO - Running setup.py install for blessings 00:37:37 INFO - Successfully installed blessings 00:37:37 INFO - Cleaning up... 00:37:37 INFO - Return code: 0 00:37:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:37:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:37:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:37:37 INFO - Reading from file tmpfile_stdout 00:37:37 INFO - Current package versions: 00:37:37 INFO - blessings == 1.6 00:37:37 INFO - blobuploader == 1.2.4 00:37:37 INFO - docopt == 0.6.1 00:37:37 INFO - manifestparser == 1.1 00:37:38 INFO - mozInstall == 1.12 00:37:38 INFO - mozcrash == 0.17 00:37:38 INFO - mozdebug == 0.1 00:37:38 INFO - mozdevice == 0.48 00:37:38 INFO - mozfile == 1.2 00:37:38 INFO - mozhttpd == 0.7 00:37:38 INFO - mozinfo == 0.9 00:37:38 INFO - mozleak == 0.1 00:37:38 INFO - mozlog == 3.1 00:37:38 INFO - moznetwork == 0.27 00:37:38 INFO - mozprocess == 0.22 00:37:38 INFO - mozprofile == 0.28 00:37:38 INFO - mozrunner == 6.11 00:37:38 INFO - mozscreenshot == 0.1 00:37:38 INFO - mozsystemmonitor == 0.0 00:37:38 INFO - moztest == 0.7 00:37:38 INFO - mozversion == 1.4 00:37:38 INFO - psutil == 3.1.1 00:37:38 INFO - requests == 1.2.3 00:37:38 INFO - wsgiref == 0.1.2 00:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:38 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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:38 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 00:37:38 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 00:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:38 INFO - 'HOME': '/Users/cltbld', 00:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:38 INFO - 'LOGNAME': 'cltbld', 00:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:38 INFO - 'MOZ_NO_REMOTE': '1', 00:37:38 INFO - 'NO_EM_RESTART': '1', 00:37:38 INFO - 'PAGER': '/bin/cat', 00:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:38 INFO - 'PWD': '/builds/slave/test', 00:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:38 INFO - 'SHELL': '/bin/bash', 00:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:38 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:38 INFO - 'USER': 'cltbld', 00:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:38 INFO - 'XPC_FLAGS': '0x0', 00:37:38 INFO - 'XPC_SERVICE_NAME': '0', 00:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 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)) 00:37:38 INFO - Cleaning up... 00:37:38 INFO - Return code: 0 00:37:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:38 INFO - 'HOME': '/Users/cltbld', 00:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:38 INFO - 'LOGNAME': 'cltbld', 00:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:38 INFO - 'MOZ_NO_REMOTE': '1', 00:37:38 INFO - 'NO_EM_RESTART': '1', 00:37:38 INFO - 'PAGER': '/bin/cat', 00:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:38 INFO - 'PWD': '/builds/slave/test', 00:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:38 INFO - 'SHELL': '/bin/bash', 00:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:38 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:38 INFO - 'USER': 'cltbld', 00:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:38 INFO - 'XPC_FLAGS': '0x0', 00:37:38 INFO - 'XPC_SERVICE_NAME': '0', 00:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:38 INFO - Downloading/unpacking jsonschema 00:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:37:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:37:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:37:40 INFO - Downloading/unpacking functools32 (from jsonschema) 00:37: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 00:37: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 00:37: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 00:37: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 00:37: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 00:37: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 00:37:41 INFO - Downloading functools32-3.2.3-2.tar.gz 00:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:37:41 INFO - warning: no files found matching '*.txt' 00:37:41 INFO - no previously-included directories found matching 'build' 00:37:41 INFO - no previously-included directories found matching 'dist' 00:37:41 INFO - no previously-included directories found matching '.git*' 00:37:41 INFO - Installing collected packages: jsonschema, functools32 00:37:41 INFO - Running setup.py install for functools32 00:37:41 INFO - warning: no files found matching '*.txt' 00:37:41 INFO - no previously-included directories found matching 'build' 00:37:41 INFO - no previously-included directories found matching 'dist' 00:37:41 INFO - no previously-included directories found matching '.git*' 00:37:41 INFO - Successfully installed jsonschema functools32 00:37:41 INFO - Cleaning up... 00:37:41 INFO - Return code: 0 00:37:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:37:41 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 0x10e15ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e162570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b859ff30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, '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.1oo0Q1boJB/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.fxsE92OGc6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 00:37:41 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 00:37:41 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 00:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:37:41 INFO - 'HOME': '/Users/cltbld', 00:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:37:41 INFO - 'LOGNAME': 'cltbld', 00:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:37:41 INFO - 'MOZ_NO_REMOTE': '1', 00:37:41 INFO - 'NO_EM_RESTART': '1', 00:37:41 INFO - 'PAGER': '/bin/cat', 00:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:37:41 INFO - 'PWD': '/builds/slave/test', 00:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:37:41 INFO - 'SHELL': '/bin/bash', 00:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:37:41 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:37:41 INFO - 'USER': 'cltbld', 00:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:37:41 INFO - 'XPC_FLAGS': '0x0', 00:37:41 INFO - 'XPC_SERVICE_NAME': '0', 00:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:37:41 INFO - Downloading/unpacking flake8 00:37:41 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 00:37:41 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 00:37:41 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 00:37:41 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 00:37:41 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 00:37:41 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 00:37:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:37:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:37:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 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 00:37:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:37:45 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 00:37:45 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 00:37:45 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 00:37:45 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 00:37:45 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 00:37:45 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 00:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:37:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:37:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:37:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:37:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:37:45 INFO - no previously-included directories found matching 'docs/_build' 00:37:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:37:45 INFO - Running setup.py install for pep8 00:37:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:37:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:37:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:37:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:37:45 INFO - no previously-included directories found matching 'docs/_build' 00:37:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:37:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:37:45 INFO - Cleaning up... 00:37:45 INFO - Return code: 0 00:37:45 INFO - Running post-action listener: _resource_record_post_action 00:37:45 INFO - Running post-action listener: _start_resource_monitoring 00:37:45 INFO - Starting resource monitoring. 00:37:45 INFO - ##### 00:37:45 INFO - ##### Finished create-virtualenv step (success) 00:37:45 INFO - ##### 00:37:45 INFO - ##### 00:37:45 INFO - ##### Running install step. 00:37:45 INFO - ##### 00:37:45 INFO - Running pre-action listener: _resource_record_pre_action 00:37:45 INFO - Running main action method: install 00:37:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:37:46 INFO - Reading from file tmpfile_stdout 00:37:46 INFO - Detecting whether we're running mozinstall >=1.0... 00:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:37:46 INFO - Reading from file tmpfile_stdout 00:37:46 INFO - Output received: 00:37:46 INFO - Usage: mozinstall [options] installer 00:37:46 INFO - Options: 00:37:46 INFO - -h, --help show this help message and exit 00:37:46 INFO - -d DEST, --destination=DEST 00:37:46 INFO - Directory to install application into. [default: 00:37:46 INFO - "/builds/slave/test"] 00:37:46 INFO - --app=APP Application being installed. [default: firefox] 00:37:46 INFO - mkdir: /builds/slave/test/build/application 00:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 00:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 00:38:19 INFO - Reading from file tmpfile_stdout 00:38:19 INFO - Output received: 00:38:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:38:19 INFO - Running post-action listener: _resource_record_post_action 00:38:19 INFO - ##### 00:38:19 INFO - ##### Finished install step (success) 00:38:19 INFO - ##### 00:38:19 INFO - ##### 00:38:19 INFO - ##### Running run-tests step. 00:38:19 INFO - ##### 00:38:19 INFO - Running pre-action listener: _resource_record_pre_action 00:38:19 INFO - Running main action method: run_tests 00:38:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:38:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:38:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:38:19 INFO - 2016-05-10 00:38:19.480 screenresolution[1652:8697] starting screenresolution argv=screenresolution get 00:38:19 INFO - 2016-05-10 00:38:19.483 screenresolution[1652:8697] Display 0: 1600x1200x32@60 00:38:19 INFO - 2016-05-10 00:38:19.489 screenresolution[1653:8699] starting screenresolution argv=screenresolution list 00:38:19 INFO - Available Modes on Display 0 00:38:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:38:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:38:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:38:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:38:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:38:19 INFO - Intel Iris: 00:38:19 INFO - Chipset Model: Intel Iris 00:38:19 INFO - Type: GPU 00:38:19 INFO - Bus: Built-In 00:38:19 INFO - VRAM (Dynamic, Max): 1536 MB 00:38:19 INFO - Vendor: Intel (0x8086) 00:38:19 INFO - Device ID: 0x0a2e 00:38:19 INFO - Revision ID: 0x0009 00:38:19 INFO - Displays: 00:38:19 INFO - Display: 00:38:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:38:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:38:19 INFO - Display Serial Number: 1600x1200 60 00:38:19 INFO - Main Display: Yes 00:38:19 INFO - Mirror: Off 00:38:19 INFO - Online: Yes 00:38:19 INFO - Rotation: Supported 00:38:19 INFO - Return code: 0 00:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:38:19 INFO - Python 2.7.3 00:38:19 INFO - Return code: 0 00:38:19 INFO - grabbing minidump binary from tooltool 00:38:19 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')]} 00:38:19 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 0x10e000d30>, '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 0x10de24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dff7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:38:19 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 00:38:19 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 00:38:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:38:19 INFO - Return code: 0 00:38:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:38:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:38:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:38:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:38:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:38:20 INFO - Return code: 0 00:38:20 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/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 00:38:20 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/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 00:38:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1oo0Q1boJB/Render', 00:38:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:38:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:38:20 INFO - 'HOME': '/Users/cltbld', 00:38:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:38:20 INFO - 'LOGNAME': 'cltbld', 00:38:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:38:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:38:20 INFO - 'MOZ_NO_REMOTE': '1', 00:38:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:38:20 INFO - 'NO_EM_RESTART': '1', 00:38:20 INFO - 'PAGER': '/bin/cat', 00:38:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:38:20 INFO - 'PWD': '/builds/slave/test', 00:38:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:38:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:38:20 INFO - 'SHELL': '/bin/bash', 00:38:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fxsE92OGc6/Listeners', 00:38:20 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 00:38:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:38:20 INFO - 'USER': 'cltbld', 00:38:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:38:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:38:20 INFO - 'XPC_FLAGS': '0x0', 00:38:20 INFO - 'XPC_SERVICE_NAME': '0', 00:38:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:38:20 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/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 00:38:21 INFO - mozversion application_buildid: 20160509234605 00:38:21 INFO - mozversion application_changeset: e34254bca3f447e27e5ee5586ce57eb37691bfa2 00:38:21 INFO - mozversion application_display_name: Nightly 00:38:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:38:21 INFO - mozversion application_name: Firefox 00:38:21 INFO - mozversion application_remotingname: firefox 00:38:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:38:21 INFO - mozversion application_vendor: Mozilla 00:38:21 INFO - mozversion application_version: 49.0a1 00:38:21 INFO - mozversion platform_buildid: 20160509234605 00:38:21 INFO - mozversion platform_changeset: e34254bca3f447e27e5ee5586ce57eb37691bfa2 00:38:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:38:21 INFO - mozversion platform_version: 49.0a1 00:38:21 INFO - using testdate: 1462865901 00:38:21 INFO - actual date: 1462865901 00:38:21 INFO - starting webserver on 'localhost:49275' 00:38:21 INFO - SUITE-START | Running 2 tests 00:38:21 INFO - TEST-START | dromaeo_css 00:38:21 INFO - operating with platform_type : mac_ 00:38:21 INFO - Initialising browser for dromaeo_css test... 00:38:21 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpnua_1_/profile http://localhost:49275/getInfo.html) 00:38:23 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 00:38:23 INFO - PROCESS | 1671 | colorDepth:24 00:38:23 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 00:38:23 INFO - PROCESS | 1671 | __metrics 00:38:23 INFO - PROCESS | 1671 | 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" 00:38:23 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:38:24 INFO - TEST-INFO | 1671: exit 0 00:38:24 INFO - Browser initialized. 00:38:24 INFO - Running cycle 1/1 for dromaeo_css test... 00:38:24 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpnua_1_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:38:36 INFO - PROCESS | 1674 | RSS: Main: 238997504 00:38:36 INFO - PROCESS | 1674 | 00:39:27 INFO - PROCESS | 1674 | 1462865967845 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] 00:41:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 00:41:39 INFO - PROCESS | 1674 | RSS: Main: 393658368 00:41:39 INFO - PROCESS | 1674 | 00:44:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 00:44:43 INFO - PROCESS | 1674 | RSS: Main: 375336960 00:44:43 INFO - PROCESS | 1674 | 00:47:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 00:47:46 INFO - PROCESS | 1674 | RSS: Main: 371052544 00:47:46 INFO - PROCESS | 1674 | 00:50:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 00:50:49 INFO - PROCESS | 1674 | RSS: Main: 369123328 00:50:49 INFO - PROCESS | 1674 | 00:53:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 00:53:53 INFO - PROCESS | 1674 | RSS: Main: 343470080 00:53:53 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 00:56:56 INFO - PROCESS | 1674 | RSS: Main: 380162048 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | __start_tp_report 00:56:56 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 00:56:56 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 00:56:56 INFO - PROCESS | 1674 | |i|pagename|runs| 00:56:56 INFO - PROCESS | 1674 | |0;dojo.html;2756.2007017964793;3017.426688929104;3461.8096004719746;3475.965240347599;3533.205028868505;5657.264555607765;5676.233708449358;5717.085266357383;5768.596198266117;5800.506956908668;5762.049261871786;5854.297484301879;5856.531477481802;5859.76560937563;5891.234139561851;5213.478652134794;5256.316678831747;5277.868053298679;5299.231611416342;5350.117230656937;5310.468953104678;5320.441353657869;5320.840374788762;5332.013577488157;5335.1997200419855;5155.871103222412;5180.171172612383;5182.533571978521;5191.117510023305;5315.229291752694;5288.6562373445795;5296.894062118775;5306.893862122774;5315.946840531584;5376.354837419534;3741.4387841823673;4526.932096018562;5051.570616497602;5193.584513238932;5211.5830733541225;5521.365043020024;5576.665400076008;5578.30271216098;5591.9720401397735;5602.831915042514;6160.106784516265;6247.531435142382;6272.2159730033745;6283.465905398047;6317.905231421532;6135.1101794315;6211.254649442096;6274.874502509924;6286.277078136027;6332.366763323631;5443.673379597197;5883.7058147092475;5886.646801191942;5925.940740592625;5949.970250148722;6275.843103922347;6281.214848143982;6290;6310.432061114522;6311.431971122621;6014.7594096236535;6087.391260873966;6136.386361363828;6198.380161983856;6228.128062071313;5612.130119831432;6195.349488303754;6256.7810126645345;6293.748250070037;6322.462590679752;6206.06908963659;6235.688215589203;6273.12176295319;6275.717592708339;6280.497560195174;6134.4172303631085;6152.3540028297275;6287.748490060438;6315.463185629273;6322.178116844781;4934.851954441372;4958.206686930074;4969.204927211629;4969.478204788517;4983.6013118950395;3841.3853783394525;4494.505604383515;4532.750698711537;4534.115847409789;4547.272436410159;5685.488306052475;5713.5714821388565;5715.599908006399;5720.913026524947;5739.081746920473;6268.185135932391;6317.4946004319545;6320.494360451153;6325;6328.525360597973;5762.567807414461;5769;5777.480026797524;5780.248567686258;5819.94180058194;5346.331708536433;5393.487618676219;5439.129739241703;5457.754401051962;5491.972540137273;6304.18045654062;6365.8726825464155;6377.74489020434;6379.425851673279;6384.680765961683;6329.398707122816;6334.144890439916;6342.207224096988;6358.809235722936;6364.109024736633;6218;6256.718447669957;6257.09272155544;6316.905246421399;6378.840528986812;6220.502359811295;6233.470155036691;6241.656708880873;6252.5935814171935;6256.624602523863;6286.057091436322;6327.272363678052;6330.335314791881;6330.8733825322315;6341.587796793194;5929.792457263975;6023.3073196582955;6026.397360264114;6036.3661815510495;6073.331933487225;6094.146819445193;6104.450599445893;6173.56785025044;6175.59858609189;6182.474489668429;6006.819795406145;6023.247094113236;6032.185654936703;6054.061620448661;6062.272527296753;5455.399906010256;5918.526517878646;5959.523238141025;6129.1112788644505;6171.7531298749345;5840.240768700041;6059.091136329585;6064.3026052002815;6084.11780291864;6085.69571521431 00:56:56 INFO - PROCESS | 1674 | |1;ext.html;527.6675694312582;527.9128943724286;528.2235114381857;528.7990563585837;569.9430056994299;29678.55482167769;31520.527192092108;38294.8085259574;42984.570154298446;43092;43186.78406607963;43410.34884476735;43554.911127221836;43570.12859742803;43797.562024379746;9743.074407931254;9923;9935.552900119475;9938.055884690979;9971.551280192409;7497.512661677001;8680.348973826956;8875.511846848452;8932.955335223314;8943.910560894405;11629.70925726855;11874.821877671842;11896.286222826615;11983.161178717493;11986.520539178422;9958.20334373252;11657.41712914355;11789.115816313724;11801.11491638122;11818.704532386673;8581.699640512552;11369.829452558219;11736.471858766376;11831.467583958742;11870.52517899283;40157.598424015676;40473.79763101166;40498.797506012284;40581.59418405808;40588.59411405907;2693.676758788938;3271.3457308538386;3364.6803553662608;3444.51776751255;3681.687056600192;29325.706742932725;29382.55926161109;29752.55371169434;30365.696343036507;30706.078817635283;2726.1140129458004;2729.9181024569098;2759.806813523054;2772.3900741836765;2811.9718802811913;483886;487951;489770;490022;490721;59067.70466147728;59375.81248375008;59402.702986484794;59410;59410.51473713105;36539.08652283714;37209.813950930074;37295.81352093222;63232.68383658052;63361.68319158467;17294.65410691779;17368.218430170673;17463.388781392845;17466.476005719847;17483.825161748475;13168.60494185176;13226.537071202654;13241.205527668179;13244.470221191237;13249.470021199237;17106;17511.562210944827;19482.220711171394;19606;19755.901220493804;8281.37889658278;8342.499450033018;8363.707270245512;8379.874301885477;8423.831523369621;919.5723988345452;1055.3140458701905;1114.885114885115;1117.1453837814079;1192.1833544022372;24193;28630.284242894093;29306;29666.22002679803;29850.879987258173;1060.8037513059953;1064.414571985405;1069.2194697870598;1206.5837286136268;1217.7990631545695;14762.630934226516;14897.659210671092;15276.770848437282;15482.5355239343;15799.763003554956;544308;668884;670411;671952;672401;53975.19037214445;55007.44992550103;59568.404315956286;60763.08855367173;60871.6956415224;1457.0747575289768;1459.7373272119753;1479.9704005919607;1492.970140597182;1498.3257534109694;29651.555226672048;29977.250568735955;30396.392072158877;30662.08013759591;30798.538021929242;50810.745946270035;51051.74474127606;51505.742471287405;51518.96962060813;51612.48387516077;578390;580408;581662;582747;582815;26716.8664156678;26743.331416714736;26870;26893.058742943904;27274.590881136402;45866;46351;46618.601441956795;46907.76546117248;47165.52834471611;37595;37744;37774.05564860791;38390.04024899288;38579.228415430975;36842.07894802544;37357.6264237365;37909.24181516299;38124.61875381211;38136;36664;37314.8134259327;37472.62527374691;37560.624393755716;38387.424188636636;33188.33623327471;33544.1613959653;33659.49510757389;33884.66115338815;33937.98186054422;20644;24289.878550607133;24710.50578988374;25298.36754081163;25352 00:56:56 INFO - PROCESS | 1674 | |2;jquery.html;355.51472240391854;385.9884203473896;391.8863529576423;392.5819002762059;407.43977031581574;15143.545693629196;20713;22210.444738881535;22524.21165259214;22537;20644.69032964507;20712.378628641127;21730.348089557297;21734.89132554339;21769;4890.168671325883;5475.589330800186;5528.695921724304;5685.914711279334;5699.544036477072;5554.805581804638;5555.416681248471;5672.914906276388;5678.37537870836;5684.801031963882;5432.511074003339;5575.219469274305;5603.663780173182;5611.326640803089;5656.406077361879;5573.5819813513945;5582.7766889324375;5624.381318055031;5624.5781566382275;5817.621854579439;5299;5454.754536045887;5479.315085614298;5482.314710661168;5488.204210389511;25360.87319563409;25605.359866003313;26305.473890522084;26649.866750666122;26767.330816729544;2043.8773673579485;2088.4152437317575;2103.0851579562786;2200.449887528118;2674.2645772412548;10228.130608898253;10347.689569312932;10431.895681043168;13005.284709340982;14437.422503099864;1434;1747.3185457671518;1778.7865456145346;1803.4769916724135;1868.2807119259182;131848;132049;132345;133225.66774332325;133564.3321783385;9481.857772133424;10251.794964100676;10282.331648442827;10442.68671939843;10555.313904596176;12471.189372690927;12542.561010364594;12617.87382126167;12621.747565048647;12631.494740210288;8096.595170241464;8280.668773249123;8291.33669306454;8397.202265784741;8403.36974726898;8572.485650860968;8572.657093716181;8587.871181932276;8679.696210632597;8688.348373871984;3405.6594340565744;4075.694322925733;4077.510698716173;4128.6284234418445;4130.690198235145;1947.9026048697508;1995.8602897797305;2003.519155402722;2020.272701827341;2021.8887961162277;687.6046273392819;897.3001059173855;909.0591238068567;931.3108299858056;958.520739630185;18912;19184.424467266006;19501.12244948981;19518.21927122899;19573.902130489256;923.612082925173;929.163752622645;939.145377706284;1007.3905287301224;1049.490996866519;9257.305702072372;10134.949325253474;10292.015315558554;10472.842907356397;10631.84052239217;592273;594309;600938;610552;610996;136900;138155;138853;139702;141731;959.2733080455153;971.9125278724948;990.4799980010553;992.9404235745734;1027.019196667181;14033.508827190999;16116.549510544108;17344.653106938043;17548.12259387;17662.558436039202;50082.49917500778;50329.49670503395;53317.733411332694;53461.73269133629;54778;74359.62820185865;78399;79455.20544794478;82099;82920.58539707263;7141;7487.775366739006;7570.659320330488;7768.572728499987;8230.42387032902;9030;11154.665360039211;12517.93741031289;12817.423215955117;12945.54690585825;11024.559017639525;11093.833592495957;11136.721581960515;11435.199536032398;11512.36681982499;9561.426314421156;10513.421761802869;10901.291416057931;11218.775624487627;11248.931351521593;10715.261868437039;10854.131669466582;11161.218714689894;11309.491072901903;11685.065194784567;8245.464044837066;8315.085340612648;8456.492610443147;8524.70163544273;8691.826163476822;9084.772880678036;10569.682909512727;10617.416042117758;11036.282641628268;11610.129240306842 00:56:56 INFO - PROCESS | 1674 | |3;mootools.html;1410.7655801173973;1577.1641030253966;1596.1939220693553;1814.891106533608;1950.746402967614;7685.231476852312;8446.282066024387;8700;8707.564621768917;8839.955800221005;8455.74632761018;8684.478931264113;8695.69565065223;8701.869471957925;8725.781855453617;1968.4586738646844;2082.458560774202;2313.1325752842686;2400.3759022654062;2405.194259922928;2409.915652952147;2441.8023778599227;2442.206282958046;2445.217530390276;2446.1683027770555;2364.598018336887;2384.1178763857397;2404.0984630763464;2408.5664580375524;2409.132712223607;1938.9917243033544;2053.712480252766;2311.2026350908995;2331.8367714260007;2372.3476044087843;2315.3169814904645;2396.269137912936;2411.746766589518;2416.5408572371193;2417.3231495181376;7515.849683006364;8603.182697643713;8612.52631105289;8615.61229744663;8635.093315201877;5115.232715092766;5141.280220769096;5355.3305836770405;5380.515753582196;5390.110631745757;3436;3477.2523907359787;3577.2487777566744;3581.8746343877838;3583.372909740785;997.1623835977741;1027.7944411117733;1035.2132379391642;1050.4117694091258;1066.6693325069252;11534.019608333301;11581.42092895352;11584.478698458506;11586.768264634744;11602.767944641144;4720.457147428021;4793.089313030513;4870.975645121752;4882.829100981448;4896.828610998598;9819.901800982041;9958.352707074017;9976.650817221362;9979.900200997898;10001.599936002624;5400.756965936543;5415.458454154632;5449.04641687711;5453.481919217668;5453.972730136404;4374.147041326911;4547.886302842389;4665.836695715701;4802.663813533089;4807.711537307703;3912.9021774455296;3985.8007099645483;4030.234255491447;4063.309237429644;4107.466029416157;1302.8892544133855;1312.7046414556648;1313.8029295605736;1319.399673148715;1327.4955516903708;915.6566287642135;929.5398777605129;937.8171256604896;938.1322276893845;1011.1758916483043;5925.614835035709;5931.347551769302;5988.251468566429;6009.699515024231;6122.816315510542;957.363353370015;976.2824324121765;999.2555546118043;1003.2225025605214;1004.2217281606813;3942.15243722601;4008.6793056555407;4035.051762835731;4096.938545921814;4106.404571337199;9509.239260859114;10615.52230149645;10753.51609177589;10772.515236814363;10804.567817287378;10561.841572376421;10626.574937002433;10654.467276636136;10776.67669969902;10797.380392940964;6881.41507971828;7654.349380302737;7996.840063198703;8264.132266111972;8275.793105172419;5966.343702192842;6000.609960352563;6093.542984276109;6148.846278842886;6271.341509141657;9434.528273586431;9711.27165462579;9833.213342932691;9990.800183996134;9995.100440960348;8705.651773928972;9552.85365756086;9664.71005869825;10130.89869101329;10300.124489418184;6829.795106146824;7068.469864760061;7307.159676637255;7523.285287897641;7861.921380786119;8023.759287221393;8167.3466122711225;8223.17768223173;8268.627911743866;8269.62786674589;7075.823104422225;7252.746153884589;7370.189279179179;7512.098548174254;7515.549067055994;7150.892736608851;7162.820929476805;7211.278872112747;7231.891521627285;7251.492395532262;6927.653617319214;6968.2927682718055;7024.438044956493;7027.789166325018;7027.9297207027275;4830.2513110467935;4834.178189707688;4981.750912454435;5016.272640467185;5178.559822415137;4925.729084900365;4926.531979461945;4988.176950803077;5031.371078615173;5137.6660517066275 00:56:56 INFO - PROCESS | 1674 | |4;prototype.html;307.7953161147837;316.03924070824695;319.8145075856004;321.55785794532505;325.2632786738037;5163.75376711131;5234.55506281966;5247.291615631893;5249.71126588038;5260.368755749316;4347.978225117103;4914.631402644807;5135.614828887839;5259.9474005259935;5278.891432799107;3170.9048728538182;3214.710676039156;3797.9810100949458;3847.5179962134243;3874.128321127752;4001.659858911996;4023.718339716221;4029.823895096659;4044.8179831907487;4051.6150965658217;3495.675375875053;4003.418649633393;4041.636252737253;4053.7365071270274;4092.0588264699136;3795.0891785971444;3881.4565960765517;4068.5117785865737;4074.9641517069676;4083.979005248688;3502.8949131526097;3506.650544135435;3528.6118526962014;3548.8935331940083;3558.1994051338243;4241.427407300006;4737.247218529132;4859.902801943941;4959.752012399365;5039.269305950654;3985.5217373915025;4128.6284234419045;4485.865424037252;4629.606483448911;4721.905561888743;5143.794248230104;5445.727713614304;5475.753591088371;5512.614117011811;5609.49514543693;3438;3892.9416058759143;3894.40329464918;3921.6666583340166;3924.332863413198;5669.631473954221;5784.334801497797;5826.854328641775;5867;5916.9645312070215;3488.3546543888956;3611.9277614448088;3727.9748069281163;3952.3281042222893;3973.264945985002;4056.7363121397016;4062.6343629073526;4064.4309796628495;4099.774512401847;4109.958900411018;3966.424868394067;4132.367714752656;4646.675697426217;4691.155591993405;4695.600873925755;5054.317667114892;5541.168824676331;5592.5539196338195;5628.634138780966;5693.572982026281;1203.5486692490317;1275.355945247644;1282.9037822163375;1302.5571305756089;1316.8024796280442;852.5524099847505;869.9086595907465;903.512103464122;918.1874041473211;927.1470247372433;4458.130664520388;5100.719460429712;5162.341394181999;5201.557867581298;5240.868978275574;5299.231611416323;5664.405237450009;5714.628549144292;5739.713014349266;5741.224934633771;4922.941567562991;4959.380077490314;5019.7239151847;5155.123628983082;5169.353830771153;2444.4744379958393;2481.16393868536;2561.2956436979607;2783.819051761629;2867.3978464522684;4807;4867.634927380461;5262.079136151158;5301.95137795769;5570.1592187984415;5282.445343238905;5318.563987723293;5343.946560534423;5372.62391632582;5594.860128496739;4777.713337199779;5082.364704411949;5392.595555333366;5953.851153721105;6119.663418512025;5067.315912351785;5822.417758224144;5835.503982161564;5837.3870743571315;5861.853453663693;4830.589399901048;4989.600831933364;5280.81517146898;5691.4877661010705;5726.255586773693;5446.400895901527;5532.585056120726;5772.363411051178;6052.788152414644;6054.969725151346;3094.706002929718;3717.2565486902186;3832.94250586247;3921;3932.8820135395986;4715.622750180046;5490.149026900836;5707.372189059117;5755.8561035974435;5774.913376299275;5207.479252074762;5255.05716798594;5473;5549.694766787866;5589.664620122806;4647.883802904955;4707.634785912047;5285.4714528548375;5443.564514838724;5579.386267510489;4921.655484116078;5168.405633352213;5405.918911216414;5463.48096930791;5500.22996780443;4933.407991041098;5039.5968322534845;5425.403205647297;5513.365962914316;5647.519960803378;4962.6774259673;5085.059264036166;5291.841244762663;5564.916526252028;5565.02612042899 00:56:56 INFO - PROCESS | 1674 | |5;yui.html;642.402565613979;647.8866198415276;683.8290427393155;737.7565403416872;749.6214411722079;14811.851881481181;15474.690506189869;15651.45219917303;15679.45121920733;15688.372465101396;14451.349689264009;14806;14815.703685926275;14827.777583336232;14829.851701482981;728.785008422514;749.512816669164;915.8168366326728;917.8760867282898;934.6121359635742;895.4627223665781;927.0729270729271;928.6703220356777;929.4005366538579;929.4284015330591;980.4803454169302;981.2003217377815;981.813455443467;983.3608154699467;984.7685793838442;750.5721738608995;935.8736570563022;977.799551092028;980.7548112971757;981.79873126009;724.7427163356986;728.5483000539647;729.3508777188307;729.9671514781847;730.4229658569724;15321.6169595761;15425.15161666108;15426;15440.073595584301;15447.227638618137;12416.317102559356;12447.3776311185;12644.810327845089;12668.2399056056;12835.614931552069;9284.953575232148;10815.891841081568;10872.402017889013;10898.455077246184;11384.430778461045;2834.4047749972324;3438.862445502177;3608.6030536640947;3938.330483817758;4314.417553630251;89172;90197;90409;90477.28568143032;90608.09391906128;9296;9310.208632266198;9425.104615061558;9626.566804493814;9666;20486.692699609517;20526.589468210554;20534.58930821375;20536;20545.589088218152;7133.3936615388275;7179.92820071803;7181.281871812777;7232.746853860195;7339.559626422431;3846.9038274043373;3851.7496362736333;3942.073612701013;4003.2794097062224;4135.379693046007;3461.7922924624604;3531.9293614127573;3555.5733312002726;3558.857645694195;3559.039059453933;619.1672200889886;626.192212046455;632.4529282170889;651.5764752910646;654.9063064681898;557.8047683310808;558.7536413437858;592.8814237152586;600.504583718434;603.2941458493573;7576.962115189389;7866;7930.801729956682;8057.959710201411;8067.475614085066;573.2032474859948;575.2521721761776;575.5654480866918;592.5881512348876;598.8772301678146;3145.732612727944;3655.8666813287964;3684.1710615111388;3823.369144091194;3889.1638297766117;94852.52573737087;96598.0340196603;97445.02554974501;98182.5090874541;98538.01461985432;82242.76635850467;82514.93712657111;82995.17004829996;83405.16594833974;87662;73059;73748;74304.14239644053;74781.25218747852;75831.86252206331;6982.615956122462;7253.746118885813;8042.437029407885;8159;8336.791580210544;37843.62156378401;44066.779666101465;45206.32190517211;47707;47816.5218347826;50285;61659.691701541204;62203.377966219756;65698;66046;11094.722631933942;11125.388103654053;11189.552417903193;11419.429028548706;11944.462499187384;10673.879242679717;11906.642800715992;12131.696707582028;12453.1905426147;12895.419706113435;10628.627998020032;11317.717057073309;11437.485313161094;11663.475143618387;12343.012558995079;11057.46643876007;11341.319520828776;11396.886031139915;11500.597479088192;11538.192326537062;9111;11099.778004440028;11232.4383780809;11266.436678166223;11438.485268163118;7947.801304967422;9265.166135047879;9389.342746007715;9501.857472137784;9710.417374957526;7386.926130738624;8437.704680336159;9710.660126895526;9748.415095094317;9827.852582211415 00:56:56 INFO - PROCESS | 1674 | __end_tp_report 00:56:56 INFO - PROCESS | 1674 | __start_cc_report 00:56:56 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1520 00:56:56 INFO - PROCESS | 1674 | __end_cc_report 00:56:56 INFO - PROCESS | 1674 | __startTimestamp1462867016795__endTimestamp 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | ------- Summary: start ------- 00:56:56 INFO - PROCESS | 1674 | Number of tests: 6 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5755.83 Median:6014.76 stddev:685.11 (11.4%) stddev-sans-first:645.54 00:56:56 INFO - PROCESS | 1674 | Values: 2756.2 3017.4 3461.8 3476.0 3533.2 5657.3 5676.2 5717.1 5768.6 5800.5 5762.0 5854.3 5856.5 5859.8 5891.2 5213.5 5256.3 5277.9 5299.2 5350.1 5310.5 5320.4 5320.8 5332.0 5335.2 5155.9 5180.2 5182.5 5191.1 5315.2 5288.7 5296.9 5306.9 5315.9 5376.4 3741.4 4526.9 5051.6 5193.6 5211.6 5521.4 5576.7 5578.3 5592.0 5602.8 6160.1 6247.5 6272.2 6283.5 6317.9 6135.1 6211.3 6274.9 6286.3 6332.4 5443.7 5883.7 5886.6 5925.9 5950.0 6275.8 6281.2 6290.0 6310.4 6311.4 6014.8 6087.4 6136.4 6198.4 6228.1 5612.1 6195.3 6256.8 6293.7 6322.5 6206.1 6235.7 6273.1 6275.7 6280.5 6134.4 6152.4 6287.7 6315.5 6322.2 4934.9 4958.2 4969.2 4969.5 4983.6 3841.4 4494.5 4532.8 4534.1 4547.3 5685.5 5713.6 5715.6 5720.9 5739.1 6268.2 6317.5 6320.5 6325.0 6328.5 5762.6 5769.0 5777.5 5780.2 5819.9 5346.3 5393.5 5439.1 5457.8 5492.0 6304.2 6365.9 6377.7 6379.4 6384.7 6329.4 6334.1 6342.2 6358.8 6364.1 6218.0 6256.7 6257.1 6316.9 6378.8 6220.5 6233.5 6241.7 6252.6 6256.6 6286.1 6327.3 6330.3 6330.9 6341.6 5929.8 6023.3 6026.4 6036.4 6073.3 6094.1 6104.5 6173.6 6175.6 6182.5 6006.8 6023.2 6032.2 6054.1 6062.3 5455.4 5918.5 5959.5 6129.1 6171.8 5840.2 6059.1 6064.3 6084.1 6085.7 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:70041.07 Median:20261.88 stddev:154021.54 (760.2%) stddev-sans-first:154365.18 00:56:56 INFO - PROCESS | 1674 | Values: 527.7 527.9 528.2 528.8 569.9 29678.6 31520.5 38294.8 42984.6 43092.0 43186.8 43410.3 43554.9 43570.1 43797.6 9743.1 9923.0 9935.6 9938.1 9971.6 7497.5 8680.3 8875.5 8933.0 8943.9 11629.7 11874.8 11896.3 11983.2 11986.5 9958.2 11657.4 11789.1 11801.1 11818.7 8581.7 11369.8 11736.5 11831.5 11870.5 40157.6 40473.8 40498.8 40581.6 40588.6 2693.7 3271.3 3364.7 3444.5 3681.7 29325.7 29382.6 29752.6 30365.7 30706.1 2726.1 2729.9 2759.8 2772.4 2812.0 483886.0 487951.0 489770.0 490022.0 490721.0 59067.7 59375.8 59402.7 59410.0 59410.5 36539.1 37209.8 37295.8 63232.7 63361.7 17294.7 17368.2 17463.4 17466.5 17483.8 13168.6 13226.5 13241.2 13244.5 13249.5 17106.0 17511.6 19482.2 19606.0 19755.9 8281.4 8342.5 8363.7 8379.9 8423.8 919.6 1055.3 1114.9 1117.1 1192.2 24193.0 28630.3 29306.0 29666.2 29850.9 1060.8 1064.4 1069.2 1206.6 1217.8 14762.6 14897.7 15276.8 15482.5 15799.8 544308.0 668884.0 670411.0 671952.0 672401.0 53975.2 55007.4 59568.4 60763.1 60871.7 1457.1 1459.7 1480.0 1493.0 1498.3 29651.6 29977.3 30396.4 30662.1 30798.5 50810.7 51051.7 51505.7 51519.0 51612.5 578390.0 580408.0 581662.0 582747.0 582815.0 26716.9 26743.3 26870.0 26893.1 27274.6 45866.0 46351.0 46618.6 46907.8 47165.5 37595.0 37744.0 37774.1 38390.0 38579.2 36842.1 37357.6 37909.2 38124.6 38136.0 36664.0 37314.8 37472.6 37560.6 38387.4 33188.3 33544.2 33659.5 33884.7 33938.0 20644.0 24289.9 24710.5 25298.4 25352.0 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:35642.96 Median:14662.07 stddev:101153.21 (689.9%) stddev-sans-first:101402.27 00:56:56 INFO - PROCESS | 1674 | Values: 355.5 386.0 391.9 392.6 407.4 15143.5 20713.0 22210.4 22524.2 22537.0 20644.7 20712.4 21730.3 21734.9 21769.0 4890.2 5475.6 5528.7 5685.9 5699.5 5554.8 5555.4 5672.9 5678.4 5684.8 5432.5 5575.2 5603.7 5611.3 5656.4 5573.6 5582.8 5624.4 5624.6 5817.6 5299.0 5454.8 5479.3 5482.3 5488.2 25360.9 25605.4 26305.5 26649.9 26767.3 2043.9 2088.4 2103.1 2200.4 2674.3 10228.1 10347.7 10431.9 13005.3 14437.4 1434.0 1747.3 1778.8 1803.5 1868.3 131848.0 132049.0 132345.0 133225.7 133564.3 9481.9 10251.8 10282.3 10442.7 10555.3 12471.2 12542.6 12617.9 12621.7 12631.5 8096.6 8280.7 8291.3 8397.2 8403.4 8572.5 8572.7 8587.9 8679.7 8688.3 3405.7 4075.7 4077.5 4128.6 4130.7 1947.9 1995.9 2003.5 2020.3 2021.9 687.6 897.3 909.1 931.3 958.5 18912.0 19184.4 19501.1 19518.2 19573.9 923.6 929.2 939.1 1007.4 1049.5 9257.3 10134.9 10292.0 10472.8 10631.8 592273.0 594309.0 600938.0 610552.0 610996.0 136900.0 138155.0 138853.0 139702.0 141731.0 959.3 971.9 990.5 992.9 1027.0 14033.5 16116.5 17344.7 17548.1 17662.6 50082.5 50329.5 53317.7 53461.7 54778.0 74359.6 78399.0 79455.2 82099.0 82920.6 7141.0 7487.8 7570.7 7768.6 8230.4 9030.0 11154.7 12517.9 12817.4 12945.5 11024.6 11093.8 11136.7 11435.2 11512.4 9561.4 10513.4 10901.3 11218.8 11248.9 10715.3 10854.1 11161.2 11309.5 11685.1 8245.5 8315.1 8456.5 8524.7 8691.8 9084.8 10569.7 10617.4 11036.3 11610.1 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5666.70 Median:5023.82 stddev:3129.38 (62.3%) stddev-sans-first:3121.81 00:56:56 INFO - PROCESS | 1674 | Values: 1410.8 1577.2 1596.2 1814.9 1950.7 7685.2 8446.3 8700.0 8707.6 8840.0 8455.7 8684.5 8695.7 8701.9 8725.8 1968.5 2082.5 2313.1 2400.4 2405.2 2409.9 2441.8 2442.2 2445.2 2446.2 2364.6 2384.1 2404.1 2408.6 2409.1 1939.0 2053.7 2311.2 2331.8 2372.3 2315.3 2396.3 2411.7 2416.5 2417.3 7515.8 8603.2 8612.5 8615.6 8635.1 5115.2 5141.3 5355.3 5380.5 5390.1 3436.0 3477.3 3577.2 3581.9 3583.4 997.2 1027.8 1035.2 1050.4 1066.7 11534.0 11581.4 11584.5 11586.8 11602.8 4720.5 4793.1 4871.0 4882.8 4896.8 9819.9 9958.4 9976.7 9979.9 10001.6 5400.8 5415.5 5449.0 5453.5 5454.0 4374.1 4547.9 4665.8 4802.7 4807.7 3912.9 3985.8 4030.2 4063.3 4107.5 1302.9 1312.7 1313.8 1319.4 1327.5 915.7 929.5 937.8 938.1 1011.2 5925.6 5931.3 5988.3 6009.7 6122.8 957.4 976.3 999.3 1003.2 1004.2 3942.2 4008.7 4035.1 4096.9 4106.4 9509.2 10615.5 10753.5 10772.5 10804.6 10561.8 10626.6 10654.5 10776.7 10797.4 6881.4 7654.3 7996.8 8264.1 8275.8 5966.3 6000.6 6093.5 6148.8 6271.3 9434.5 9711.3 9833.2 9990.8 9995.1 8705.7 9552.9 9664.7 10130.9 10300.1 6829.8 7068.5 7307.2 7523.3 7861.9 8023.8 8167.3 8223.2 8268.6 8269.6 7075.8 7252.7 7370.2 7512.1 7515.5 7150.9 7162.8 7211.3 7231.9 7251.5 6927.7 6968.3 7024.4 7027.8 7027.9 4830.3 4834.2 4981.8 5016.3 5178.6 4925.7 4926.5 4988.2 5031.4 5137.7 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4457.99 Median:5029.50 stddev:1369.63 (27.2%) stddev-sans-first:1337.58 00:56:56 INFO - PROCESS | 1674 | Values: 307.8 316.0 319.8 321.6 325.3 5163.8 5234.6 5247.3 5249.7 5260.4 4348.0 4914.6 5135.6 5259.9 5278.9 3170.9 3214.7 3798.0 3847.5 3874.1 4001.7 4023.7 4029.8 4044.8 4051.6 3495.7 4003.4 4041.6 4053.7 4092.1 3795.1 3881.5 4068.5 4075.0 4084.0 3502.9 3506.7 3528.6 3548.9 3558.2 4241.4 4737.2 4859.9 4959.8 5039.3 3985.5 4128.6 4485.9 4629.6 4721.9 5143.8 5445.7 5475.8 5512.6 5609.5 3438.0 3892.9 3894.4 3921.7 3924.3 5669.6 5784.3 5826.9 5867.0 5917.0 3488.4 3611.9 3728.0 3952.3 3973.3 4056.7 4062.6 4064.4 4099.8 4110.0 3966.4 4132.4 4646.7 4691.2 4695.6 5054.3 5541.2 5592.6 5628.6 5693.6 1203.5 1275.4 1282.9 1302.6 1316.8 852.6 869.9 903.5 918.2 927.1 4458.1 5100.7 5162.3 5201.6 5240.9 5299.2 5664.4 5714.6 5739.7 5741.2 4922.9 4959.4 5019.7 5155.1 5169.4 2444.5 2481.2 2561.3 2783.8 2867.4 4807.0 4867.6 5262.1 5302.0 5570.2 5282.4 5318.6 5343.9 5372.6 5594.9 4777.7 5082.4 5392.6 5953.9 6119.7 5067.3 5822.4 5835.5 5837.4 5861.9 4830.6 4989.6 5280.8 5691.5 5726.3 5446.4 5532.6 5772.4 6052.8 6055.0 3094.7 3717.3 3832.9 3921.0 3932.9 4715.6 5490.1 5707.4 5755.9 5774.9 5207.5 5255.1 5473.0 5549.7 5589.7 4647.9 4707.6 5285.5 5443.6 5579.4 4921.7 5168.4 5405.9 5463.5 5500.2 4933.4 5039.6 5425.4 5513.4 5647.5 4962.7 5085.1 5291.8 5564.9 5565.0 00:56:56 INFO - PROCESS | 1674 | 00:56:56 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18603.56 Median:31411.27 stddev:26928.29 (85.7%) stddev-sans-first:26970.06 00:56:56 INFO - PROCESS | 1674 | Values: 642.4 647.9 683.8 737.8 749.6 14811.9 15474.7 15651.5 15679.5 15688.4 14451.3 14806.0 14815.7 14827.8 14829.9 728.8 749.5 915.8 917.9 934.6 895.5 927.1 928.7 929.4 929.4 980.5 981.2 981.8 983.4 984.8 750.6 935.9 977.8 980.8 981.8 724.7 728.5 729.4 730.0 730.4 15321.6 15425.2 15426.0 15440.1 15447.2 12416.3 12447.4 12644.8 12668.2 12835.6 9285.0 10815.9 10872.4 10898.5 11384.4 2834.4 3438.9 3608.6 3938.3 4314.4 89172.0 90197.0 90409.0 90477.3 90608.1 9296.0 9310.2 9425.1 9626.6 9666.0 20486.7 20526.6 20534.6 20536.0 20545.6 7133.4 7179.9 7181.3 7232.7 7339.6 3846.9 3851.7 3942.1 4003.3 4135.4 3461.8 3531.9 3555.6 3558.9 3559.0 619.2 626.2 632.5 651.6 654.9 557.8 558.8 592.9 600.5 603.3 7577.0 7866.0 7930.8 8058.0 8067.5 573.2 575.3 575.6 592.6 598.9 3145.7 3655.9 3684.2 3823.4 3889.2 94852.5 96598.0 97445.0 98182.5 98538.0 82242.8 82514.9 82995.2 83405.2 87662.0 73059.0 73748.0 74304.1 74781.3 75831.9 6982.6 7253.7 8042.4 8159.0 8336.8 37843.6 44066.8 45206.3 47707.0 47816.5 50285.0 61659.7 62203.4 65698.0 66046.0 11094.7 11125.4 11189.6 11419.4 11944.5 10673.9 11906.6 12131.7 12453.2 12895.4 10628.6 11317.7 11437.5 11663.5 12343.0 11057.5 11341.3 11396.9 11500.6 11538.2 9111.0 11099.8 11232.4 11266.4 11438.5 7947.8 9265.2 9389.3 9501.9 9710.4 7386.9 8437.7 9710.7 9748.4 9827.9 00:56:56 INFO - PROCESS | 1674 | -------- Summary: end -------- 00:56:56 INFO - PROCESS | 1674 | 00:56:57 INFO - TEST-INFO | 1674: exit 0 00:56:57 INFO - TEST-OK | dromaeo_css | took 1116343ms 00:56:57 INFO - TEST-START | kraken 00:56:57 INFO - operating with platform_type : mac_ 00:56:57 INFO - Initialising browser for kraken test... 00:56:57 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpAUsfUQ/profile http://localhost:49275/getInfo.html) 00:56:59 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 00:56:59 INFO - PROCESS | 1679 | colorDepth:24 00:56:59 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 00:56:59 INFO - PROCESS | 1679 | __metrics 00:57:00 INFO - PROCESS | 1679 | 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" 00:57:00 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:57:00 INFO - TEST-INFO | 1679: exit 0 00:57:00 INFO - Browser initialized. 00:57:00 INFO - Running cycle 1/1 for kraken test... 00:57:00 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpAUsfUQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:57:12 INFO - PROCESS | 1681 | RSS: Main: 239095808 00:57:12 INFO - PROCESS | 1681 | 00:57:15 INFO - PROCESS | 1681 | 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) 00:57:16 INFO - PROCESS | 1681 | RSS: Main: 286773248 00:57:16 INFO - PROCESS | 1681 | 00:57:18 INFO - PROCESS | 1681 | 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) 00:57:19 INFO - PROCESS | 1681 | RSS: Main: 295981056 00:57:19 INFO - PROCESS | 1681 | 00:57:23 INFO - PROCESS | 1681 | 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) 00:57:23 INFO - PROCESS | 1681 | RSS: Main: 386392064 00:57:23 INFO - PROCESS | 1681 | 00:57:25 INFO - PROCESS | 1681 | 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) 00:57:26 INFO - PROCESS | 1681 | RSS: Main: 284356608 00:57:26 INFO - PROCESS | 1681 | 00:57:28 INFO - PROCESS | 1681 | 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) 00:57:29 INFO - PROCESS | 1681 | RSS: Main: 288309248 00:57:29 INFO - PROCESS | 1681 | 00:57:32 INFO - PROCESS | 1681 | 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) 00:57:32 INFO - PROCESS | 1681 | RSS: Main: 327135232 00:57:32 INFO - PROCESS | 1681 | 00:57:35 INFO - PROCESS | 1681 | 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) 00:57:36 INFO - PROCESS | 1681 | RSS: Main: 328380416 00:57:36 INFO - PROCESS | 1681 | 00:57:39 INFO - PROCESS | 1681 | 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) 00:57:40 INFO - PROCESS | 1681 | RSS: Main: 327127040 00:57:40 INFO - PROCESS | 1681 | 00:57:41 INFO - PROCESS | 1681 | 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) 00:57:42 INFO - PROCESS | 1681 | RSS: Main: 315670528 00:57:42 INFO - PROCESS | 1681 | 00:57:44 INFO - PROCESS | 1681 | 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) 00:57:45 INFO - PROCESS | 1681 | RSS: Main: 322551808 00:57:45 INFO - PROCESS | 1681 | 00:57:47 INFO - PROCESS | 1681 | 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) 00:57:48 INFO - PROCESS | 1681 | RSS: Main: 325079040 00:57:48 INFO - PROCESS | 1681 | 00:57:51 INFO - PROCESS | 1681 | 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) 00:57:51 INFO - PROCESS | 1681 | RSS: Main: 329437184 00:57:51 INFO - PROCESS | 1681 | 00:57:54 INFO - PROCESS | 1681 | 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) 00:57:55 INFO - PROCESS | 1681 | RSS: Main: 327290880 00:57:55 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | 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) 00:57:57 INFO - PROCESS | 1681 | RSS: Main: 373850112 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | __start_tp_report 00:57:57 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 00:57:57 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 00:57:57 INFO - PROCESS | 1681 | |i|pagename|runs| 00:57:57 INFO - PROCESS | 1681 | |0;ai-astar;110;105;111;110;110;110;110;110;110;110 00:57:57 INFO - PROCESS | 1681 | |1;audio-beat-detection;167;118;143;198;122;141;138;121;174;113 00:57:57 INFO - PROCESS | 1681 | |2;audio-dft;186;183;184;184;184;184;179;183;184;185 00:57:57 INFO - PROCESS | 1681 | |3;audio-fft;80;63;59;146;81;70;57;59;109;57 00:57:57 INFO - PROCESS | 1681 | |4;audio-oscillator;93;97;149;113;119;89;103;83;80;102 00:57:57 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;86;87;88;83;86;83;87;83;86;83 00:57:57 INFO - PROCESS | 1681 | |6;imaging-darkroom;92;93;93;93;93;91;94;106;146;93 00:57:57 INFO - PROCESS | 1681 | |7;imaging-desaturate;84;85;85;85;85;84;83;84;86;84 00:57:57 INFO - PROCESS | 1681 | |8;json-parse-financial;57;59;61;60;58;60;61;121;57;57 00:57:57 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;63;76;75;75;71;68;68;68;69;68 00:57:57 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;86;84;86;83;86;86;82;86;86;86 00:57:57 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;139;138;141;141;139;139;142;142;243;128 00:57:57 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;169;176;202;166;168;170;217;221;166;222 00:57:57 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;69;71;82;85;89;82;70;66;65;69 00:57:57 INFO - PROCESS | 1681 | __end_tp_report 00:57:57 INFO - PROCESS | 1681 | __start_cc_report 00:57:57 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,2247 00:57:57 INFO - PROCESS | 1681 | __end_cc_report 00:57:57 INFO - PROCESS | 1681 | __startTimestamp1462867077669__endTimestamp 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | ------- Summary: start ------- 00:57:57 INFO - PROCESS | 1681 | Number of tests: 14 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:1.65 (1.5%) stddev-sans-first:1.74 00:57:57 INFO - PROCESS | 1681 | Values: 110.0 105.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:142.00 stddev:27.97 (19.7%) stddev-sans-first:28.34 00:57:57 INFO - PROCESS | 1681 | Values: 167.0 118.0 143.0 198.0 122.0 141.0 138.0 121.0 174.0 113.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.84 (1.0%) stddev-sans-first:1.73 00:57:57 INFO - PROCESS | 1681 | Values: 186.0 183.0 184.0 184.0 184.0 184.0 179.0 183.0 184.0 185.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:78.10 Median:61.00 stddev:28.85 (47.3%) stddev-sans-first:30.59 00:57:57 INFO - PROCESS | 1681 | Values: 80.0 63.0 59.0 146.0 81.0 70.0 57.0 59.0 109.0 57.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:81.50 stddev:20.37 (25.0%) stddev-sans-first:21.29 00:57:57 INFO - PROCESS | 1681 | Values: 93.0 97.0 149.0 113.0 119.0 89.0 103.0 83.0 80.0 102.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.99 (2.3%) stddev-sans-first:2.09 00:57:57 INFO - PROCESS | 1681 | Values: 86.0 87.0 88.0 83.0 86.0 83.0 87.0 83.0 86.0 83.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:93.00 stddev:16.91 (18.2%) stddev-sans-first:17.73 00:57:57 INFO - PROCESS | 1681 | Values: 92.0 93.0 93.0 93.0 93.0 91.0 94.0 106.0 146.0 93.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 00:57:57 INFO - PROCESS | 1681 | Values: 84.0 85.0 85.0 85.0 85.0 84.0 83.0 84.0 86.0 84.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:59.50 stddev:19.71 (33.1%) stddev-sans-first:20.68 00:57:57 INFO - PROCESS | 1681 | Values: 57.0 59.0 61.0 60.0 58.0 60.0 61.0 121.0 57.0 57.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:4.12 (5.9%) stddev-sans-first:3.48 00:57:57 INFO - PROCESS | 1681 | Values: 63.0 76.0 75.0 75.0 71.0 68.0 68.0 68.0 69.0 68.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.00 stddev:1.52 (1.8%) stddev-sans-first:1.58 00:57:57 INFO - PROCESS | 1681 | Values: 86.0 84.0 86.0 83.0 86.0 86.0 82.0 86.0 86.0 86.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:141.00 stddev:33.21 (23.6%) stddev-sans-first:35.01 00:57:57 INFO - PROCESS | 1681 | Values: 139.0 138.0 141.0 141.0 139.0 139.0 142.0 142.0 243.0 128.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:189.00 stddev:24.67 (13.1%) stddev-sans-first:25.22 00:57:57 INFO - PROCESS | 1681 | Values: 169.0 176.0 202.0 166.0 168.0 170.0 217.0 221.0 166.0 222.0 00:57:57 INFO - PROCESS | 1681 | 00:57:57 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:76.50 stddev:8.74 (11.4%) stddev-sans-first:9.02 00:57:57 INFO - PROCESS | 1681 | Values: 69.0 71.0 82.0 85.0 89.0 82.0 70.0 66.0 65.0 69.0 00:57:57 INFO - PROCESS | 1681 | -------- Summary: end -------- 00:57:57 INFO - PROCESS | 1681 | 00:57:58 INFO - TEST-INFO | 1681: exit 0 00:57:58 INFO - TEST-OK | kraken | took 61130ms 00:57:58 INFO - SUITE-END | took 1177s 00:57:58 INFO - Completed test suite (00:19:37) 00:57:58 INFO - javascript benchmark 00:57:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2756.2007017964793, 3017.426688929104, 3461.8096004719746, 3475.965240347599, 3533.205028868505, 5657.264555607765, 5676.233708449358, 5717.085266357383, 5768.596198266117, 5800.506956908668, 5762.049261871786, 5854.297484301879, 5856.531477481802, 5859.76560937563, 5891.234139561851, 5213.478652134794, 5256.316678831747, 5277.868053298679, 5299.231611416342, 5350.117230656937, 5310.468953104678, 5320.441353657869, 5320.840374788762, 5332.013577488157, 5335.1997200419855, 5155.871103222412, 5180.171172612383, 5182.533571978521, 5191.117510023305, 5315.229291752694, 5288.6562373445795, 5296.894062118775, 5306.893862122774, 5315.946840531584, 5376.354837419534, 3741.4387841823673, 4526.932096018562, 5051.570616497602, 5193.584513238932, 5211.5830733541225, 5521.365043020024, 5576.665400076008, 5578.30271216098, 5591.9720401397735, 5602.831915042514, 6160.106784516265, 6247.531435142382, 6272.2159730033745, 6283.465905398047, 6317.905231421532, 6135.1101794315, 6211.254649442096, 6274.874502509924, 6286.277078136027, 6332.366763323631, 5443.673379597197, 5883.7058147092475, 5886.646801191942, 5925.940740592625, 5949.970250148722, 6275.843103922347, 6281.214848143982, 6290.0, 6310.432061114522, 6311.431971122621, 6014.7594096236535, 6087.391260873966, 6136.386361363828, 6198.380161983856, 6228.128062071313, 5612.130119831432, 6195.349488303754, 6256.7810126645345, 6293.748250070037, 6322.462590679752, 6206.06908963659, 6235.688215589203, 6273.12176295319, 6275.717592708339, 6280.497560195174, 6134.4172303631085, 6152.3540028297275, 6287.748490060438, 6315.463185629273, 6322.178116844781, 4934.851954441372, 4958.206686930074, 4969.204927211629, 4969.478204788517, 4983.6013118950395, 3841.3853783394525, 4494.505604383515, 4532.750698711537, 4534.115847409789, 4547.272436410159, 5685.488306052475, 5713.5714821388565, 5715.599908006399, 5720.913026524947, 5739.081746920473, 6268.185135932391, 6317.4946004319545, 6320.494360451153, 6325.0, 6328.525360597973, 5762.567807414461, 5769.0, 5777.480026797524, 5780.248567686258, 5819.94180058194, 5346.331708536433, 5393.487618676219, 5439.129739241703, 5457.754401051962, 5491.972540137273, 6304.18045654062, 6365.8726825464155, 6377.74489020434, 6379.425851673279, 6384.680765961683, 6329.398707122816, 6334.144890439916, 6342.207224096988, 6358.809235722936, 6364.109024736633, 6218.0, 6256.718447669957, 6257.09272155544, 6316.905246421399, 6378.840528986812, 6220.502359811295, 6233.470155036691, 6241.656708880873, 6252.5935814171935, 6256.624602523863, 6286.057091436322, 6327.272363678052, 6330.335314791881, 6330.8733825322315, 6341.587796793194, 5929.792457263975, 6023.3073196582955, 6026.397360264114, 6036.3661815510495, 6073.331933487225, 6094.146819445193, 6104.450599445893, 6173.56785025044, 6175.59858609189, 6182.474489668429, 6006.819795406145, 6023.247094113236, 6032.185654936703, 6054.061620448661, 6062.272527296753, 5455.399906010256, 5918.526517878646, 5959.523238141025, 6129.1112788644505, 6171.7531298749345, 5840.240768700041, 6059.091136329585, 6064.3026052002815, 6084.11780291864, 6085.69571521431], "value": 5709.04127788963, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.6675694312582, 527.9128943724286, 528.2235114381857, 528.7990563585837, 569.9430056994299, 29678.55482167769, 31520.527192092108, 38294.8085259574, 42984.570154298446, 43092.0, 43186.78406607963, 43410.34884476735, 43554.911127221836, 43570.12859742803, 43797.562024379746, 9743.074407931254, 9923.0, 9935.552900119475, 9938.055884690979, 9971.551280192409, 7497.512661677001, 8680.348973826956, 8875.511846848452, 8932.955335223314, 8943.910560894405, 11629.70925726855, 11874.821877671842, 11896.286222826615, 11983.161178717493, 11986.520539178422, 9958.20334373252, 11657.41712914355, 11789.115816313724, 11801.11491638122, 11818.704532386673, 8581.699640512552, 11369.829452558219, 11736.471858766376, 11831.467583958742, 11870.52517899283, 40157.598424015676, 40473.79763101166, 40498.797506012284, 40581.59418405808, 40588.59411405907, 2693.676758788938, 3271.3457308538386, 3364.6803553662608, 3444.51776751255, 3681.687056600192, 29325.706742932725, 29382.55926161109, 29752.55371169434, 30365.696343036507, 30706.078817635283, 2726.1140129458004, 2729.9181024569098, 2759.806813523054, 2772.3900741836765, 2811.9718802811913, 483886.0, 487951.0, 489770.0, 490022.0, 490721.0, 59067.70466147728, 59375.81248375008, 59402.702986484794, 59410.0, 59410.51473713105, 36539.08652283714, 37209.813950930074, 37295.81352093222, 63232.68383658052, 63361.68319158467, 17294.65410691779, 17368.218430170673, 17463.388781392845, 17466.476005719847, 17483.825161748475, 13168.60494185176, 13226.537071202654, 13241.205527668179, 13244.470221191237, 13249.470021199237, 17106.0, 17511.562210944827, 19482.220711171394, 19606.0, 19755.901220493804, 8281.37889658278, 8342.499450033018, 8363.707270245512, 8379.874301885477, 8423.831523369621, 919.5723988345452, 1055.3140458701905, 1114.885114885115, 1117.1453837814079, 1192.1833544022372, 24193.0, 28630.284242894093, 29306.0, 29666.22002679803, 29850.879987258173, 1060.8037513059953, 1064.414571985405, 1069.2194697870598, 1206.5837286136268, 1217.7990631545695, 14762.630934226516, 14897.659210671092, 15276.770848437282, 15482.5355239343, 15799.763003554956, 544308.0, 668884.0, 670411.0, 671952.0, 672401.0, 53975.19037214445, 55007.44992550103, 59568.404315956286, 60763.08855367173, 60871.6956415224, 1457.0747575289768, 1459.7373272119753, 1479.9704005919607, 1492.970140597182, 1498.3257534109694, 29651.555226672048, 29977.250568735955, 30396.392072158877, 30662.08013759591, 30798.538021929242, 50810.745946270035, 51051.74474127606, 51505.742471287405, 51518.96962060813, 51612.48387516077, 578390.0, 580408.0, 581662.0, 582747.0, 582815.0, 26716.8664156678, 26743.331416714736, 26870.0, 26893.058742943904, 27274.590881136402, 45866.0, 46351.0, 46618.601441956795, 46907.76546117248, 47165.52834471611, 37595.0, 37744.0, 37774.05564860791, 38390.04024899288, 38579.228415430975, 36842.07894802544, 37357.6264237365, 37909.24181516299, 38124.61875381211, 38136.0, 36664.0, 37314.8134259327, 37472.62527374691, 37560.624393755716, 38387.424188636636, 33188.33623327471, 33544.1613959653, 33659.49510757389, 33884.66115338815, 33937.98186054422, 20644.0, 24289.878550607133, 24710.50578988374, 25298.36754081163, 25352.0], "value": 20126.889045908087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.51472240391854, 385.9884203473896, 391.8863529576423, 392.5819002762059, 407.43977031581574, 15143.545693629196, 20713.0, 22210.444738881535, 22524.21165259214, 22537.0, 20644.69032964507, 20712.378628641127, 21730.348089557297, 21734.89132554339, 21769.0, 4890.168671325883, 5475.589330800186, 5528.695921724304, 5685.914711279334, 5699.544036477072, 5554.805581804638, 5555.416681248471, 5672.914906276388, 5678.37537870836, 5684.801031963882, 5432.511074003339, 5575.219469274305, 5603.663780173182, 5611.326640803089, 5656.406077361879, 5573.5819813513945, 5582.7766889324375, 5624.381318055031, 5624.5781566382275, 5817.621854579439, 5299.0, 5454.754536045887, 5479.315085614298, 5482.314710661168, 5488.204210389511, 25360.87319563409, 25605.359866003313, 26305.473890522084, 26649.866750666122, 26767.330816729544, 2043.8773673579485, 2088.4152437317575, 2103.0851579562786, 2200.449887528118, 2674.2645772412548, 10228.130608898253, 10347.689569312932, 10431.895681043168, 13005.284709340982, 14437.422503099864, 1434.0, 1747.3185457671518, 1778.7865456145346, 1803.4769916724135, 1868.2807119259182, 131848.0, 132049.0, 132345.0, 133225.66774332325, 133564.3321783385, 9481.857772133424, 10251.794964100676, 10282.331648442827, 10442.68671939843, 10555.313904596176, 12471.189372690927, 12542.561010364594, 12617.87382126167, 12621.747565048647, 12631.494740210288, 8096.595170241464, 8280.668773249123, 8291.33669306454, 8397.202265784741, 8403.36974726898, 8572.485650860968, 8572.657093716181, 8587.871181932276, 8679.696210632597, 8688.348373871984, 3405.6594340565744, 4075.694322925733, 4077.510698716173, 4128.6284234418445, 4130.690198235145, 1947.9026048697508, 1995.8602897797305, 2003.519155402722, 2020.272701827341, 2021.8887961162277, 687.6046273392819, 897.3001059173855, 909.0591238068567, 931.3108299858056, 958.520739630185, 18912.0, 19184.424467266006, 19501.12244948981, 19518.21927122899, 19573.902130489256, 923.612082925173, 929.163752622645, 939.145377706284, 1007.3905287301224, 1049.490996866519, 9257.305702072372, 10134.949325253474, 10292.015315558554, 10472.842907356397, 10631.84052239217, 592273.0, 594309.0, 600938.0, 610552.0, 610996.0, 136900.0, 138155.0, 138853.0, 139702.0, 141731.0, 959.2733080455153, 971.9125278724948, 990.4799980010553, 992.9404235745734, 1027.019196667181, 14033.508827190999, 16116.549510544108, 17344.653106938043, 17548.12259387, 17662.558436039202, 50082.49917500778, 50329.49670503395, 53317.733411332694, 53461.73269133629, 54778.0, 74359.62820185865, 78399.0, 79455.20544794478, 82099.0, 82920.58539707263, 7141.0, 7487.775366739006, 7570.659320330488, 7768.572728499987, 8230.42387032902, 9030.0, 11154.665360039211, 12517.93741031289, 12817.423215955117, 12945.54690585825, 11024.559017639525, 11093.833592495957, 11136.721581960515, 11435.199536032398, 11512.36681982499, 9561.426314421156, 10513.421761802869, 10901.291416057931, 11218.775624487627, 11248.931351521593, 10715.261868437039, 10854.131669466582, 11161.218714689894, 11309.491072901903, 11685.065194784567, 8245.464044837066, 8315.085340612648, 8456.492610443147, 8524.70163544273, 8691.826163476822, 9084.772880678036, 10569.682909512727, 10617.416042117758, 11036.282641628268, 11610.129240306842], "value": 9388.994705549303, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.7655801173973, 1577.1641030253966, 1596.1939220693553, 1814.891106533608, 1950.746402967614, 7685.231476852312, 8446.282066024387, 8700.0, 8707.564621768917, 8839.955800221005, 8455.74632761018, 8684.478931264113, 8695.69565065223, 8701.869471957925, 8725.781855453617, 1968.4586738646844, 2082.458560774202, 2313.1325752842686, 2400.3759022654062, 2405.194259922928, 2409.915652952147, 2441.8023778599227, 2442.206282958046, 2445.217530390276, 2446.1683027770555, 2364.598018336887, 2384.1178763857397, 2404.0984630763464, 2408.5664580375524, 2409.132712223607, 1938.9917243033544, 2053.712480252766, 2311.2026350908995, 2331.8367714260007, 2372.3476044087843, 2315.3169814904645, 2396.269137912936, 2411.746766589518, 2416.5408572371193, 2417.3231495181376, 7515.849683006364, 8603.182697643713, 8612.52631105289, 8615.61229744663, 8635.093315201877, 5115.232715092766, 5141.280220769096, 5355.3305836770405, 5380.515753582196, 5390.110631745757, 3436.0, 3477.2523907359787, 3577.2487777566744, 3581.8746343877838, 3583.372909740785, 997.1623835977741, 1027.7944411117733, 1035.2132379391642, 1050.4117694091258, 1066.6693325069252, 11534.019608333301, 11581.42092895352, 11584.478698458506, 11586.768264634744, 11602.767944641144, 4720.457147428021, 4793.089313030513, 4870.975645121752, 4882.829100981448, 4896.828610998598, 9819.901800982041, 9958.352707074017, 9976.650817221362, 9979.900200997898, 10001.599936002624, 5400.756965936543, 5415.458454154632, 5449.04641687711, 5453.481919217668, 5453.972730136404, 4374.147041326911, 4547.886302842389, 4665.836695715701, 4802.663813533089, 4807.711537307703, 3912.9021774455296, 3985.8007099645483, 4030.234255491447, 4063.309237429644, 4107.466029416157, 1302.8892544133855, 1312.7046414556648, 1313.8029295605736, 1319.399673148715, 1327.4955516903708, 915.6566287642135, 929.5398777605129, 937.8171256604896, 938.1322276893845, 1011.1758916483043, 5925.614835035709, 5931.347551769302, 5988.251468566429, 6009.699515024231, 6122.816315510542, 957.363353370015, 976.2824324121765, 999.2555546118043, 1003.2225025605214, 1004.2217281606813, 3942.15243722601, 4008.6793056555407, 4035.051762835731, 4096.938545921814, 4106.404571337199, 9509.239260859114, 10615.52230149645, 10753.51609177589, 10772.515236814363, 10804.567817287378, 10561.841572376421, 10626.574937002433, 10654.467276636136, 10776.67669969902, 10797.380392940964, 6881.41507971828, 7654.349380302737, 7996.840063198703, 8264.132266111972, 8275.793105172419, 5966.343702192842, 6000.609960352563, 6093.542984276109, 6148.846278842886, 6271.341509141657, 9434.528273586431, 9711.27165462579, 9833.213342932691, 9990.800183996134, 9995.100440960348, 8705.651773928972, 9552.85365756086, 9664.71005869825, 10130.89869101329, 10300.124489418184, 6829.795106146824, 7068.469864760061, 7307.159676637255, 7523.285287897641, 7861.921380786119, 8023.759287221393, 8167.3466122711225, 8223.17768223173, 8268.627911743866, 8269.62786674589, 7075.823104422225, 7252.746153884589, 7370.189279179179, 7512.098548174254, 7515.549067055994, 7150.892736608851, 7162.820929476805, 7211.278872112747, 7231.891521627285, 7251.492395532262, 6927.653617319214, 6968.2927682718055, 7024.438044956493, 7027.789166325018, 7027.9297207027275, 4830.2513110467935, 4834.178189707688, 4981.750912454435, 5016.272640467185, 5178.559822415137, 4925.729084900365, 4926.531979461945, 4988.176950803077, 5031.371078615173, 5137.6660517066275], "value": 4584.240980548324, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.7953161147837, 316.03924070824695, 319.8145075856004, 321.55785794532505, 325.2632786738037, 5163.75376711131, 5234.55506281966, 5247.291615631893, 5249.71126588038, 5260.368755749316, 4347.978225117103, 4914.631402644807, 5135.614828887839, 5259.9474005259935, 5278.891432799107, 3170.9048728538182, 3214.710676039156, 3797.9810100949458, 3847.5179962134243, 3874.128321127752, 4001.659858911996, 4023.718339716221, 4029.823895096659, 4044.8179831907487, 4051.6150965658217, 3495.675375875053, 4003.418649633393, 4041.636252737253, 4053.7365071270274, 4092.0588264699136, 3795.0891785971444, 3881.4565960765517, 4068.5117785865737, 4074.9641517069676, 4083.979005248688, 3502.8949131526097, 3506.650544135435, 3528.6118526962014, 3548.8935331940083, 3558.1994051338243, 4241.427407300006, 4737.247218529132, 4859.902801943941, 4959.752012399365, 5039.269305950654, 3985.5217373915025, 4128.6284234419045, 4485.865424037252, 4629.606483448911, 4721.905561888743, 5143.794248230104, 5445.727713614304, 5475.753591088371, 5512.614117011811, 5609.49514543693, 3438.0, 3892.9416058759143, 3894.40329464918, 3921.6666583340166, 3924.332863413198, 5669.631473954221, 5784.334801497797, 5826.854328641775, 5867.0, 5916.9645312070215, 3488.3546543888956, 3611.9277614448088, 3727.9748069281163, 3952.3281042222893, 3973.264945985002, 4056.7363121397016, 4062.6343629073526, 4064.4309796628495, 4099.774512401847, 4109.958900411018, 3966.424868394067, 4132.367714752656, 4646.675697426217, 4691.155591993405, 4695.600873925755, 5054.317667114892, 5541.168824676331, 5592.5539196338195, 5628.634138780966, 5693.572982026281, 1203.5486692490317, 1275.355945247644, 1282.9037822163375, 1302.5571305756089, 1316.8024796280442, 852.5524099847505, 869.9086595907465, 903.512103464122, 918.1874041473211, 927.1470247372433, 4458.130664520388, 5100.719460429712, 5162.341394181999, 5201.557867581298, 5240.868978275574, 5299.231611416323, 5664.405237450009, 5714.628549144292, 5739.713014349266, 5741.224934633771, 4922.941567562991, 4959.380077490314, 5019.7239151847, 5155.123628983082, 5169.353830771153, 2444.4744379958393, 2481.16393868536, 2561.2956436979607, 2783.819051761629, 2867.3978464522684, 4807.0, 4867.634927380461, 5262.079136151158, 5301.95137795769, 5570.1592187984415, 5282.445343238905, 5318.563987723293, 5343.946560534423, 5372.62391632582, 5594.860128496739, 4777.713337199779, 5082.364704411949, 5392.595555333366, 5953.851153721105, 6119.663418512025, 5067.315912351785, 5822.417758224144, 5835.503982161564, 5837.3870743571315, 5861.853453663693, 4830.589399901048, 4989.600831933364, 5280.81517146898, 5691.4877661010705, 5726.255586773693, 5446.400895901527, 5532.585056120726, 5772.363411051178, 6052.788152414644, 6054.969725151346, 3094.706002929718, 3717.2565486902186, 3832.94250586247, 3921.0, 3932.8820135395986, 4715.622750180046, 5490.149026900836, 5707.372189059117, 5755.8561035974435, 5774.913376299275, 5207.479252074762, 5255.05716798594, 5473.0, 5549.694766787866, 5589.664620122806, 4647.883802904955, 4707.634785912047, 5285.4714528548375, 5443.564514838724, 5579.386267510489, 4921.655484116078, 5168.405633352213, 5405.918911216414, 5463.48096930791, 5500.22996780443, 4933.407991041098, 5039.5968322534845, 5425.403205647297, 5513.365962914316, 5647.519960803378, 4962.6774259673, 5085.059264036166, 5291.841244762663, 5564.916526252028, 5565.02612042899], "value": 4025.7203794440425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.402565613979, 647.8866198415276, 683.8290427393155, 737.7565403416872, 749.6214411722079, 14811.851881481181, 15474.690506189869, 15651.45219917303, 15679.45121920733, 15688.372465101396, 14451.349689264009, 14806.0, 14815.703685926275, 14827.777583336232, 14829.851701482981, 728.785008422514, 749.512816669164, 915.8168366326728, 917.8760867282898, 934.6121359635742, 895.4627223665781, 927.0729270729271, 928.6703220356777, 929.4005366538579, 929.4284015330591, 980.4803454169302, 981.2003217377815, 981.813455443467, 983.3608154699467, 984.7685793838442, 750.5721738608995, 935.8736570563022, 977.799551092028, 980.7548112971757, 981.79873126009, 724.7427163356986, 728.5483000539647, 729.3508777188307, 729.9671514781847, 730.4229658569724, 15321.6169595761, 15425.15161666108, 15426.0, 15440.073595584301, 15447.227638618137, 12416.317102559356, 12447.3776311185, 12644.810327845089, 12668.2399056056, 12835.614931552069, 9284.953575232148, 10815.891841081568, 10872.402017889013, 10898.455077246184, 11384.430778461045, 2834.4047749972324, 3438.862445502177, 3608.6030536640947, 3938.330483817758, 4314.417553630251, 89172.0, 90197.0, 90409.0, 90477.28568143032, 90608.09391906128, 9296.0, 9310.208632266198, 9425.104615061558, 9626.566804493814, 9666.0, 20486.692699609517, 20526.589468210554, 20534.58930821375, 20536.0, 20545.589088218152, 7133.3936615388275, 7179.92820071803, 7181.281871812777, 7232.746853860195, 7339.559626422431, 3846.9038274043373, 3851.7496362736333, 3942.073612701013, 4003.2794097062224, 4135.379693046007, 3461.7922924624604, 3531.9293614127573, 3555.5733312002726, 3558.857645694195, 3559.039059453933, 619.1672200889886, 626.192212046455, 632.4529282170889, 651.5764752910646, 654.9063064681898, 557.8047683310808, 558.7536413437858, 592.8814237152586, 600.504583718434, 603.2941458493573, 7576.962115189389, 7866.0, 7930.801729956682, 8057.959710201411, 8067.475614085066, 573.2032474859948, 575.2521721761776, 575.5654480866918, 592.5881512348876, 598.8772301678146, 3145.732612727944, 3655.8666813287964, 3684.1710615111388, 3823.369144091194, 3889.1638297766117, 94852.52573737087, 96598.0340196603, 97445.02554974501, 98182.5090874541, 98538.01461985432, 82242.76635850467, 82514.93712657111, 82995.17004829996, 83405.16594833974, 87662.0, 73059.0, 73748.0, 74304.14239644053, 74781.25218747852, 75831.86252206331, 6982.615956122462, 7253.746118885813, 8042.437029407885, 8159.0, 8336.791580210544, 37843.62156378401, 44066.779666101465, 45206.32190517211, 47707.0, 47816.5218347826, 50285.0, 61659.691701541204, 62203.377966219756, 65698.0, 66046.0, 11094.722631933942, 11125.388103654053, 11189.552417903193, 11419.429028548706, 11944.462499187384, 10673.879242679717, 11906.642800715992, 12131.696707582028, 12453.1905426147, 12895.419706113435, 10628.627998020032, 11317.717057073309, 11437.485313161094, 11663.475143618387, 12343.012558995079, 11057.46643876007, 11341.319520828776, 11396.886031139915, 11500.597479088192, 11538.192326537062, 9111.0, 11099.778004440028, 11232.4383780809, 11266.436678166223, 11438.485268163118, 7947.801304967422, 9265.166135047879, 9389.342746007715, 9501.857472137784, 9710.417374957526, 7386.926130738624, 8437.704680336159, 9710.660126895526, 9748.415095094317, 9827.852582211415], "value": 6875.162711432296, "unit": "score"}], "name": "dromaeo_css", "value": 7179.043927988073, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 118.0, 143.0, 198.0, 122.0, 141.0, 138.0, 121.0, 174.0, 113.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 184.0, 184.0, 184.0, 184.0, 179.0, 183.0, 184.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 63.0, 59.0, 146.0, 81.0, 70.0, 57.0, 59.0, 109.0, 57.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 149.0, 113.0, 119.0, 89.0, 103.0, 83.0, 80.0, 102.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 88.0, 83.0, 86.0, 83.0, 87.0, 83.0, 86.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 93.0, 91.0, 94.0, 106.0, 146.0, 93.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 85.0, 85.0, 84.0, 83.0, 84.0, 86.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 61.0, 60.0, 58.0, 60.0, 61.0, 121.0, 57.0, 57.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 76.0, 75.0, 75.0, 71.0, 68.0, 68.0, 68.0, 69.0, 68.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 83.0, 86.0, 86.0, 82.0, 86.0, 86.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 141.0, 141.0, 139.0, 139.0, 142.0, 142.0, 243.0, 128.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 176.0, 202.0, 166.0, 168.0, 170.0, 217.0, 221.0, 166.0, 222.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 82.0, 85.0, 89.0, 82.0, 70.0, 66.0, 65.0, 69.0], "value": 74.8, "unit": "score"}], "name": "kraken", "value": 1518.7, "alertThreshold": 2.0}]} 00:57:58 INFO - Return code: 0 00:57:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:57:58 ERROR - # TBPL SUCCESS # 00:57:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:57:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:57:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:57:58 INFO - 2016-05-10 00:57:58.985 screenresolution[1685:10650] starting screenresolution argv=screenresolution get 00:57:58 INFO - 2016-05-10 00:57:58.989 screenresolution[1685:10650] Display 0: 1600x1200x32@60 00:57:58 INFO - 2016-05-10 00:57:58.994 screenresolution[1686:10653] starting screenresolution argv=screenresolution list 00:57:58 INFO - Available Modes on Display 0 00:57:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:57:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:57:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:57:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:57:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:57:59 INFO - Intel Iris: 00:57:59 INFO - Chipset Model: Intel Iris 00:57:59 INFO - Type: GPU 00:57:59 INFO - Bus: Built-In 00:57:59 INFO - VRAM (Dynamic, Max): 1536 MB 00:57:59 INFO - Vendor: Intel (0x8086) 00:57:59 INFO - Device ID: 0x0a2e 00:57:59 INFO - Revision ID: 0x0009 00:57:59 INFO - Displays: 00:57:59 INFO - Display: 00:57:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:57:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:57:59 INFO - Display Serial Number: 1600x1200 60 00:57:59 INFO - Main Display: Yes 00:57:59 INFO - Mirror: Off 00:57:59 INFO - Online: Yes 00:57:59 INFO - Rotation: Supported 00:57:59 INFO - Return code: 0 00:57:59 INFO - Running post-action listener: _resource_record_post_action 00:57:59 INFO - ##### 00:57:59 INFO - ##### Finished run-tests step (success) 00:57:59 INFO - ##### 00:57:59 INFO - Running post-run listener: _resource_record_post_run 00:58:00 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32300544; Write bytes: 696026624; Read time: 325; Write time: 2166 00:58:00 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 386574848; Write bytes: 381462528; Read time: 28447; Write time: 809 00:58:00 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30539264; Write bytes: 295194112; Read time: 307; Write time: 1307 00:58:00 INFO - Running post-run listener: _upload_blobber_files 00:58:00 INFO - Blob upload gear active. 00:58:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:58:00 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 00:58:00 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'] 00:58:00 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 00:58:00 INFO - (blobuploader) - INFO - Open directory for files ... 00:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:58:01 INFO - (blobuploader) - INFO - Done attempting. 00:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:58:02 INFO - (blobuploader) - INFO - Done attempting. 00:58:02 INFO - (blobuploader) - INFO - Iteration through files over. 00:58:02 INFO - Return code: 0 00:58:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:58:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:58:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/276f726a1b84b73c4e01c3b862831348fe53fd7cae94affe1c4b721abe222f6ba1edb04b54f709a1b377f316e3550fe68e4d72d43bdf13b677a3cb201bcb25ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/61e7c3f15b4e1c6839220fc375e392f9c91e4fa64ec57b051e0b7ffc91183407eeab14997ad7f3221467a43400b98cf1f5b4fe6cbaac83c248257b084b699f38"} 00:58:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:58:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:58:02 INFO - Contents: 00:58:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/276f726a1b84b73c4e01c3b862831348fe53fd7cae94affe1c4b721abe222f6ba1edb04b54f709a1b377f316e3550fe68e4d72d43bdf13b677a3cb201bcb25ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/61e7c3f15b4e1c6839220fc375e392f9c91e4fa64ec57b051e0b7ffc91183407eeab14997ad7f3221467a43400b98cf1f5b4fe6cbaac83c248257b084b699f38"} 00:58:02 INFO - Running post-run listener: copy_logs_to_upload_dir 00:58:02 INFO - Copying logs to upload dir... 00:58:02 INFO - mkdir: /builds/slave/test/build/upload/logs 00:58:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.742100 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-10 00:58:02.807307) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 00:58:02.814990) ========= 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.1oo0Q1boJB/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.fxsE92OGc6/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/276f726a1b84b73c4e01c3b862831348fe53fd7cae94affe1c4b721abe222f6ba1edb04b54f709a1b377f316e3550fe68e4d72d43bdf13b677a3cb201bcb25ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/61e7c3f15b4e1c6839220fc375e392f9c91e4fa64ec57b051e0b7ffc91183407eeab14997ad7f3221467a43400b98cf1f5b4fe6cbaac83c248257b084b699f38"} build_url:https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011022 build_url: 'https://queue.taskcluster.net/v1/task/MUHnHFc1Tke8JCXHdQlQMQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/276f726a1b84b73c4e01c3b862831348fe53fd7cae94affe1c4b721abe222f6ba1edb04b54f709a1b377f316e3550fe68e4d72d43bdf13b677a3cb201bcb25ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/61e7c3f15b4e1c6839220fc375e392f9c91e4fa64ec57b051e0b7ffc91183407eeab14997ad7f3221467a43400b98cf1f5b4fe6cbaac83c248257b084b699f38"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 00:58:02.844928) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:58:02.845266) ========= 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.1oo0Q1boJB/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.fxsE92OGc6/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 00:58:02.893319) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 00:58:02.896665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 00:58:02.896995) ========= ========= Total master_lag: 0.23 =========