builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0052 starttime: 1464648644.29 results: success (0) buildid: 20160530120549 builduid: 7997d47d310b490c9d491deb39fe4327 revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.288166) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.288595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.288916) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.376726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.376999) ========= 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.WMJqH2re0I/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.GfBjB3czRE/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-30 15:50:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-05-30 15:50:44 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137690 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.535049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.535377) ========= 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.WMJqH2re0I/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.GfBjB3czRE/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.019929 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.587692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 15:50:44.588049) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WMJqH2re0I/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.GfBjB3czRE/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-30 15:50:44,661 truncating revision to first 12 chars 2016-05-30 15:50:44,661 Setting DEBUG logging. 2016-05-30 15:50:44,661 attempt 1/10 2016-05-30 15:50:44,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3c7a33fe0b64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 15:50:45,654 unpacking tar archive at: mozilla-aurora-3c7a33fe0b64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.265708 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 15:50:45.874317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.874620) ========= script_repo_revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.874997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.875263) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.890970) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 45 secs) (at 2016-05-30 15:50:45.891314) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WMJqH2re0I/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.GfBjB3czRE/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:50:46 INFO - MultiFileLogger online at 20160530 15:50:46 in /builds/slave/test 15:50:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:50:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:46 INFO - {'append_to_log': False, 15:50:46 INFO - 'base_work_dir': '/builds/slave/test', 15:50:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:50:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:46 INFO - 'branch': 'Mozilla-Aurora', 15:50:46 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:46 INFO - 'config_files': ('talos/mac_config.py',), 15:50:46 INFO - 'default_actions': ('clobber', 15:50:46 INFO - 'read-buildbot-config', 15:50:46 INFO - 'download-and-extract', 15:50:46 INFO - 'populate-webroot', 15:50:46 INFO - 'create-virtualenv', 15:50:46 INFO - 'install', 15:50:46 INFO - 'run-tests'), 15:50:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:46 INFO - 'download_minidump_stackwalk': True, 15:50:46 INFO - 'download_symbols': 'ondemand', 15:50:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:46 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:46 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:46 INFO - 'installer_path': 'installer.exe', 15:50:46 INFO - 'log_level': 'info', 15:50:46 INFO - 'log_name': 'talos', 15:50:46 INFO - 'log_to_console': True, 15:50:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:46 INFO - 'opt_config_files': (), 15:50:46 INFO - 'pip_index': False, 15:50:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'run_cmd_checks_enabled': True, 15:50:46 INFO - 'sps_profile': False, 15:50:46 INFO - 'sps_profile_interval': 0, 15:50:46 INFO - 'suite': 'dromaeojs', 15:50:46 INFO - 'system_bits': '32', 15:50:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:46 INFO - 'title': 't-yosemite-r7-0052', 15:50:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:46 INFO - 'use_talos_json': True, 15:50:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:46 INFO - 'work_dir': 'build'} 15:50:46 INFO - ##### 15:50:46 INFO - ##### Running clobber step. 15:50:46 INFO - ##### 15:50:46 INFO - Running pre-action listener: _resource_record_pre_action 15:50:46 INFO - Running main action method: clobber 15:50:46 INFO - rmtree: /builds/slave/test/build 15:50:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - ##### 15:50:47 INFO - ##### Running read-buildbot-config step. 15:50:47 INFO - ##### 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: read_buildbot_config 15:50:47 INFO - Using buildbot properties: 15:50:47 INFO - { 15:50:47 INFO - "project": "", 15:50:47 INFO - "product": "firefox", 15:50:47 INFO - "script_repo_revision": "production", 15:50:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:50:47 INFO - "repository": "", 15:50:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:50:47 INFO - "buildid": "20160530120549", 15:50:47 INFO - "pgo_build": "False", 15:50:47 INFO - "basedir": "/builds/slave/test", 15:50:47 INFO - "buildnumber": 336, 15:50:47 INFO - "slavename": "t-yosemite-r7-0052", 15:50:47 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:50:47 INFO - "platform": "yosemite_r7", 15:50:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:50:47 INFO - "branch": "mozilla-aurora", 15:50:47 INFO - "repo_path": "releases/mozilla-aurora", 15:50:47 INFO - "revision": "3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4", 15:50:47 INFO - "stage_platform": "macosx64", 15:50:47 INFO - "builduid": "7997d47d310b490c9d491deb39fe4327", 15:50:47 INFO - "slavebuilddir": "test" 15:50:47 INFO - } 15:50:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - ##### 15:50:47 INFO - ##### Running download-and-extract step. 15:50:47 INFO - ##### 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: download_and_extract 15:50:47 INFO - mkdir: /builds/slave/test/build/tests 15:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:47 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:50:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:51:13 INFO - Downloaded 1199 bytes. 15:51:13 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:51:13 INFO - Using the following test package requirements: 15:51:13 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:51:13 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:51:13 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:51:13 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'jsshell-mac.zip'], 15:51:13 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:51:13 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:51:13 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:51:13 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:51:13 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:51:13 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:13 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:51:13 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:13 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:51:43 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 15:51:43 INFO - retry: attempt #1 caught exception: timed out 15:51:43 INFO - retry: Failed, sleeping 30 seconds before retrying 15:52:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 15:52:23 INFO - Downloaded 18463801 bytes. 15:52:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:52:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:52:26 INFO - Return code: 0 15:52:26 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:26 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:52:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:52:28 INFO - Downloaded 18463801 bytes. 15:52:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:52:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:52:31 INFO - Return code: 0 15:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:31 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:52:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:52:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:52:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:52:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:52:32 INFO - Downloaded 10840145 bytes. 15:52:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:52:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:52:33 INFO - Return code: 0 15:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:33 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:52:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:52:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:52:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:52:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:52:43 INFO - Downloaded 114159654 bytes. 15:52:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:52:43 INFO - mkdir: /builds/slave/test/properties 15:52:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:52:43 INFO - Writing to file /builds/slave/test/properties/build_url 15:52:43 INFO - Contents: 15:52:43 INFO - build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:52:44 INFO - Running post-action listener: _resource_record_post_action 15:52:44 INFO - Running post-action listener: set_extra_try_arguments 15:52:44 INFO - ##### 15:52:44 INFO - ##### Running populate-webroot step. 15:52:44 INFO - ##### 15:52:44 INFO - Running pre-action listener: _resource_record_pre_action 15:52:44 INFO - Running main action method: populate_webroot 15:52:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:52:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:52:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:52:44 INFO - '--tppagecycles', 15:52:44 INFO - '7'], 15:52:44 INFO - 'tests': ['tsvgm']}}, 15:52:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:52:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:52:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:52:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:52:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:52:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:52:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['damp', 'tps']}, 15:52:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'tests': ['damp', 'tps']}, 15:52:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['dromaeo_dom']}, 15:52:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:52:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['a11yr', 15:52:44 INFO - 'ts_paint', 15:52:44 INFO - 'tpaint', 15:52:44 INFO - 'sessionrestore', 15:52:44 INFO - 'sessionrestore_no_auto_restore', 15:52:44 INFO - 'tabpaint']}, 15:52:44 INFO - 'other-e10s': {'tests': ['a11yr', 15:52:44 INFO - 'ts_paint', 15:52:44 INFO - 'tpaint', 15:52:44 INFO - 'sessionrestore', 15:52:44 INFO - 'sessionrestore_no_auto_restore', 15:52:44 INFO - 'tabpaint']}, 15:52:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:52:44 INFO - 'ts_paint', 15:52:44 INFO - 'tpaint', 15:52:44 INFO - 'sessionrestore', 15:52:44 INFO - 'sessionrestore_no_auto_restore', 15:52:44 INFO - 'tabpaint']}, 15:52:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:52:44 INFO - 'ts_paint', 15:52:44 INFO - 'tpaint', 15:52:44 INFO - 'sessionrestore', 15:52:44 INFO - 'sessionrestore_no_auto_restore', 15:52:44 INFO - 'tabpaint']}, 15:52:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['a11yr', 15:52:44 INFO - 'ts_paint', 15:52:44 INFO - 'tpaint', 15:52:44 INFO - 'sessionrestore', 15:52:44 INFO - 'sessionrestore_no_auto_restore', 15:52:44 INFO - 'tabpaint']}, 15:52:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['a11yr', 15:52:44 INFO - 'ts_paint', 15:52:44 INFO - 'tpaint', 15:52:44 INFO - 'sessionrestore', 15:52:44 INFO - 'sessionrestore_no_auto_restore', 15:52:44 INFO - 'tabpaint']}, 15:52:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['tsvgx', 15:52:44 INFO - 'tsvgr_opacity', 15:52:44 INFO - 'tart', 15:52:44 INFO - 'tscrollx', 15:52:44 INFO - 'cart']}, 15:52:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:52:44 INFO - 'tsvgr_opacity', 15:52:44 INFO - 'tart', 15:52:44 INFO - 'tscrollx', 15:52:44 INFO - 'cart']}, 15:52:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'talos_options': ['--disable-e10s'], 15:52:44 INFO - 'tests': ['tp5o']}, 15:52:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'tests': ['tp5o']}, 15:52:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'talos_options': ['--disable-e10s', 15:52:44 INFO - '--xperf_path', 15:52:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:52:44 INFO - 'tests': ['tp5n']}, 15:52:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:52:44 INFO - 'talos_options': ['--xperf_path', 15:52:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:52:44 INFO - 'tests': ['tp5n']}}, 15:52:44 INFO - 'talos.zip': {'path': '', 15:52:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:52:44 INFO - Running post-action listener: _resource_record_post_action 15:52:44 INFO - ##### 15:52:44 INFO - ##### Running create-virtualenv step. 15:52:44 INFO - ##### 15:52:44 INFO - Running pre-action listener: _resource_record_pre_action 15:52:44 INFO - Running main action method: create_virtualenv 15:52:44 INFO - Creating virtualenv /builds/slave/test/build/venv 15:52:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:52:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:52:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:52:44 INFO - Using real prefix '/tools/python27' 15:52:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:52:45 INFO - Installing distribute.............................................................................................................................................................................................done. 15:52:48 INFO - Installing pip.................done. 15:52:48 INFO - Return code: 0 15:52:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:48 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:52:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:48 INFO - 'HOME': '/Users/cltbld', 15:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:48 INFO - 'LOGNAME': 'cltbld', 15:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:48 INFO - 'MOZ_NO_REMOTE': '1', 15:52:48 INFO - 'NO_EM_RESTART': '1', 15:52:48 INFO - 'PAGER': '/bin/cat', 15:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:48 INFO - 'PWD': '/builds/slave/test', 15:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:48 INFO - 'SHELL': '/bin/bash', 15:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:52:48 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:48 INFO - 'USER': 'cltbld', 15:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:48 INFO - 'XPC_FLAGS': '0x0', 15:52:48 INFO - 'XPC_SERVICE_NAME': '0', 15:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:52:48 INFO - Cleaning up... 15:52:48 INFO - Return code: 0 15:52:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:48 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:52:48 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 15:52:48 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 15:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:48 INFO - 'HOME': '/Users/cltbld', 15:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:48 INFO - 'LOGNAME': 'cltbld', 15:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:48 INFO - 'MOZ_NO_REMOTE': '1', 15:52:48 INFO - 'NO_EM_RESTART': '1', 15:52:48 INFO - 'PAGER': '/bin/cat', 15:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:48 INFO - 'PWD': '/builds/slave/test', 15:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:48 INFO - 'SHELL': '/bin/bash', 15:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:52:48 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:48 INFO - 'USER': 'cltbld', 15:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:48 INFO - 'XPC_FLAGS': '0x0', 15:52:48 INFO - 'XPC_SERVICE_NAME': '0', 15:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:48 INFO - Downloading/unpacking psutil>=0.7.1 15:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:52:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:52:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:52:51 INFO - Installing collected packages: psutil 15:52:51 INFO - Running setup.py install for psutil 15:52:51 INFO - building 'psutil._psutil_osx' extension 15:52:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:52:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:52:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:52:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:52:52 INFO - building 'psutil._psutil_posix' extension 15:52:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:52:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:52:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:52:52 INFO - ^ 15:52:52 INFO - 1 warning generated. 15:52:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:52:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:52:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:52:52 INFO - Successfully installed psutil 15:52:52 INFO - Cleaning up... 15:52:52 INFO - Return code: 0 15:52:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:52 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:52:52 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 15:52:52 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 15:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:52 INFO - 'HOME': '/Users/cltbld', 15:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:52 INFO - 'LOGNAME': 'cltbld', 15:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:52 INFO - 'MOZ_NO_REMOTE': '1', 15:52:52 INFO - 'NO_EM_RESTART': '1', 15:52:52 INFO - 'PAGER': '/bin/cat', 15:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:52 INFO - 'PWD': '/builds/slave/test', 15:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:52 INFO - 'SHELL': '/bin/bash', 15:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:52:52 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:52 INFO - 'USER': 'cltbld', 15:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:52 INFO - 'XPC_FLAGS': '0x0', 15:52:52 INFO - 'XPC_SERVICE_NAME': '0', 15:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:52:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:52:55 INFO - Installing collected packages: mozsystemmonitor 15:52:55 INFO - Running setup.py install for mozsystemmonitor 15:52:55 INFO - Successfully installed mozsystemmonitor 15:52:55 INFO - Cleaning up... 15:52:55 INFO - Return code: 0 15:52:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:55 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:52:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:52:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:52:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:55 INFO - 'HOME': '/Users/cltbld', 15:52:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:55 INFO - 'LOGNAME': 'cltbld', 15:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:55 INFO - 'MOZ_NO_REMOTE': '1', 15:52:55 INFO - 'NO_EM_RESTART': '1', 15:52:55 INFO - 'PAGER': '/bin/cat', 15:52:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:55 INFO - 'PWD': '/builds/slave/test', 15:52:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:55 INFO - 'SHELL': '/bin/bash', 15:52:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:52:55 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:52:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:55 INFO - 'USER': 'cltbld', 15:52:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:55 INFO - 'XPC_FLAGS': '0x0', 15:52:55 INFO - 'XPC_SERVICE_NAME': '0', 15:52:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:55 INFO - Downloading/unpacking blobuploader==1.2.4 15:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:52:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:52:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:59 INFO - Downloading docopt-0.6.1.tar.gz 15:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:52:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:52:59 INFO - Running setup.py install for blobuploader 15:52:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:52:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:52:59 INFO - Running setup.py install for requests 15:53:00 INFO - Running setup.py install for docopt 15:53:00 INFO - Successfully installed blobuploader requests docopt 15:53:00 INFO - Cleaning up... 15:53:00 INFO - Return code: 0 15:53:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:00 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:00 INFO - 'HOME': '/Users/cltbld', 15:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:00 INFO - 'LOGNAME': 'cltbld', 15:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:00 INFO - 'MOZ_NO_REMOTE': '1', 15:53:00 INFO - 'NO_EM_RESTART': '1', 15:53:00 INFO - 'PAGER': '/bin/cat', 15:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:00 INFO - 'PWD': '/builds/slave/test', 15:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:00 INFO - 'SHELL': '/bin/bash', 15:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:53:00 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:00 INFO - 'USER': 'cltbld', 15:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:00 INFO - 'XPC_FLAGS': '0x0', 15:53:00 INFO - 'XPC_SERVICE_NAME': '0', 15:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:53:00 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-TAl596-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:53:00 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-y_nT8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:53:00 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-txTgjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:53:00 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-RXi26B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-P_6_ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-dVz3gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-YTkLpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-muL285-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-uEpPQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-rgIS6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-n13Fxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-7Y6z4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-6zZ_JY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:53:01 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-sEL58H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:02 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-2F9Jdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:53:02 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-tta0sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:53:02 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-GZXkY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:53:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:53:02 INFO - Running setup.py install for manifestparser 15:53:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:53:02 INFO - Running setup.py install for mozcrash 15:53:02 INFO - Running setup.py install for mozdebug 15:53:02 INFO - Running setup.py install for mozdevice 15:53:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Running setup.py install for mozfile 15:53:03 INFO - Running setup.py install for mozhttpd 15:53:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Running setup.py install for mozinfo 15:53:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Running setup.py install for mozInstall 15:53:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Running setup.py install for mozleak 15:53:03 INFO - Running setup.py install for mozlog 15:53:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:53:03 INFO - Running setup.py install for moznetwork 15:53:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:53:04 INFO - Running setup.py install for mozprocess 15:53:04 INFO - Running setup.py install for mozprofile 15:53:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:53:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:53:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:53:04 INFO - Running setup.py install for mozrunner 15:53:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:53:04 INFO - Running setup.py install for mozscreenshot 15:53:04 INFO - Running setup.py install for moztest 15:53:04 INFO - Running setup.py install for mozversion 15:53:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:53:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:53:04 INFO - Cleaning up... 15:53:05 INFO - Return code: 0 15:53:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53: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')]} 15:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53: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')]} 15:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:05 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:05 INFO - 'HOME': '/Users/cltbld', 15:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:05 INFO - 'LOGNAME': 'cltbld', 15:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:05 INFO - 'MOZ_NO_REMOTE': '1', 15:53:05 INFO - 'NO_EM_RESTART': '1', 15:53:05 INFO - 'PAGER': '/bin/cat', 15:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:05 INFO - 'PWD': '/builds/slave/test', 15:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:05 INFO - 'SHELL': '/bin/bash', 15:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:53:05 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:05 INFO - 'USER': 'cltbld', 15:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:05 INFO - 'XPC_FLAGS': '0x0', 15:53:05 INFO - 'XPC_SERVICE_NAME': '0', 15:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-hlXCpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-BxTAgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-jCTFEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-XKyQWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Be6ipZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-0Or4wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:53:05 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-dhLNoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-xPVstX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-JL4Pcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-2a3j1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:53:06 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)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-TgxN3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-XknpPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AL4tlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-npqCnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-aWIRuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-jxmyk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:53:06 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-UkuXgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:53:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:09 INFO - Downloading blessings-1.6.tar.gz 15:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:53:09 INFO - Installing collected packages: blessings 15:53:09 INFO - Running setup.py install for blessings 15:53:09 INFO - Successfully installed blessings 15:53:09 INFO - Cleaning up... 15:53:09 INFO - Return code: 0 15:53:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:53:10 INFO - Reading from file tmpfile_stdout 15:53:10 INFO - Current package versions: 15:53:10 INFO - blessings == 1.6 15:53:10 INFO - blobuploader == 1.2.4 15:53:10 INFO - docopt == 0.6.1 15:53:10 INFO - manifestparser == 1.1 15:53:10 INFO - mozInstall == 1.12 15:53:10 INFO - mozcrash == 0.17 15:53:10 INFO - mozdebug == 0.1 15:53:10 INFO - mozdevice == 0.48 15:53:10 INFO - mozfile == 1.2 15:53:10 INFO - mozhttpd == 0.7 15:53:10 INFO - mozinfo == 0.9 15:53:10 INFO - mozleak == 0.1 15:53:10 INFO - mozlog == 3.1 15:53:10 INFO - moznetwork == 0.27 15:53:10 INFO - mozprocess == 0.22 15:53:10 INFO - mozprofile == 0.28 15:53:10 INFO - mozrunner == 6.11 15:53:10 INFO - mozscreenshot == 0.1 15:53:10 INFO - mozsystemmonitor == 0.0 15:53:10 INFO - moztest == 0.7 15:53:10 INFO - mozversion == 1.4 15:53:10 INFO - psutil == 3.1.1 15:53:10 INFO - requests == 1.2.3 15:53:10 INFO - wsgiref == 0.1.2 15:53:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:10 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:10 INFO - 'HOME': '/Users/cltbld', 15:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:10 INFO - 'LOGNAME': 'cltbld', 15:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:10 INFO - 'MOZ_NO_REMOTE': '1', 15:53:10 INFO - 'NO_EM_RESTART': '1', 15:53:10 INFO - 'PAGER': '/bin/cat', 15:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:10 INFO - 'PWD': '/builds/slave/test', 15:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:10 INFO - 'SHELL': '/bin/bash', 15:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:53:10 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:10 INFO - 'USER': 'cltbld', 15:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:10 INFO - 'XPC_FLAGS': '0x0', 15:53:10 INFO - 'XPC_SERVICE_NAME': '0', 15:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:53:10 INFO - Cleaning up... 15:53:10 INFO - Return code: 0 15:53:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:10 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:10 INFO - 'HOME': '/Users/cltbld', 15:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:10 INFO - 'LOGNAME': 'cltbld', 15:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:10 INFO - 'MOZ_NO_REMOTE': '1', 15:53:10 INFO - 'NO_EM_RESTART': '1', 15:53:10 INFO - 'PAGER': '/bin/cat', 15:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:10 INFO - 'PWD': '/builds/slave/test', 15:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:10 INFO - 'SHELL': '/bin/bash', 15:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:53:10 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:10 INFO - 'USER': 'cltbld', 15:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:10 INFO - 'XPC_FLAGS': '0x0', 15:53:10 INFO - 'XPC_SERVICE_NAME': '0', 15:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:10 INFO - Downloading/unpacking jsonschema 15:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:53:13 INFO - Downloading/unpacking functools32 (from jsonschema) 15:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:13 INFO - Downloading functools32-3.2.3-2.tar.gz 15:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:53:13 INFO - warning: no files found matching '*.txt' 15:53:13 INFO - no previously-included directories found matching 'build' 15:53:13 INFO - no previously-included directories found matching 'dist' 15:53:13 INFO - no previously-included directories found matching '.git*' 15:53:13 INFO - Installing collected packages: jsonschema, functools32 15:53:13 INFO - Running setup.py install for functools32 15:53:13 INFO - warning: no files found matching '*.txt' 15:53:13 INFO - no previously-included directories found matching 'build' 15:53:13 INFO - no previously-included directories found matching 'dist' 15:53:13 INFO - no previously-included directories found matching '.git*' 15:53:13 INFO - Successfully installed jsonschema functools32 15:53:13 INFO - Cleaning up... 15:53:13 INFO - Return code: 0 15:53:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:13 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 0x10ffa4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffb4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f897a4a5db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, '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.WMJqH2re0I/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.GfBjB3czRE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:53:13 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 15:53:13 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 15:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:13 INFO - 'HOME': '/Users/cltbld', 15:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:13 INFO - 'LOGNAME': 'cltbld', 15:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:13 INFO - 'MOZ_NO_REMOTE': '1', 15:53:13 INFO - 'NO_EM_RESTART': '1', 15:53:13 INFO - 'PAGER': '/bin/cat', 15:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:13 INFO - 'PWD': '/builds/slave/test', 15:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:13 INFO - 'SHELL': '/bin/bash', 15:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:53:13 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:13 INFO - 'USER': 'cltbld', 15:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:13 INFO - 'XPC_FLAGS': '0x0', 15:53:13 INFO - 'XPC_SERVICE_NAME': '0', 15:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:14 INFO - Downloading/unpacking flake8 15:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:53:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:53:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53: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 15:53:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:53:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:53:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:53:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:53:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:53:17 INFO - no previously-included directories found matching 'docs/_build' 15:53:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:53:17 INFO - Running setup.py install for pep8 15:53:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:53:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:53:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:53:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:53:17 INFO - no previously-included directories found matching 'docs/_build' 15:53:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:53:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:53:18 INFO - Cleaning up... 15:53:18 INFO - Return code: 0 15:53:18 INFO - Running post-action listener: _resource_record_post_action 15:53:18 INFO - Running post-action listener: _start_resource_monitoring 15:53:18 INFO - Starting resource monitoring. 15:53:18 INFO - ##### 15:53:18 INFO - ##### Running install step. 15:53:18 INFO - ##### 15:53:18 INFO - Running pre-action listener: _resource_record_pre_action 15:53:18 INFO - Running main action method: install 15:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:53:18 INFO - Reading from file tmpfile_stdout 15:53:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:53:18 INFO - Reading from file tmpfile_stdout 15:53:18 INFO - Output received: 15:53:18 INFO - Usage: mozinstall [options] installer 15:53:18 INFO - Options: 15:53:18 INFO - -h, --help show this help message and exit 15:53:18 INFO - -d DEST, --destination=DEST 15:53:18 INFO - Directory to install application into. [default: 15:53:18 INFO - "/builds/slave/test"] 15:53:18 INFO - --app=APP Application being installed. [default: firefox] 15:53:18 INFO - mkdir: /builds/slave/test/build/application 15:53:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:53:49 INFO - Reading from file tmpfile_stdout 15:53:49 INFO - Output received: 15:53:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:53:49 INFO - Running post-action listener: _resource_record_post_action 15:53:49 INFO - ##### 15:53:49 INFO - ##### Running run-tests step. 15:53:49 INFO - ##### 15:53:49 INFO - Running pre-action listener: _resource_record_pre_action 15:53:49 INFO - Running main action method: run_tests 15:53:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:53:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:53:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:53:49 INFO - 2016-05-30 15:53:49.880 screenresolution[1988:10603] starting screenresolution argv=screenresolution get 15:53:49 INFO - 2016-05-30 15:53:49.883 screenresolution[1988:10603] Display 0: 1600x1200x32@60 15:53:49 INFO - 2016-05-30 15:53:49.889 screenresolution[1989:10605] starting screenresolution argv=screenresolution list 15:53:49 INFO - Available Modes on Display 0 15:53:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:53:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:53:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:53:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:53:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:53:50 INFO - Intel Iris: 15:53:50 INFO - Chipset Model: Intel Iris 15:53:50 INFO - Type: GPU 15:53:50 INFO - Bus: Built-In 15:53:50 INFO - VRAM (Dynamic, Max): 1536 MB 15:53:50 INFO - Vendor: Intel (0x8086) 15:53:50 INFO - Device ID: 0x0a2e 15:53:50 INFO - Revision ID: 0x0009 15:53:50 INFO - Displays: 15:53:50 INFO - Display: 15:53:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:53:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:53:50 INFO - Display Serial Number: 1600x1200 60 15:53:50 INFO - Main Display: Yes 15:53:50 INFO - Mirror: Off 15:53:50 INFO - Online: Yes 15:53:50 INFO - Rotation: Supported 15:53:50 INFO - Return code: 0 15:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:53:50 INFO - Python 2.7.3 15:53:50 INFO - Return code: 0 15:53:50 INFO - grabbing minidump binary from tooltool 15:53: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')]} 15:53:50 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 0x10fe43e00>, '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 0x10fcacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:53:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:53:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:53:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:53:50 INFO - Return code: 0 15:53:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:53:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:53:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:53:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:53:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:53:51 INFO - Return code: 0 15:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WMJqH2re0I/Render', 15:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:51 INFO - 'HOME': '/Users/cltbld', 15:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:51 INFO - 'LOGNAME': 'cltbld', 15:53:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:53:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:51 INFO - 'MOZ_NO_REMOTE': '1', 15:53:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:53:51 INFO - 'NO_EM_RESTART': '1', 15:53:51 INFO - 'PAGER': '/bin/cat', 15:53:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:51 INFO - 'PWD': '/builds/slave/test', 15:53:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:51 INFO - 'SHELL': '/bin/bash', 15:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GfBjB3czRE/Listeners', 15:53:51 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 15:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:51 INFO - 'USER': 'cltbld', 15:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:51 INFO - 'XPC_FLAGS': '0x0', 15:53:51 INFO - 'XPC_SERVICE_NAME': '0', 15:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:53:51 INFO - mozversion application_buildid: 20160530120549 15:53:51 INFO - mozversion application_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:53:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:53:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:53:51 INFO - mozversion application_name: Firefox 15:53:51 INFO - mozversion application_remotingname: firefox-dev 15:53:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:53:51 INFO - mozversion application_vendor: Mozilla 15:53:51 INFO - mozversion application_version: 48.0a2 15:53:51 INFO - mozversion platform_buildid: 20160530120549 15:53:51 INFO - mozversion platform_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:53:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:53:51 INFO - mozversion platform_version: 48.0a2 15:53:51 INFO - using testdate: 1464648831 15:53:51 INFO - actual date: 1464648831 15:53:51 INFO - starting webserver on 'localhost:49395' 15:53:51 INFO - SUITE-START | Running 2 tests 15:53:51 INFO - TEST-START | dromaeo_css 15:53:51 INFO - operating with platform_type : mac_ 15:53:51 INFO - Initialising browser for dromaeo_css test... 15:53:51 INFO - TEST-INFO | started process 2007 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpImtHy6/profile http://localhost:49395/getInfo.html) 15:53:53 INFO - PROCESS | 2007 | __metrics Screen width/height:1600/1200 15:53:53 INFO - PROCESS | 2007 | colorDepth:24 15:53:53 INFO - PROCESS | 2007 | Browser inner width/height: 1024/689 15:53:53 INFO - PROCESS | 2007 | __metrics 15:53:53 INFO - PROCESS | 2007 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:53:53 ERROR - PROCESS | 2007 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:53:54 INFO - TEST-INFO | 2007: exit 0 15:53:54 INFO - Browser initialized. 15:53:54 INFO - Running cycle 1/1 for dromaeo_css test... 15:53:54 INFO - TEST-INFO | started process 2009 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpImtHy6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:54:06 INFO - PROCESS | 2009 | RSS: Main: 211415040 15:54:06 INFO - PROCESS | 2009 | 15:54:57 INFO - PROCESS | 2009 | 1464648897843 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:57:08 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49395/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49395/tests/dromaeo/cssquery-ext.html) 15:57:10 INFO - PROCESS | 2009 | RSS: Main: 391696384 15:57:10 INFO - PROCESS | 2009 | 16:00:13 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49395/tests/dromaeo/cssquery-ext.html (next: http://localhost:49395/tests/dromaeo/cssquery-jquery.html) 16:00:13 INFO - PROCESS | 2009 | RSS: Main: 357638144 16:00:13 INFO - PROCESS | 2009 | 16:03:15 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49395/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49395/tests/dromaeo/cssquery-mootools.html) 16:03:16 INFO - PROCESS | 2009 | RSS: Main: 359419904 16:03:16 INFO - PROCESS | 2009 | 16:06:18 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49395/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49395/tests/dromaeo/cssquery-prototype.html) 16:06:19 INFO - PROCESS | 2009 | RSS: Main: 359931904 16:06:19 INFO - PROCESS | 2009 | 16:09:22 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49395/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49395/tests/dromaeo/cssquery-yui.html) 16:09:23 INFO - PROCESS | 2009 | RSS: Main: 333369344 16:09:23 INFO - PROCESS | 2009 | 16:12:25 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49395/tests/dromaeo/cssquery-yui.html (next: http://localhost:49395/tests/dromaeo/cssquery-dojo.html) 16:12:26 INFO - PROCESS | 2009 | RSS: Main: 367853568 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | __start_tp_report 16:12:26 INFO - PROCESS | 2009 | _x_x_mozilla_page_load 16:12:26 INFO - PROCESS | 2009 | _x_x_mozilla_page_load_details 16:12:26 INFO - PROCESS | 2009 | |i|pagename|runs| 16:12:26 INFO - PROCESS | 2009 | |0;dojo.html;3372.0558243691758;3416.6241713411505;3434.3989801784683;3461.1866211440324;3496.6852983231506;5680.0627896397145;5680.659160450371;5687.715614219288;5695.800646977362;5710.828675139742;4854.393200849894;5598.824246908144;5693.202951586781;5727.914081288784;5743.282089738783;5070.923936140961;5136.512031357015;5205.401378841444;5259.421463638997;5303.734813259341;5214.478552144793;5220.504052115043;5220.738963051851;5227.973860130694;5233.9738301308635;4277.465316835395;5174.974125129369;5216;5231.86920326993;5235.738213089349;5206.219067139921;5236.083685355085;5244.344456942882;5264.42091369949;5317.175837745156;5112.156494178456;5130;5139.691618502902;5173.611979101544;5190.4031036430915;4621.399218101659;5483.862903427406;5502.752376143083;5539.224508568804;5542.8337149885165;6226.40849119333;6232.626042437423;6244.5316601254635;6246.843828904268;6261.46777523911;5647.887042259173;6259.186305780265;6269.122322874756;6278.183836101323;6325;5846.619969701956;5853.707314634251;5869.148973398833;5883.529317654577;5909.438603332676;6310.0850376695125;6313.652749098844;6317.084022816666;6325.557210995278;6328.050792381179;5931.911021334683;6028.728707208186;6097.786577469856;6198.783042593578;6228.844278893064;6265.96867015662;6267.811965641038;6293;6293.937060629335;6305.369463053749;6126.785562505291;6195.535334849904;6205.565610407228;6214.937850621436;6219.689015549205;5247.396549396868;5915.940840591625;6139.386061393914;6234.875302494015;6243.43809057187;4848.442429120626;4863.221884498464;4883.975580122076;4887.120318342661;4894.363732714724;4403.53762854902;4427.579379958899;4428.937055106751;4453.376527286183;4499.662525310615;5078.314427552307;5482.725863706799;5650.886982260414;5669.6314739541795;5670.262865827416;6237.656928868865;6310.1481300024825;6313.494920406357;6322.146510221153;6322.589031712924;5553.250311208016;5640.5487560995025;5648.152777083388;5692.772289108472;5731.484166425042;5288.101022826128;5424.918626220609;5426.131818908955;5441.918371224435;5460.180972854024;5564.582656300794;6330.68346582669;6365.936340636627;6373.139626150411;6373.553851230369;5320.9467905322;6151.800398922346;6240.906386404116;6313.210848643919;6324.30432652417;6317.241930968313;6362.427381535684;6370.012648039561;6371.362863713592;6377.681115944091;6209.0996805462055;6227.439530442282;6232.5325600581045;6234.15838861766;6239.376062393724;5856.677882716492;5960.8807823844145;6116.26604807426;6310.842228944313;6316.905246421215;6089;6120.632762034292;6130.080487926846;6144.754209831557;6149.262088549223;6038.75844966214;6078.72645730952;6118.143459915526;6120.265568131853;6139.416755408229;5787.508061814793;5846.619969701956;5863.061910094364;5864.560157988253;5868.618539794899;5974.701264936823;5989.760409583568;6082.239720034996;6155.753769849156;6164.845878853064;5926.407359264039;5927.792527261525;5930.644161350333;5955.047192449188;5955.136505206808 16:12:26 INFO - PROCESS | 2009 | |1;ext.html;513.6635503745046;514.7992283009626;524.6799452334077;525.9447757985412;593.1132956230437;25226.2432127036;31450.842745786296;37327.44008839866;38053;38191.85424437271;41061.794691026575;41600;41669.58330416695;41752.95617609561;42005.7899710501;8965.596548155316;9093.727188184364;9459.29055320852;9680.661176858812;9695.563699633498;7793.766187014398;8543.231109200171;8730.563471826383;8732.563371831415;11347.943260283688;11451.312921224711;11501.827472587918;11510.942445287803;11518.596849110283;11567.53729850805;11415.885841141566;11473.311601303865;11482.598109066226;11499.712507187345;11506.424678766029;8502.74491765248;8721.171488708562;8722;9465.574049167804;11278.492467838967;37997.810010950045;38432.231355372736;38432.807835960644;38522.80738596317;38596.80701596474;3181.236503239206;3210.6949839765184;3493.510908472816;3611.3499570077374;3829.1767270037076;27040.323991900164;27106.1868143954;27148.728512714817;27293;27630.44739105227;2243.4391402149463;2411.903523859061;2588.4305452800354;2750.1337078820306;2765.1566272286946;466865;467736.3226367727;468621;468689;469232;58725;59033.5241618963;59129;59449;59512.70243648754;64718.67640661766;64913;64924;64933.35066649367;65078.34921650818;16974.745378819327;17421.390251341396;17442.91278543599;17444;17452.95282283067;13350.399232034388;13376.331183440983;13391.26348050847;13395.062345635712;13459.798103028463;14578.781318280235;16266.349346026263;16546.33814647425;16589.66820663604;16642.16789160537;5281.577473802086;6145.170401995762;6290.874182516324;6300;6334.651594162273;917.179124683418;940.948247846375;1049.5644307612408;1075.3601607043797;1112.4271000434783;27989.44021119607;29422.705772941998;29820.10539683813;30996.22509437282;31244.687553124633;764.5833021003456;1079.2768844873954;1083.1876092930302;1095.9616413425492;1153.4521102476256;11952.701182470508;13857.099288546213;14248.073875198079;14276.92923030777;14459.421623134956;609733;614434;614465;614596;616622;47821.76089119532;57930.420695792505;58245;58300;58325.70837145787;1343.4626149540263;1467.2590341877496;1497.7229212595705;1499.9100053996794;1502.233860731035;28157.43685126327;28574.57138142888;28969.275768105967;29012.854935725187;29232;46586;46964;47009.529904701885;47031.76484117557;47040.529594703614;370113;372211;372460;372912;605235;25541.616875746506;26072.217833464267;26262.86868565645;26353.20940371792;26373.868130659223;41305.1738965225;45742;46636.53363466322;47248.763756181;48021.75989120032;34538.61845526151;35849.82075089608;36033.639663603026;36114.4582831263;36704.449433259055;36240.27519449649;36396.454053188696;37003.62996370002;37060.25879482449;37190.44214336733;35916.92249232527;36556.45165322469;37072.78363298202;37362.62637373591;37447.81276093602;31420.601115844256;31946.68053319437;31991;32119.51820722738;32306;24612.753872461046;24643.507129857662;25043.373915651526;25088.99644014219;25314.240572782845 16:12:26 INFO - PROCESS | 2009 | |2;jquery.html;399.2812936713915;403.95354534228557;405.4911086586334;405.67545963229406;412.23530351198525;12853.421596028178;14853.851461485381;16155.757663635055;20122.69815952762;20879.060442280097;16080.83919160808;16404.343826246964;18082.457526274233;20387.796122038777;20875.791242087613;5599.888002239953;5838.620489668158;5858.707064646772;5860.677662728548;5861.618994765348;3849.40334248192;4037.1723796621623;5602.1596760485845;5890.852728681774;5892.351841297454;4032.7580345179235;4033.5361433435237;4034.213328400963;5629.915551266734;5938.613990090652;5894.705264736767;5897.351291357947;5906.291245050578;5907.350191478956;6400.4559612432995;5613.887722245532;5742.483176514133;5769.163471296681;5772.451617096369;5776.6534007959235;13154.079214454992;13159.736805263938;13167.539136130285;15596.766048509282;23959.88020059906;2145.324222869791;2169.2949791317883;2204;2297.5979203639295;2299.4016761059897;9789.951050244774;9911.256655750847;9956.551955161962;12331.5067397304;14554.417823287056;1526.6412393087614;1673.9497815065445;1703.7529558214114;1727.894147745444;1736.4825530771586;106368;108245.37631935527;109412;111707;115393;9139.588718507683;9355.672551460666;11447.370394628375;11576.536938522533;11807.52769889212;14952.700945981236;15014.324355404033;15028;15051.698966020618;15053.548393548212;8474.872876906851;8871;8906.643734250687;9007.504587247764;9073.13805188506;7426.145993210742;8172.223638754309;8292.88046113779;9079.773005674911;9098.545072746336;4623.745693986863;4638.930416043762;4666.626669866402;4670;4676.111538807616;1681.4619321816901;1965.9705104423444;1972.960540789205;1981.4253866378876;1981.603679264153;724.8151721311137;769.7883082152341;785.8349746553288;952.7141857442878;983.5868935047297;18965.24139034451;19963.700544491843;19965.301214457424;19980;20101.99490025493;822.3174764945081;913.921572544404;957.5308099031564;958.7315551645551;974.3617930255693;8537.701180458655;10642.308249963728;10814.35113893169;10899.618513351994;11155.219134660658;506859;581001;586320;588508;591237;119125;121823;123901.70737194746;126545.36727316305;127015.45969080752;993.9254555908336;1003.9447830369254;1013.6806905824642;1013.9797204055877;1016.0246163682799;17915.462536123938;17974;18048.007359594823;18087.547811304295;18709.43871683852;52521.47478525166;52627.73686131545;52663.736681316346;52745.94508109893;55137;75016;75905;77139;78347;78647.03382415256;7944;7981.64082616272;8003;8365.498070115813;8367.62345694433;9712.854307185256;11698.883011169779;12250.611130829402;12412.317322547347;12770.936145319214;8892.377533572846;11062.446877655924;11264;11437.59968401102;11448.885511144781;7327.74352897646;7613.390928725799;10766.676999690022;11048.834267486154;11255.437228138724;11214.102871770076;11468.4265786712;11765.823512647146;11783.823242651539;11812.409379530816;8178.67285308581;8219.630116644885;8265.504069755594;8547.102554231717;8608.78478038054;10785.029347358775;11124.666260012213;11264.38045907483;11382.772344552897;11471.885281147082 16:12:26 INFO - PROCESS | 2009 | |3;mootools.html;1419.1626940105336;1550.6278493161642;1571.3557441449009;1791.3551121596222;1939.5635981904065;7786.688532458702;8434.451760635562;8602.61288242029;8616.224539791418;8656.047834738189;7334.706611735524;8360.205780450862;8565.972083350007;8647.221750042494;8652.178043085896;2149.892505374733;2368.348704106376;2431.7081950166007;2437.183543512925;2462.655228268044;2458.5820410530255;2463.4457247119344;2464.667269918556;2464.938376540583;2466.6053431450973;2000.249906285143;2373.5239524505346;2439.2804122783823;2442.206282958037;2447.596146635803;2146.420466474043;2374.631932050535;2422.5760491913934;2431.6474111253947;2432.8418652787514;2411.360989337827;2445.217530390276;2451.460678650694;2453.2394957563033;2457.619069044301;8644.394892357537;8720.258778003878;8722.12778722129;8744.344174186897;8765.94808622963;4229.873103806891;5317.335333083364;5362.115250983583;5370.489803468664;5371.570274378079;3398.5921689397182;3414.709749671281;3427.7086447651977;3447;3465.42820434628;1062.9043386095211;1617.7735117083619;1634.4197809777477;1656.0891509669634;1676.4132553606262;9618.615255389775;11456.828147577793;11664.475098620582;11667.474963626657;11695.35675537845;4503.774811259424;4548.840790572314;4588.541145885385;4641.396618439649;4678.2982552616695;9297.116773906468;9298.349115561981;9303.348765586345;9318.487483188354;9321.487318197429;5274.7626356814035;5298.231756395301;5302.310699609026;5311.389190243094;5313.734313284321;4245.957540424618;4383.320585309282;4688.507706690816;4814.229723244264;4824.059308434892;3845.423186521988;3863.208042351311;3914.3149948758855;3949.111449923744;3980;1192.5110704611068;1196.9640910772691;1199.2264989082169;1207.0404028796952;1208.546794951893;844.5988155626152;884.1026358246385;909.3134683314188;913.5249670171473;926.3839546701503;5753.223314852525;5819.679917604488;5889.764409423661;5966.582339236212;6139.754409823557;455.02397357667616;458.8118871262833;461.3333732756202;469.572688853142;473.4366104335829;3849.538055433366;3907.882763517099;3924.234774219057;4007.238624661305;4011.939820902689;10361.119304859172;10729;10738.73153171177;10840.319750438695;10870.239083264254;10564.788704225872;10591;10685;10697.946510267398;10717.981791729773;8175.427720059658;8178.83642327162;8215.137410571924;8321.292690121407;8334.333253339719;5781.4507621775865;5830.2420685310635;5855.414458554111;6000.159977603226;6000.339962604023;8777.990531089006;8956.68651597191;9327.813443731224;9427.528623568931;9705.951470242604;9153.313501487282;9536.237101031762;9693.127618514367;9707.417554946725;9862.753431164278;6626.237982632059;6809.421199198124;7278.963605182152;7452.590107544137;7538.849223015619;8242.876356854771;8246;8293.460925039853;8301.46040507365;8321.875171872547;7297.63511824396;7391.593462359622;7401.444891633257;7541.773746787605;7541.962290188733;7252.456065795192;7254.891176632249;7276.927230727625;7311.122665279988;7419.369353605004;6863.347981941907;7012.964935175291;7019.333163349474;7026.262242464672;7032.331928466788;4818.807247710052;4824.831130910401;4838.491958344323;4949.802007919643;4995.225740010304;4344.326629372453;4895.4370247422;4896.192128298933;4953.578945789648;5012.398512178562 16:12:26 INFO - PROCESS | 2009 | |4;prototype.html;318.3934604578278;323.345225917517;327.98688052477894;329.41528786404143;332.4630721384964;6581.6051036937815;6602.405783479492;6610.9669451652735;6625.17185351831;6637.203535575725;5312.760925758341;5827.796027139051;6543.0839682444375;6626.602403855772;6633.635150066744;3414.8121853298057;4765.21373973294;4775.283707443894;4874.098291816008;4891.755412229374;4876.341693871317;4929.014197160564;4933.481984391641;4949.208126699728;4955.355803745508;4900.387451568554;4909.729964851932;4910.680805747615;4922.630802689795;4928.162212423897;4627.629789616823;4684.672072954878;4812.855614331575;4834.516548345173;4837.250226214942;4097.262492751303;4108.589141085898;4125.793710314472;4130.421740956269;4134.090500089976;5488.341399032141;6267.1539342189135;6337.746490140389;6369.044643303495;6395.168628078367;4883.926741098886;5734.713264336809;5748.19525266463;5755.68343741094;5763.106718458645;6835.111435513401;7062.858742825115;7073.540219885743;7075;7085.82285442868;4681.836135735267;4721.622270218374;4733.526647335273;4746.952530474686;4754.263089221176;7416.443766717463;7454.1055073391;7480.0649918760155;7486.213947535485;7492.063492063492;4780.211265141213;4797.880052998633;4808.2787581863;4811.398575178103;4813.291281594983;4870.561649451495;4873.463918968911;4877.292792545132;4877.487863774323;4880.487548807395;5788.392218816964;5789.565782566325;5789.739461724233;5791.102379131242;5805.983952808326;6804.557703749339;7043.436525077981;7045.048918395951;7084.397826184729;7119;1367.3368416318078;1594.936202551908;1632.1675945267998;1635.5747505648378;1637.3614290426742;972.8832540095092;973.8782652168479;1016.9440680762481;1020.2297265564451;1042.5464922758624;6529.836754081186;6636.933630663728;6644.8671026579195;6684.799456016327;6760.391564759196;7451.552906825608;7455.589942553103;7462.820820340627;7466.813329666802;7467.402607791364;6614.470842332602;6619.569727967763;6622.9006564901565;6628.304028077079;6630.270670226271;3793.7534060285993;3886.145048089416;3931.272714547818;3940.5074365704286;3943.5464921533817;7731.342835858903;7763;7763.92236077632;7768.495047821986;7820.960895195602;7567.962160189164;7643.082830060317;7643.273888980538;7662.5785581792425;7663.27198916102;7744.574048427278;7766.417518686008;7778.7666370007855;7789.221077892279;7814.452988290879;7156.284371562802;7379.963100184465;7382.889256661262;7401.703931842666;7412.777616671508;7258.782236532912;7280.381167600814;7306.671199796128;7319.560826350436;7346.449016323691;7819.47520233549;7823.452358334862;7836.02049743782;7844.607769611611;7845.411594130349;4640.512746161605;4686.882827929329;4689.296605509065;4695.272232803921;4703.811847526061;7213.531120477152;7425.108986921388;7454.4781865268915;7457.7389791357045;7467.887981680387;7122.60825654594;7183.497155199295;7190.568565886064;7194.280571942764;7205.603691797001;7191.964040179975;7195.964020179866;7201.567905925661;7207.027051348001;7210.819729506804;7018.368346848808;7018.473614478832;7021.473389495911;7028.0863487746265;7033.085698859116;6869.519133660596;7097.929020709933;7113.502054856111;7205.171405288459;7232.023676803564;6708.396244338033;6805.387515123464;6899.137607799025;6915.792526224222;6949.583025018515 16:12:26 INFO - PROCESS | 2009 | |5;yui.html;636.894912339464;675.9121314229147;742.8440027594205;747.5850902748972;762.4967521435857;14823.332950017248;14957;14978.176200308992;15000.249987500609;15061.246937653115;13532.84584271453;13695.041347105707;13860.861391386083;14008.439662413492;14018.088824226417;716.57721944053;884.7036242858634;893.6872094766812;902.3457992955088;905.5381755304809;729.3836707981774;876.2289185516768;892.3441270666056;893.8569828827389;894.5259012723267;930.5486838883136;933.7758937854934;934.1172591900656;936.241644268145;943.627267229444;746.6751962896158;929.4655573045526;933.0482907434413;935.5789894547481;942.8208640358345;589.6904125334191;695.0894328429773;696.435886931587;700.2192555300854;701.4949236549676;14951.700965980619;15173.468928587446;15181.316840742193;15196.088234705952;15201.847981520154;12601.810972835416;12622.242665440102;12782;12853.228806271654;12881.806772898413;8102.635381407851;9997.400155990665;10087.646932357333;10131.848022279672;10200.94899525505;3429.5713035870517;3471.0801637566065;3492.1793378556017;3506.2286297014616;3574.696150827183;76185.61907190485;76931.8460223097;77026.84459733109;77990;78194.60902695508;9303.58133883977;9334.579943902541;9448;9454.763630909145;9492.857607135898;21003.78996210018;21160.894195529232;21161.941902904793;21175;21206.78793212079;7347.88978165328;7386.889196662054;7456.32893039629;7572.734954276684;7584.355329797029;3976.1451287972645;3978.3236849735613;3984.2828290907337;4045.110075783323;4065.918681626351;3012.472817256971;3600.1539638185004;3649.1424515238896;3653.8173091345325;3654.4518322251874;588.0796553393925;679.9218089919624;687.2337343861558;706.0750416953807;711.7366574367413;456.5776656592705;486.79068000760014;592.2744637818638;618.3198481670127;625.8435391152212;7738.806529836799;7764.883526747103;8186.859855108669;8203.343732501386;8218.876716849252;588.635046271306;589.5018709190726;591.3022633292755;598.912972954087;604.9667268300286;3621.583517895423;3875.8255878485534;3876.8836934891997;3891.5135608048995;3903.1413089120347;82953.58523207346;83066;83234.58382708048;84575;87458.56270718733;64413.71172576522;64530.386740331865;67407.52960702285;68694.9695754564;70646;51370;65911.01133483002;66228.3442913931;67015.32984670189;67339.66330168415;7465.477416580788;7585.658645360862;7690.384769218337;7728.690852366068;7822.13956464777;46808;48200.5179948196;48372.5162748368;48621.5137848617;49431.75284123556;62419.6879015602;64396.678016609614;65055.674721627984;65232.673836630514;67557.66221168863;9708.805823883624;9736;9885.604575816888;9985.350952188084;10035.749106272402;10554.472276386303;10621.681349559525;10631.362118272928;11013.559457621606;11022.063124634175;10281.948590257001;10456.058954694114;10456.424896630759;10516.737081573021;10537.789244215226;9998.750031249278;10196.3372380795;10301.536430860779;10584.682459526226;10690.358578485315;9861.802763944825;10024.448655324028;10326.070653640907;10428.582856685647;10843.132549395872;8522.531260780468;8702.608382622671;8921.107889211027;8962.103789620985;9161.175494205554;8611.741647750578;8657.350698697497;8667.4366166199;8762.912370876209;8877.24543413817 16:12:26 INFO - PROCESS | 2009 | __end_tp_report 16:12:26 INFO - PROCESS | 2009 | __start_cc_report 16:12:26 INFO - PROCESS | 2009 | _x_x_mozilla_cycle_collect,1691 16:12:26 INFO - PROCESS | 2009 | __end_cc_report 16:12:26 INFO - PROCESS | 2009 | __startTimestamp1464649946211__endTimestamp 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | ------- Summary: start ------- 16:12:26 INFO - PROCESS | 2009 | Number of tests: 6 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | [#0] dojo.html Cycles:165 Average:5703.22 Median:5869.15 stddev:653.09 (11.1%) stddev-sans-first:628.97 16:12:26 INFO - PROCESS | 2009 | Values: 3372.1 3416.6 3434.4 3461.2 3496.7 5680.1 5680.7 5687.7 5695.8 5710.8 4854.4 5598.8 5693.2 5727.9 5743.3 5070.9 5136.5 5205.4 5259.4 5303.7 5214.5 5220.5 5220.7 5228.0 5234.0 4277.5 5175.0 5216.0 5231.9 5235.7 5206.2 5236.1 5244.3 5264.4 5317.2 5112.2 5130.0 5139.7 5173.6 5190.4 4621.4 5483.9 5502.8 5539.2 5542.8 6226.4 6232.6 6244.5 6246.8 6261.5 5647.9 6259.2 6269.1 6278.2 6325.0 5846.6 5853.7 5869.1 5883.5 5909.4 6310.1 6313.7 6317.1 6325.6 6328.1 5931.9 6028.7 6097.8 6198.8 6228.8 6266.0 6267.8 6293.0 6293.9 6305.4 6126.8 6195.5 6205.6 6214.9 6219.7 5247.4 5915.9 6139.4 6234.9 6243.4 4848.4 4863.2 4884.0 4887.1 4894.4 4403.5 4427.6 4428.9 4453.4 4499.7 5078.3 5482.7 5650.9 5669.6 5670.3 6237.7 6310.1 6313.5 6322.1 6322.6 5553.3 5640.5 5648.2 5692.8 5731.5 5288.1 5424.9 5426.1 5441.9 5460.2 5564.6 6330.7 6365.9 6373.1 6373.6 5320.9 6151.8 6240.9 6313.2 6324.3 6317.2 6362.4 6370.0 6371.4 6377.7 6209.1 6227.4 6232.5 6234.2 6239.4 5856.7 5960.9 6116.3 6310.8 6316.9 6089.0 6120.6 6130.1 6144.8 6149.3 6038.8 6078.7 6118.1 6120.3 6139.4 5787.5 5846.6 5863.1 5864.6 5868.6 5974.7 5989.8 6082.2 6155.8 6164.8 5926.4 5927.8 5930.6 5955.0 5955.1 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | [#1] ext.html Cycles:180 Average:63714.27 Median:36318.36 stddev:136175.72 (375.0%) stddev-sans-first:136475.05 16:12:26 INFO - PROCESS | 2009 | Values: 513.7 514.8 524.7 525.9 593.1 25226.2 31450.8 37327.4 38053.0 38191.9 41061.8 41600.0 41669.6 41753.0 42005.8 8965.6 9093.7 9459.3 9680.7 9695.6 7793.8 8543.2 8730.6 8732.6 11347.9 11451.3 11501.8 11510.9 11518.6 11567.5 11415.9 11473.3 11482.6 11499.7 11506.4 8502.7 8721.2 8722.0 9465.6 11278.5 37997.8 38432.2 38432.8 38522.8 38596.8 3181.2 3210.7 3493.5 3611.3 3829.2 27040.3 27106.2 27148.7 27293.0 27630.4 2243.4 2411.9 2588.4 2750.1 2765.2 466865.0 467736.3 468621.0 468689.0 469232.0 58725.0 59033.5 59129.0 59449.0 59512.7 64718.7 64913.0 64924.0 64933.4 65078.3 16974.7 17421.4 17442.9 17444.0 17453.0 13350.4 13376.3 13391.3 13395.1 13459.8 14578.8 16266.3 16546.3 16589.7 16642.2 5281.6 6145.2 6290.9 6300.0 6334.7 917.2 940.9 1049.6 1075.4 1112.4 27989.4 29422.7 29820.1 30996.2 31244.7 764.6 1079.3 1083.2 1096.0 1153.5 11952.7 13857.1 14248.1 14276.9 14459.4 609733.0 614434.0 614465.0 614596.0 616622.0 47821.8 57930.4 58245.0 58300.0 58325.7 1343.5 1467.3 1497.7 1499.9 1502.2 28157.4 28574.6 28969.3 29012.9 29232.0 46586.0 46964.0 47009.5 47031.8 47040.5 370113.0 372211.0 372460.0 372912.0 605235.0 25541.6 26072.2 26262.9 26353.2 26373.9 41305.2 45742.0 46636.5 47248.8 48021.8 34538.6 35849.8 36033.6 36114.5 36704.4 36240.3 36396.5 37003.6 37060.3 37190.4 35916.9 36556.5 37072.8 37362.6 37447.8 31420.6 31946.7 31991.0 32119.5 32306.0 24612.8 24643.5 25043.4 25089.0 25314.2 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | [#2] jquery.html Cycles:180 Average:33363.85 Median:401.62 stddev:95466.26 (23770.4%) stddev-sans-first:95701.98 16:12:26 INFO - PROCESS | 2009 | Values: 399.3 404.0 405.5 405.7 412.2 12853.4 14853.9 16155.8 20122.7 20879.1 16080.8 16404.3 18082.5 20387.8 20875.8 5599.9 5838.6 5858.7 5860.7 5861.6 3849.4 4037.2 5602.2 5890.9 5892.4 4032.8 4033.5 4034.2 5629.9 5938.6 5894.7 5897.4 5906.3 5907.4 6400.5 5613.9 5742.5 5769.2 5772.5 5776.7 13154.1 13159.7 13167.5 15596.8 23959.9 2145.3 2169.3 2204.0 2297.6 2299.4 9790.0 9911.3 9956.6 12331.5 14554.4 1526.6 1673.9 1703.8 1727.9 1736.5 106368.0 108245.4 109412.0 111707.0 115393.0 9139.6 9355.7 11447.4 11576.5 11807.5 14952.7 15014.3 15028.0 15051.7 15053.5 8474.9 8871.0 8906.6 9007.5 9073.1 7426.1 8172.2 8292.9 9079.8 9098.5 4623.7 4638.9 4666.6 4670.0 4676.1 1681.5 1966.0 1973.0 1981.4 1981.6 724.8 769.8 785.8 952.7 983.6 18965.2 19963.7 19965.3 19980.0 20102.0 822.3 913.9 957.5 958.7 974.4 8537.7 10642.3 10814.4 10899.6 11155.2 506859.0 581001.0 586320.0 588508.0 591237.0 119125.0 121823.0 123901.7 126545.4 127015.5 993.9 1003.9 1013.7 1014.0 1016.0 17915.5 17974.0 18048.0 18087.5 18709.4 52521.5 52627.7 52663.7 52745.9 55137.0 75016.0 75905.0 77139.0 78347.0 78647.0 7944.0 7981.6 8003.0 8365.5 8367.6 9712.9 11698.9 12250.6 12412.3 12770.9 8892.4 11062.4 11264.0 11437.6 11448.9 7327.7 7613.4 10766.7 11048.8 11255.4 11214.1 11468.4 11765.8 11783.8 11812.4 8178.7 8219.6 8265.5 8547.1 8608.8 10785.0 11124.7 11264.4 11382.8 11471.9 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | [#3] mootools.html Cycles:180 Average:5602.66 Median:4923.00 stddev:3095.70 (62.9%) stddev-sans-first:3088.42 16:12:26 INFO - PROCESS | 2009 | Values: 1419.2 1550.6 1571.4 1791.4 1939.6 7786.7 8434.5 8602.6 8616.2 8656.0 7334.7 8360.2 8566.0 8647.2 8652.2 2149.9 2368.3 2431.7 2437.2 2462.7 2458.6 2463.4 2464.7 2464.9 2466.6 2000.2 2373.5 2439.3 2442.2 2447.6 2146.4 2374.6 2422.6 2431.6 2432.8 2411.4 2445.2 2451.5 2453.2 2457.6 8644.4 8720.3 8722.1 8744.3 8765.9 4229.9 5317.3 5362.1 5370.5 5371.6 3398.6 3414.7 3427.7 3447.0 3465.4 1062.9 1617.8 1634.4 1656.1 1676.4 9618.6 11456.8 11664.5 11667.5 11695.4 4503.8 4548.8 4588.5 4641.4 4678.3 9297.1 9298.3 9303.3 9318.5 9321.5 5274.8 5298.2 5302.3 5311.4 5313.7 4246.0 4383.3 4688.5 4814.2 4824.1 3845.4 3863.2 3914.3 3949.1 3980.0 1192.5 1197.0 1199.2 1207.0 1208.5 844.6 884.1 909.3 913.5 926.4 5753.2 5819.7 5889.8 5966.6 6139.8 455.0 458.8 461.3 469.6 473.4 3849.5 3907.9 3924.2 4007.2 4011.9 10361.1 10729.0 10738.7 10840.3 10870.2 10564.8 10591.0 10685.0 10697.9 10718.0 8175.4 8178.8 8215.1 8321.3 8334.3 5781.5 5830.2 5855.4 6000.2 6000.3 8778.0 8956.7 9327.8 9427.5 9706.0 9153.3 9536.2 9693.1 9707.4 9862.8 6626.2 6809.4 7279.0 7452.6 7538.8 8242.9 8246.0 8293.5 8301.5 8321.9 7297.6 7391.6 7401.4 7541.8 7542.0 7252.5 7254.9 7276.9 7311.1 7419.4 6863.3 7013.0 7019.3 7026.3 7032.3 4818.8 4824.8 4838.5 4949.8 4995.2 4344.3 4895.4 4896.2 4953.6 5012.4 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | [#4] prototype.html Cycles:180 Average:5838.40 Median:6629.29 stddev:1892.49 (28.5%) stddev-sans-first:1851.89 16:12:26 INFO - PROCESS | 2009 | Values: 318.4 323.3 328.0 329.4 332.5 6581.6 6602.4 6611.0 6625.2 6637.2 5312.8 5827.8 6543.1 6626.6 6633.6 3414.8 4765.2 4775.3 4874.1 4891.8 4876.3 4929.0 4933.5 4949.2 4955.4 4900.4 4909.7 4910.7 4922.6 4928.2 4627.6 4684.7 4812.9 4834.5 4837.3 4097.3 4108.6 4125.8 4130.4 4134.1 5488.3 6267.2 6337.7 6369.0 6395.2 4883.9 5734.7 5748.2 5755.7 5763.1 6835.1 7062.9 7073.5 7075.0 7085.8 4681.8 4721.6 4733.5 4747.0 4754.3 7416.4 7454.1 7480.1 7486.2 7492.1 4780.2 4797.9 4808.3 4811.4 4813.3 4870.6 4873.5 4877.3 4877.5 4880.5 5788.4 5789.6 5789.7 5791.1 5806.0 6804.6 7043.4 7045.0 7084.4 7119.0 1367.3 1594.9 1632.2 1635.6 1637.4 972.9 973.9 1016.9 1020.2 1042.5 6529.8 6636.9 6644.9 6684.8 6760.4 7451.6 7455.6 7462.8 7466.8 7467.4 6614.5 6619.6 6622.9 6628.3 6630.3 3793.8 3886.1 3931.3 3940.5 3943.5 7731.3 7763.0 7763.9 7768.5 7821.0 7568.0 7643.1 7643.3 7662.6 7663.3 7744.6 7766.4 7778.8 7789.2 7814.5 7156.3 7380.0 7382.9 7401.7 7412.8 7258.8 7280.4 7306.7 7319.6 7346.4 7819.5 7823.5 7836.0 7844.6 7845.4 4640.5 4686.9 4689.3 4695.3 4703.8 7213.5 7425.1 7454.5 7457.7 7467.9 7122.6 7183.5 7190.6 7194.3 7205.6 7192.0 7196.0 7201.6 7207.0 7210.8 7018.4 7018.5 7021.5 7028.1 7033.1 6869.5 7097.9 7113.5 7205.2 7232.0 6708.4 6805.4 6899.1 6915.8 6949.6 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - PROCESS | 2009 | [#5] yui.html Cycles:180 Average:17123.92 Median:65144.17 stddev:23652.15 (36.3%) stddev-sans-first:23686.10 16:12:26 INFO - PROCESS | 2009 | Values: 636.9 675.9 742.8 747.6 762.5 14823.3 14957.0 14978.2 15000.2 15061.2 13532.8 13695.0 13860.9 14008.4 14018.1 716.6 884.7 893.7 902.3 905.5 729.4 876.2 892.3 893.9 894.5 930.5 933.8 934.1 936.2 943.6 746.7 929.5 933.0 935.6 942.8 589.7 695.1 696.4 700.2 701.5 14951.7 15173.5 15181.3 15196.1 15201.8 12601.8 12622.2 12782.0 12853.2 12881.8 8102.6 9997.4 10087.6 10131.8 10200.9 3429.6 3471.1 3492.2 3506.2 3574.7 76185.6 76931.8 77026.8 77990.0 78194.6 9303.6 9334.6 9448.0 9454.8 9492.9 21003.8 21160.9 21161.9 21175.0 21206.8 7347.9 7386.9 7456.3 7572.7 7584.4 3976.1 3978.3 3984.3 4045.1 4065.9 3012.5 3600.2 3649.1 3653.8 3654.5 588.1 679.9 687.2 706.1 711.7 456.6 486.8 592.3 618.3 625.8 7738.8 7764.9 8186.9 8203.3 8218.9 588.6 589.5 591.3 598.9 605.0 3621.6 3875.8 3876.9 3891.5 3903.1 82953.6 83066.0 83234.6 84575.0 87458.6 64413.7 64530.4 67407.5 68695.0 70646.0 51370.0 65911.0 66228.3 67015.3 67339.7 7465.5 7585.7 7690.4 7728.7 7822.1 46808.0 48200.5 48372.5 48621.5 49431.8 62419.7 64396.7 65055.7 65232.7 67557.7 9708.8 9736.0 9885.6 9985.4 10035.7 10554.5 10621.7 10631.4 11013.6 11022.1 10281.9 10456.1 10456.4 10516.7 10537.8 9998.8 10196.3 10301.5 10584.7 10690.4 9861.8 10024.4 10326.1 10428.6 10843.1 8522.5 8702.6 8921.1 8962.1 9161.2 8611.7 8657.4 8667.4 8762.9 8877.2 16:12:26 INFO - PROCESS | 2009 | -------- Summary: end -------- 16:12:26 INFO - PROCESS | 2009 | 16:12:26 INFO - TEST-INFO | 2009: exit 0 16:12:26 INFO - TEST-OK | dromaeo_css | took 1115255ms 16:12:26 INFO - TEST-START | kraken 16:12:26 INFO - operating with platform_type : mac_ 16:12:26 INFO - Initialising browser for kraken test... 16:12:26 INFO - TEST-INFO | started process 2012 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpThUEf0/profile http://localhost:49395/getInfo.html) 16:12:29 INFO - PROCESS | 2012 | __metrics Screen width/height:1600/1200 16:12:29 INFO - PROCESS | 2012 | colorDepth:24 16:12:29 INFO - PROCESS | 2012 | Browser inner width/height: 1024/689 16:12:29 INFO - PROCESS | 2012 | __metrics 16:12:29 INFO - PROCESS | 2012 | 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" 16:12:29 ERROR - PROCESS | 2012 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:12:30 INFO - TEST-INFO | 2012: exit 0 16:12:30 INFO - Browser initialized. 16:12:30 INFO - Running cycle 1/1 for kraken test... 16:12:30 INFO - TEST-INFO | started process 2014 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpThUEf0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:41 INFO - PROCESS | 2014 | RSS: Main: 211402752 16:12:41 INFO - PROCESS | 2014 | 16:12:44 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49395/tests/kraken/driver.html?testName=audio-beat-detection) 16:12:45 INFO - PROCESS | 2014 | RSS: Main: 272044032 16:12:45 INFO - PROCESS | 2014 | 16:12:48 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49395/tests/kraken/driver.html?testName=audio-dft) 16:12:49 INFO - PROCESS | 2014 | RSS: Main: 287920128 16:12:49 INFO - PROCESS | 2014 | 16:12:52 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49395/tests/kraken/driver.html?testName=audio-fft) 16:12:52 INFO - PROCESS | 2014 | RSS: Main: 374083584 16:12:52 INFO - PROCESS | 2014 | 16:12:54 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49395/tests/kraken/driver.html?testName=audio-oscillator) 16:12:55 INFO - PROCESS | 2014 | RSS: Main: 279314432 16:12:55 INFO - PROCESS | 2014 | 16:12:57 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49395/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:12:58 INFO - PROCESS | 2014 | RSS: Main: 280453120 16:12:58 INFO - PROCESS | 2014 | 16:13:01 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49395/tests/kraken/driver.html?testName=imaging-darkroom) 16:13:02 INFO - PROCESS | 2014 | RSS: Main: 317808640 16:13:02 INFO - PROCESS | 2014 | 16:13:04 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49395/tests/kraken/driver.html?testName=imaging-desaturate) 16:13:05 INFO - PROCESS | 2014 | RSS: Main: 320069632 16:13:05 INFO - PROCESS | 2014 | 16:13:08 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49395/tests/kraken/driver.html?testName=json-parse-financial) 16:13:09 INFO - PROCESS | 2014 | RSS: Main: 317349888 16:13:09 INFO - PROCESS | 2014 | 16:13:10 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49395/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:13:11 INFO - PROCESS | 2014 | RSS: Main: 309190656 16:13:11 INFO - PROCESS | 2014 | 16:13:13 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:13:13 INFO - PROCESS | 2014 | RSS: Main: 315613184 16:13:13 INFO - PROCESS | 2014 | 16:13:15 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:13:16 INFO - PROCESS | 2014 | RSS: Main: 319344640 16:13:16 INFO - PROCESS | 2014 | 16:13:19 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:13:20 INFO - PROCESS | 2014 | RSS: Main: 324030464 16:13:20 INFO - PROCESS | 2014 | 16:13:22 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:13:23 INFO - PROCESS | 2014 | RSS: Main: 322134016 16:13:23 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49395/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49395/tests/kraken/driver.html?testName=ai-astar) 16:13:25 INFO - PROCESS | 2014 | RSS: Main: 386162688 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | __start_tp_report 16:13:25 INFO - PROCESS | 2014 | _x_x_mozilla_page_load 16:13:25 INFO - PROCESS | 2014 | _x_x_mozilla_page_load_details 16:13:25 INFO - PROCESS | 2014 | |i|pagename|runs| 16:13:25 INFO - PROCESS | 2014 | |0;ai-astar;113;113;119;112;113;112;114;113;114;131 16:13:25 INFO - PROCESS | 2014 | |1;audio-beat-detection;162;149;179;164;119;141;125;183;192;125 16:13:25 INFO - PROCESS | 2014 | |2;audio-dft;184;188;187;187;186;187;187;187;187;183 16:13:25 INFO - PROCESS | 2014 | |3;audio-fft;72;81;72;155;57;57;56;66;134;58 16:13:25 INFO - PROCESS | 2014 | |4;audio-oscillator;95;91;159;113;127;101;129;80;77;127 16:13:25 INFO - PROCESS | 2014 | |5;imaging-gaussian-blur;88;85;89;131;126;84;89;83;89;86 16:13:25 INFO - PROCESS | 2014 | |6;imaging-darkroom;92;94;95;93;94;94;94;94;93;93 16:13:25 INFO - PROCESS | 2014 | |7;imaging-desaturate;142;141;90;90;91;85;88;89;88;88 16:13:25 INFO - PROCESS | 2014 | |8;json-parse-financial;59;64;66;64;64;63;64;85;57;58 16:13:25 INFO - PROCESS | 2014 | |9;json-stringify-tinderbox;64;66;73;75;73;71;68;69;70;70 16:13:25 INFO - PROCESS | 2014 | |10;stanford-crypto-aes;79;81;82;79;82;76;69;73;100;87 16:13:25 INFO - PROCESS | 2014 | |11;stanford-crypto-ccm;133;150;115;145;115;143;147;139;151;149 16:13:25 INFO - PROCESS | 2014 | |12;stanford-crypto-pbkdf2;175;168;168;166;167;167;167;167;166;168 16:13:25 INFO - PROCESS | 2014 | |13;stanford-crypto-sha256-iterative;70;67;68;68;68;67;69;67;67;66 16:13:25 INFO - PROCESS | 2014 | __end_tp_report 16:13:25 INFO - PROCESS | 2014 | __start_cc_report 16:13:25 INFO - PROCESS | 2014 | _x_x_mozilla_cycle_collect,2039 16:13:25 INFO - PROCESS | 2014 | __end_cc_report 16:13:25 INFO - PROCESS | 2014 | __startTimestamp1464650005556__endTimestamp 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | ------- Summary: start ------- 16:13:25 INFO - PROCESS | 2014 | Number of tests: 14 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#0] ai-astar Cycles:10 Average:115.40 Median:113.50 stddev:5.83 (5.1%) stddev-sans-first:6.12 16:13:25 INFO - PROCESS | 2014 | Values: 113.0 113.0 119.0 112.0 113.0 112.0 114.0 113.0 114.0 131.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#1] audio-beat-detection Cycles:10 Average:153.90 Median:163.00 stddev:26.19 (16.1%) stddev-sans-first:27.62 16:13:25 INFO - PROCESS | 2014 | Values: 162.0 149.0 179.0 164.0 119.0 141.0 125.0 183.0 192.0 125.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#2] audio-dft Cycles:10 Average:186.30 Median:187.00 stddev:1.57 (0.8%) stddev-sans-first:1.42 16:13:25 INFO - PROCESS | 2014 | Values: 184.0 188.0 187.0 187.0 186.0 187.0 187.0 187.0 187.0 183.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#3] audio-fft Cycles:10 Average:80.80 Median:62.00 stddev:34.92 (56.3%) stddev-sans-first:36.90 16:13:25 INFO - PROCESS | 2014 | Values: 72.0 81.0 72.0 155.0 57.0 57.0 56.0 66.0 134.0 58.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#4] audio-oscillator Cycles:10 Average:109.90 Median:118.00 stddev:25.83 (21.9%) stddev-sans-first:26.83 16:13:25 INFO - PROCESS | 2014 | Values: 95.0 91.0 159.0 113.0 127.0 101.0 129.0 80.0 77.0 127.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:87.00 stddev:17.83 (20.5%) stddev-sans-first:18.73 16:13:25 INFO - PROCESS | 2014 | Values: 88.0 85.0 89.0 131.0 126.0 84.0 89.0 83.0 89.0 86.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.84 (0.9%) stddev-sans-first:0.67 16:13:25 INFO - PROCESS | 2014 | Values: 92.0 94.0 95.0 93.0 94.0 94.0 94.0 94.0 93.0 93.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:88.50 stddev:22.35 (25.3%) stddev-sans-first:17.54 16:13:25 INFO - PROCESS | 2014 | Values: 142.0 141.0 90.0 90.0 91.0 85.0 88.0 89.0 88.0 88.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:7.85 (12.3%) stddev-sans-first:8.08 16:13:25 INFO - PROCESS | 2014 | Values: 59.0 64.0 66.0 64.0 64.0 63.0 64.0 85.0 57.0 58.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:3.35 (4.7%) stddev-sans-first:2.79 16:13:25 INFO - PROCESS | 2014 | Values: 64.0 66.0 73.0 75.0 73.0 71.0 68.0 69.0 70.0 70.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:80.00 stddev:8.43 (10.5%) stddev-sans-first:8.92 16:13:25 INFO - PROCESS | 2014 | Values: 79.0 81.0 82.0 79.0 82.0 76.0 69.0 73.0 100.0 87.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:146.00 stddev:13.61 (9.3%) stddev-sans-first:14.28 16:13:25 INFO - PROCESS | 2014 | Values: 133.0 150.0 115.0 145.0 115.0 143.0 147.0 139.0 151.0 149.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:167.50 stddev:2.60 (1.6%) stddev-sans-first:0.78 16:13:25 INFO - PROCESS | 2014 | Values: 175.0 168.0 168.0 166.0 167.0 167.0 167.0 167.0 166.0 168.0 16:13:25 INFO - PROCESS | 2014 | 16:13:25 INFO - PROCESS | 2014 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:0.88 16:13:25 INFO - PROCESS | 2014 | Values: 70.0 67.0 68.0 68.0 68.0 67.0 69.0 67.0 67.0 66.0 16:13:25 INFO - PROCESS | 2014 | -------- Summary: end -------- 16:13:25 INFO - PROCESS | 2014 | 16:13:26 INFO - TEST-INFO | 2014: exit 0 16:13:26 INFO - TEST-OK | kraken | took 59563ms 16:13:26 INFO - SUITE-END | took 1174s 16:13:26 INFO - Completed test suite (00:19:35) 16:13:26 INFO - javascript benchmark 16:13:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.0558243691758, 3416.6241713411505, 3434.3989801784683, 3461.1866211440324, 3496.6852983231506, 5680.0627896397145, 5680.659160450371, 5687.715614219288, 5695.800646977362, 5710.828675139742, 4854.393200849894, 5598.824246908144, 5693.202951586781, 5727.914081288784, 5743.282089738783, 5070.923936140961, 5136.512031357015, 5205.401378841444, 5259.421463638997, 5303.734813259341, 5214.478552144793, 5220.504052115043, 5220.738963051851, 5227.973860130694, 5233.9738301308635, 4277.465316835395, 5174.974125129369, 5216.0, 5231.86920326993, 5235.738213089349, 5206.219067139921, 5236.083685355085, 5244.344456942882, 5264.42091369949, 5317.175837745156, 5112.156494178456, 5130.0, 5139.691618502902, 5173.611979101544, 5190.4031036430915, 4621.399218101659, 5483.862903427406, 5502.752376143083, 5539.224508568804, 5542.8337149885165, 6226.40849119333, 6232.626042437423, 6244.5316601254635, 6246.843828904268, 6261.46777523911, 5647.887042259173, 6259.186305780265, 6269.122322874756, 6278.183836101323, 6325.0, 5846.619969701956, 5853.707314634251, 5869.148973398833, 5883.529317654577, 5909.438603332676, 6310.0850376695125, 6313.652749098844, 6317.084022816666, 6325.557210995278, 6328.050792381179, 5931.911021334683, 6028.728707208186, 6097.786577469856, 6198.783042593578, 6228.844278893064, 6265.96867015662, 6267.811965641038, 6293.0, 6293.937060629335, 6305.369463053749, 6126.785562505291, 6195.535334849904, 6205.565610407228, 6214.937850621436, 6219.689015549205, 5247.396549396868, 5915.940840591625, 6139.386061393914, 6234.875302494015, 6243.43809057187, 4848.442429120626, 4863.221884498464, 4883.975580122076, 4887.120318342661, 4894.363732714724, 4403.53762854902, 4427.579379958899, 4428.937055106751, 4453.376527286183, 4499.662525310615, 5078.314427552307, 5482.725863706799, 5650.886982260414, 5669.6314739541795, 5670.262865827416, 6237.656928868865, 6310.1481300024825, 6313.494920406357, 6322.146510221153, 6322.589031712924, 5553.250311208016, 5640.5487560995025, 5648.152777083388, 5692.772289108472, 5731.484166425042, 5288.101022826128, 5424.918626220609, 5426.131818908955, 5441.918371224435, 5460.180972854024, 5564.582656300794, 6330.68346582669, 6365.936340636627, 6373.139626150411, 6373.553851230369, 5320.9467905322, 6151.800398922346, 6240.906386404116, 6313.210848643919, 6324.30432652417, 6317.241930968313, 6362.427381535684, 6370.012648039561, 6371.362863713592, 6377.681115944091, 6209.0996805462055, 6227.439530442282, 6232.5325600581045, 6234.15838861766, 6239.376062393724, 5856.677882716492, 5960.8807823844145, 6116.26604807426, 6310.842228944313, 6316.905246421215, 6089.0, 6120.632762034292, 6130.080487926846, 6144.754209831557, 6149.262088549223, 6038.75844966214, 6078.72645730952, 6118.143459915526, 6120.265568131853, 6139.416755408229, 5787.508061814793, 5846.619969701956, 5863.061910094364, 5864.560157988253, 5868.618539794899, 5974.701264936823, 5989.760409583568, 6082.239720034996, 6155.753769849156, 6164.845878853064, 5926.407359264039, 5927.792527261525, 5930.644161350333, 5955.047192449188, 5955.136505206808], "value": 5662.875471374135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.6635503745046, 514.7992283009626, 524.6799452334077, 525.9447757985412, 593.1132956230437, 25226.2432127036, 31450.842745786296, 37327.44008839866, 38053.0, 38191.85424437271, 41061.794691026575, 41600.0, 41669.58330416695, 41752.95617609561, 42005.7899710501, 8965.596548155316, 9093.727188184364, 9459.29055320852, 9680.661176858812, 9695.563699633498, 7793.766187014398, 8543.231109200171, 8730.563471826383, 8732.563371831415, 11347.943260283688, 11451.312921224711, 11501.827472587918, 11510.942445287803, 11518.596849110283, 11567.53729850805, 11415.885841141566, 11473.311601303865, 11482.598109066226, 11499.712507187345, 11506.424678766029, 8502.74491765248, 8721.171488708562, 8722.0, 9465.574049167804, 11278.492467838967, 37997.810010950045, 38432.231355372736, 38432.807835960644, 38522.80738596317, 38596.80701596474, 3181.236503239206, 3210.6949839765184, 3493.510908472816, 3611.3499570077374, 3829.1767270037076, 27040.323991900164, 27106.1868143954, 27148.728512714817, 27293.0, 27630.44739105227, 2243.4391402149463, 2411.903523859061, 2588.4305452800354, 2750.1337078820306, 2765.1566272286946, 466865.0, 467736.3226367727, 468621.0, 468689.0, 469232.0, 58725.0, 59033.5241618963, 59129.0, 59449.0, 59512.70243648754, 64718.67640661766, 64913.0, 64924.0, 64933.35066649367, 65078.34921650818, 16974.745378819327, 17421.390251341396, 17442.91278543599, 17444.0, 17452.95282283067, 13350.399232034388, 13376.331183440983, 13391.26348050847, 13395.062345635712, 13459.798103028463, 14578.781318280235, 16266.349346026263, 16546.33814647425, 16589.66820663604, 16642.16789160537, 5281.577473802086, 6145.170401995762, 6290.874182516324, 6300.0, 6334.651594162273, 917.179124683418, 940.948247846375, 1049.5644307612408, 1075.3601607043797, 1112.4271000434783, 27989.44021119607, 29422.705772941998, 29820.10539683813, 30996.22509437282, 31244.687553124633, 764.5833021003456, 1079.2768844873954, 1083.1876092930302, 1095.9616413425492, 1153.4521102476256, 11952.701182470508, 13857.099288546213, 14248.073875198079, 14276.92923030777, 14459.421623134956, 609733.0, 614434.0, 614465.0, 614596.0, 616622.0, 47821.76089119532, 57930.420695792505, 58245.0, 58300.0, 58325.70837145787, 1343.4626149540263, 1467.2590341877496, 1497.7229212595705, 1499.9100053996794, 1502.233860731035, 28157.43685126327, 28574.57138142888, 28969.275768105967, 29012.854935725187, 29232.0, 46586.0, 46964.0, 47009.529904701885, 47031.76484117557, 47040.529594703614, 370113.0, 372211.0, 372460.0, 372912.0, 605235.0, 25541.616875746506, 26072.217833464267, 26262.86868565645, 26353.20940371792, 26373.868130659223, 41305.1738965225, 45742.0, 46636.53363466322, 47248.763756181, 48021.75989120032, 34538.61845526151, 35849.82075089608, 36033.639663603026, 36114.4582831263, 36704.449433259055, 36240.27519449649, 36396.454053188696, 37003.62996370002, 37060.25879482449, 37190.44214336733, 35916.92249232527, 36556.45165322469, 37072.78363298202, 37362.62637373591, 37447.81276093602, 31420.601115844256, 31946.68053319437, 31991.0, 32119.51820722738, 32306.0, 24612.753872461046, 24643.507129857662, 25043.373915651526, 25088.99644014219, 25314.240572782845], "value": 19246.07621836667, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.2812936713915, 403.95354534228557, 405.4911086586334, 405.67545963229406, 412.23530351198525, 12853.421596028178, 14853.851461485381, 16155.757663635055, 20122.69815952762, 20879.060442280097, 16080.83919160808, 16404.343826246964, 18082.457526274233, 20387.796122038777, 20875.791242087613, 5599.888002239953, 5838.620489668158, 5858.707064646772, 5860.677662728548, 5861.618994765348, 3849.40334248192, 4037.1723796621623, 5602.1596760485845, 5890.852728681774, 5892.351841297454, 4032.7580345179235, 4033.5361433435237, 4034.213328400963, 5629.915551266734, 5938.613990090652, 5894.705264736767, 5897.351291357947, 5906.291245050578, 5907.350191478956, 6400.4559612432995, 5613.887722245532, 5742.483176514133, 5769.163471296681, 5772.451617096369, 5776.6534007959235, 13154.079214454992, 13159.736805263938, 13167.539136130285, 15596.766048509282, 23959.88020059906, 2145.324222869791, 2169.2949791317883, 2204.0, 2297.5979203639295, 2299.4016761059897, 9789.951050244774, 9911.256655750847, 9956.551955161962, 12331.5067397304, 14554.417823287056, 1526.6412393087614, 1673.9497815065445, 1703.7529558214114, 1727.894147745444, 1736.4825530771586, 106368.0, 108245.37631935527, 109412.0, 111707.0, 115393.0, 9139.588718507683, 9355.672551460666, 11447.370394628375, 11576.536938522533, 11807.52769889212, 14952.700945981236, 15014.324355404033, 15028.0, 15051.698966020618, 15053.548393548212, 8474.872876906851, 8871.0, 8906.643734250687, 9007.504587247764, 9073.13805188506, 7426.145993210742, 8172.223638754309, 8292.88046113779, 9079.773005674911, 9098.545072746336, 4623.745693986863, 4638.930416043762, 4666.626669866402, 4670.0, 4676.111538807616, 1681.4619321816901, 1965.9705104423444, 1972.960540789205, 1981.4253866378876, 1981.603679264153, 724.8151721311137, 769.7883082152341, 785.8349746553288, 952.7141857442878, 983.5868935047297, 18965.24139034451, 19963.700544491843, 19965.301214457424, 19980.0, 20101.99490025493, 822.3174764945081, 913.921572544404, 957.5308099031564, 958.7315551645551, 974.3617930255693, 8537.701180458655, 10642.308249963728, 10814.35113893169, 10899.618513351994, 11155.219134660658, 506859.0, 581001.0, 586320.0, 588508.0, 591237.0, 119125.0, 121823.0, 123901.70737194746, 126545.36727316305, 127015.45969080752, 993.9254555908336, 1003.9447830369254, 1013.6806905824642, 1013.9797204055877, 1016.0246163682799, 17915.462536123938, 17974.0, 18048.007359594823, 18087.547811304295, 18709.43871683852, 52521.47478525166, 52627.73686131545, 52663.736681316346, 52745.94508109893, 55137.0, 75016.0, 75905.0, 77139.0, 78347.0, 78647.03382415256, 7944.0, 7981.64082616272, 8003.0, 8365.498070115813, 8367.62345694433, 9712.854307185256, 11698.883011169779, 12250.611130829402, 12412.317322547347, 12770.936145319214, 8892.377533572846, 11062.446877655924, 11264.0, 11437.59968401102, 11448.885511144781, 7327.74352897646, 7613.390928725799, 10766.676999690022, 11048.834267486154, 11255.437228138724, 11214.102871770076, 11468.4265786712, 11765.823512647146, 11783.823242651539, 11812.409379530816, 8178.67285308581, 8219.630116644885, 8265.504069755594, 8547.102554231717, 8608.78478038054, 10785.029347358775, 11124.666260012213, 11264.38045907483, 11382.772344552897, 11471.885281147082], "value": 9167.142451860853, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.1626940105336, 1550.6278493161642, 1571.3557441449009, 1791.3551121596222, 1939.5635981904065, 7786.688532458702, 8434.451760635562, 8602.61288242029, 8616.224539791418, 8656.047834738189, 7334.706611735524, 8360.205780450862, 8565.972083350007, 8647.221750042494, 8652.178043085896, 2149.892505374733, 2368.348704106376, 2431.7081950166007, 2437.183543512925, 2462.655228268044, 2458.5820410530255, 2463.4457247119344, 2464.667269918556, 2464.938376540583, 2466.6053431450973, 2000.249906285143, 2373.5239524505346, 2439.2804122783823, 2442.206282958037, 2447.596146635803, 2146.420466474043, 2374.631932050535, 2422.5760491913934, 2431.6474111253947, 2432.8418652787514, 2411.360989337827, 2445.217530390276, 2451.460678650694, 2453.2394957563033, 2457.619069044301, 8644.394892357537, 8720.258778003878, 8722.12778722129, 8744.344174186897, 8765.94808622963, 4229.873103806891, 5317.335333083364, 5362.115250983583, 5370.489803468664, 5371.570274378079, 3398.5921689397182, 3414.709749671281, 3427.7086447651977, 3447.0, 3465.42820434628, 1062.9043386095211, 1617.7735117083619, 1634.4197809777477, 1656.0891509669634, 1676.4132553606262, 9618.615255389775, 11456.828147577793, 11664.475098620582, 11667.474963626657, 11695.35675537845, 4503.774811259424, 4548.840790572314, 4588.541145885385, 4641.396618439649, 4678.2982552616695, 9297.116773906468, 9298.349115561981, 9303.348765586345, 9318.487483188354, 9321.487318197429, 5274.7626356814035, 5298.231756395301, 5302.310699609026, 5311.389190243094, 5313.734313284321, 4245.957540424618, 4383.320585309282, 4688.507706690816, 4814.229723244264, 4824.059308434892, 3845.423186521988, 3863.208042351311, 3914.3149948758855, 3949.111449923744, 3980.0, 1192.5110704611068, 1196.9640910772691, 1199.2264989082169, 1207.0404028796952, 1208.546794951893, 844.5988155626152, 884.1026358246385, 909.3134683314188, 913.5249670171473, 926.3839546701503, 5753.223314852525, 5819.679917604488, 5889.764409423661, 5966.582339236212, 6139.754409823557, 455.02397357667616, 458.8118871262833, 461.3333732756202, 469.572688853142, 473.4366104335829, 3849.538055433366, 3907.882763517099, 3924.234774219057, 4007.238624661305, 4011.939820902689, 10361.119304859172, 10729.0, 10738.73153171177, 10840.319750438695, 10870.239083264254, 10564.788704225872, 10591.0, 10685.0, 10697.946510267398, 10717.981791729773, 8175.427720059658, 8178.83642327162, 8215.137410571924, 8321.292690121407, 8334.333253339719, 5781.4507621775865, 5830.2420685310635, 5855.414458554111, 6000.159977603226, 6000.339962604023, 8777.990531089006, 8956.68651597191, 9327.813443731224, 9427.528623568931, 9705.951470242604, 9153.313501487282, 9536.237101031762, 9693.127618514367, 9707.417554946725, 9862.753431164278, 6626.237982632059, 6809.421199198124, 7278.963605182152, 7452.590107544137, 7538.849223015619, 8242.876356854771, 8246.0, 8293.460925039853, 8301.46040507365, 8321.875171872547, 7297.63511824396, 7391.593462359622, 7401.444891633257, 7541.773746787605, 7541.962290188733, 7252.456065795192, 7254.891176632249, 7276.927230727625, 7311.122665279988, 7419.369353605004, 6863.347981941907, 7012.964935175291, 7019.333163349474, 7026.262242464672, 7032.331928466788, 4818.807247710052, 4824.831130910401, 4838.491958344323, 4949.802007919643, 4995.225740010304, 4344.326629372453, 4895.4370247422, 4896.192128298933, 4953.578945789648, 5012.398512178562], "value": 4487.284210863544, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.3934604578278, 323.345225917517, 327.98688052477894, 329.41528786404143, 332.4630721384964, 6581.6051036937815, 6602.405783479492, 6610.9669451652735, 6625.17185351831, 6637.203535575725, 5312.760925758341, 5827.796027139051, 6543.0839682444375, 6626.602403855772, 6633.635150066744, 3414.8121853298057, 4765.21373973294, 4775.283707443894, 4874.098291816008, 4891.755412229374, 4876.341693871317, 4929.014197160564, 4933.481984391641, 4949.208126699728, 4955.355803745508, 4900.387451568554, 4909.729964851932, 4910.680805747615, 4922.630802689795, 4928.162212423897, 4627.629789616823, 4684.672072954878, 4812.855614331575, 4834.516548345173, 4837.250226214942, 4097.262492751303, 4108.589141085898, 4125.793710314472, 4130.421740956269, 4134.090500089976, 5488.341399032141, 6267.1539342189135, 6337.746490140389, 6369.044643303495, 6395.168628078367, 4883.926741098886, 5734.713264336809, 5748.19525266463, 5755.68343741094, 5763.106718458645, 6835.111435513401, 7062.858742825115, 7073.540219885743, 7075.0, 7085.82285442868, 4681.836135735267, 4721.622270218374, 4733.526647335273, 4746.952530474686, 4754.263089221176, 7416.443766717463, 7454.1055073391, 7480.0649918760155, 7486.213947535485, 7492.063492063492, 4780.211265141213, 4797.880052998633, 4808.2787581863, 4811.398575178103, 4813.291281594983, 4870.561649451495, 4873.463918968911, 4877.292792545132, 4877.487863774323, 4880.487548807395, 5788.392218816964, 5789.565782566325, 5789.739461724233, 5791.102379131242, 5805.983952808326, 6804.557703749339, 7043.436525077981, 7045.048918395951, 7084.397826184729, 7119.0, 1367.3368416318078, 1594.936202551908, 1632.1675945267998, 1635.5747505648378, 1637.3614290426742, 972.8832540095092, 973.8782652168479, 1016.9440680762481, 1020.2297265564451, 1042.5464922758624, 6529.836754081186, 6636.933630663728, 6644.8671026579195, 6684.799456016327, 6760.391564759196, 7451.552906825608, 7455.589942553103, 7462.820820340627, 7466.813329666802, 7467.402607791364, 6614.470842332602, 6619.569727967763, 6622.9006564901565, 6628.304028077079, 6630.270670226271, 3793.7534060285993, 3886.145048089416, 3931.272714547818, 3940.5074365704286, 3943.5464921533817, 7731.342835858903, 7763.0, 7763.92236077632, 7768.495047821986, 7820.960895195602, 7567.962160189164, 7643.082830060317, 7643.273888980538, 7662.5785581792425, 7663.27198916102, 7744.574048427278, 7766.417518686008, 7778.7666370007855, 7789.221077892279, 7814.452988290879, 7156.284371562802, 7379.963100184465, 7382.889256661262, 7401.703931842666, 7412.777616671508, 7258.782236532912, 7280.381167600814, 7306.671199796128, 7319.560826350436, 7346.449016323691, 7819.47520233549, 7823.452358334862, 7836.02049743782, 7844.607769611611, 7845.411594130349, 4640.512746161605, 4686.882827929329, 4689.296605509065, 4695.272232803921, 4703.811847526061, 7213.531120477152, 7425.108986921388, 7454.4781865268915, 7457.7389791357045, 7467.887981680387, 7122.60825654594, 7183.497155199295, 7190.568565886064, 7194.280571942764, 7205.603691797001, 7191.964040179975, 7195.964020179866, 7201.567905925661, 7207.027051348001, 7210.819729506804, 7018.368346848808, 7018.473614478832, 7021.473389495911, 7028.0863487746265, 7033.085698859116, 6869.519133660596, 7097.929020709933, 7113.502054856111, 7205.171405288459, 7232.023676803564, 6708.396244338033, 6805.387515123464, 6899.137607799025, 6915.792526224222, 6949.583025018515], "value": 5192.346910046287, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.894912339464, 675.9121314229147, 742.8440027594205, 747.5850902748972, 762.4967521435857, 14823.332950017248, 14957.0, 14978.176200308992, 15000.249987500609, 15061.246937653115, 13532.84584271453, 13695.041347105707, 13860.861391386083, 14008.439662413492, 14018.088824226417, 716.57721944053, 884.7036242858634, 893.6872094766812, 902.3457992955088, 905.5381755304809, 729.3836707981774, 876.2289185516768, 892.3441270666056, 893.8569828827389, 894.5259012723267, 930.5486838883136, 933.7758937854934, 934.1172591900656, 936.241644268145, 943.627267229444, 746.6751962896158, 929.4655573045526, 933.0482907434413, 935.5789894547481, 942.8208640358345, 589.6904125334191, 695.0894328429773, 696.435886931587, 700.2192555300854, 701.4949236549676, 14951.700965980619, 15173.468928587446, 15181.316840742193, 15196.088234705952, 15201.847981520154, 12601.810972835416, 12622.242665440102, 12782.0, 12853.228806271654, 12881.806772898413, 8102.635381407851, 9997.400155990665, 10087.646932357333, 10131.848022279672, 10200.94899525505, 3429.5713035870517, 3471.0801637566065, 3492.1793378556017, 3506.2286297014616, 3574.696150827183, 76185.61907190485, 76931.8460223097, 77026.84459733109, 77990.0, 78194.60902695508, 9303.58133883977, 9334.579943902541, 9448.0, 9454.763630909145, 9492.857607135898, 21003.78996210018, 21160.894195529232, 21161.941902904793, 21175.0, 21206.78793212079, 7347.88978165328, 7386.889196662054, 7456.32893039629, 7572.734954276684, 7584.355329797029, 3976.1451287972645, 3978.3236849735613, 3984.2828290907337, 4045.110075783323, 4065.918681626351, 3012.472817256971, 3600.1539638185004, 3649.1424515238896, 3653.8173091345325, 3654.4518322251874, 588.0796553393925, 679.9218089919624, 687.2337343861558, 706.0750416953807, 711.7366574367413, 456.5776656592705, 486.79068000760014, 592.2744637818638, 618.3198481670127, 625.8435391152212, 7738.806529836799, 7764.883526747103, 8186.859855108669, 8203.343732501386, 8218.876716849252, 588.635046271306, 589.5018709190726, 591.3022633292755, 598.912972954087, 604.9667268300286, 3621.583517895423, 3875.8255878485534, 3876.8836934891997, 3891.5135608048995, 3903.1413089120347, 82953.58523207346, 83066.0, 83234.58382708048, 84575.0, 87458.56270718733, 64413.71172576522, 64530.386740331865, 67407.52960702285, 68694.9695754564, 70646.0, 51370.0, 65911.01133483002, 66228.3442913931, 67015.32984670189, 67339.66330168415, 7465.477416580788, 7585.658645360862, 7690.384769218337, 7728.690852366068, 7822.13956464777, 46808.0, 48200.5179948196, 48372.5162748368, 48621.5137848617, 49431.75284123556, 62419.6879015602, 64396.678016609614, 65055.674721627984, 65232.673836630514, 67557.66221168863, 9708.805823883624, 9736.0, 9885.604575816888, 9985.350952188084, 10035.749106272402, 10554.472276386303, 10621.681349559525, 10631.362118272928, 11013.559457621606, 11022.063124634175, 10281.948590257001, 10456.058954694114, 10456.424896630759, 10516.737081573021, 10537.789244215226, 9998.750031249278, 10196.3372380795, 10301.536430860779, 10584.682459526226, 10690.358578485315, 9861.802763944825, 10024.448655324028, 10326.070653640907, 10428.582856685647, 10843.132549395872, 8522.531260780468, 8702.608382622671, 8921.107889211027, 8962.103789620985, 9161.175494205554, 8611.741647750578, 8657.350698697497, 8667.4366166199, 8762.912370876209, 8877.24543413817], "value": 6621.173304847564, "unit": "score"}], "name": "dromaeo_css", "value": 7322.4323393263785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 119.0, 112.0, 113.0, 112.0, 114.0, 113.0, 114.0, 131.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 149.0, 179.0, 164.0, 119.0, 141.0, 125.0, 183.0, 192.0, 125.0], "value": 153.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 187.0, 187.0, 186.0, 187.0, 187.0, 187.0, 187.0, 183.0], "value": 186.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 72.0, 155.0, 57.0, 57.0, 56.0, 66.0, 134.0, 58.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 159.0, 113.0, 127.0, 101.0, 129.0, 80.0, 77.0, 127.0], "value": 109.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 131.0, 126.0, 84.0, 89.0, 83.0, 89.0, 86.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 90.0, 90.0, 91.0, 85.0, 88.0, 89.0, 88.0, 88.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 66.0, 64.0, 64.0, 63.0, 64.0, 85.0, 57.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 73.0, 75.0, 73.0, 71.0, 68.0, 69.0, 70.0, 70.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 82.0, 79.0, 82.0, 76.0, 69.0, 73.0, 100.0, 87.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 150.0, 115.0, 145.0, 115.0, 143.0, 147.0, 139.0, 151.0, 149.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 168.0, 168.0, 166.0, 167.0, 167.0, 167.0, 167.0, 166.0, 168.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 68.0, 68.0, 67.0, 69.0, 67.0, 67.0, 66.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1523.5000000000002, "alertThreshold": 2.0}]} 16:13:26 INFO - Return code: 0 16:13:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:13:26 ERROR - # TBPL SUCCESS # 16:13:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:13:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:13:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:13:26 INFO - 2016-05-30 16:13:26.877 screenresolution[2018:12522] starting screenresolution argv=screenresolution get 16:13:26 INFO - 2016-05-30 16:13:26.880 screenresolution[2018:12522] Display 0: 1600x1200x32@60 16:13:26 INFO - 2016-05-30 16:13:26.886 screenresolution[2019:12526] starting screenresolution argv=screenresolution list 16:13:26 INFO - Available Modes on Display 0 16:13:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:13:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:13:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:13:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:13:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:13:27 INFO - Intel Iris: 16:13:27 INFO - Chipset Model: Intel Iris 16:13:27 INFO - Type: GPU 16:13:27 INFO - Bus: Built-In 16:13:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:13:27 INFO - Vendor: Intel (0x8086) 16:13:27 INFO - Device ID: 0x0a2e 16:13:27 INFO - Revision ID: 0x0009 16:13:27 INFO - Displays: 16:13:27 INFO - Display: 16:13:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:13:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:13:27 INFO - Display Serial Number: 1600x1200 60 16:13:27 INFO - Main Display: Yes 16:13:27 INFO - Mirror: Off 16:13:27 INFO - Online: Yes 16:13:27 INFO - Rotation: Supported 16:13:27 INFO - Return code: 0 16:13:27 INFO - Running post-action listener: _resource_record_post_action 16:13:27 INFO - Running post-run listener: _resource_record_post_run 16:13:28 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 438272; Write bytes: 654751232; Read time: 9; Write time: 1995 16:13:28 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346855936; Write bytes: 346085376; Read time: 26646; Write time: 697 16:13:28 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 438272; Write bytes: 298708480; Read time: 9; Write time: 1269 16:13:28 INFO - Running post-run listener: _upload_blobber_files 16:13:28 INFO - Blob upload gear active. 16:13:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:13:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:13:28 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:13:28 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:13:28 INFO - (blobuploader) - INFO - Open directory for files ... 16:13:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:13:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:13:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:13:29 INFO - (blobuploader) - INFO - Done attempting. 16:13:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:13:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:13:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:13:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:13:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:13:31 INFO - (blobuploader) - INFO - Done attempting. 16:13:31 INFO - (blobuploader) - INFO - Iteration through files over. 16:13:31 INFO - Return code: 0 16:13:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:13:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:13:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e4b28de240e973475dc4bc2fda91518d88f82597ad1a1d3cae7be5c1df6968cb996e630625531e1cdfadcc1252c7f09ade4be7778bfc4e83d0674033dbb5e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54631e06048badf84fc2c82f6b24ece586fb751376a99e928a0efb031d072d0095680a78a3136efe192b2c06ecf0495a653f204166d0f2f4c424bb21d03a29e7"} 16:13:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:13:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:13:31 INFO - Contents: 16:13:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e4b28de240e973475dc4bc2fda91518d88f82597ad1a1d3cae7be5c1df6968cb996e630625531e1cdfadcc1252c7f09ade4be7778bfc4e83d0674033dbb5e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54631e06048badf84fc2c82f6b24ece586fb751376a99e928a0efb031d072d0095680a78a3136efe192b2c06ecf0495a653f204166d0f2f4c424bb21d03a29e7"} 16:13:31 INFO - Running post-run listener: copy_logs_to_upload_dir 16:13:31 INFO - Copying logs to upload dir... 16:13:31 INFO - mkdir: /builds/slave/test/build/upload/logs 16:13:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1365.343263 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 45 secs) (at 2016-05-30 16:13:31.270125) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:13:31.275723) ========= 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.WMJqH2re0I/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.GfBjB3czRE/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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/Mozilla-Aurora/sha512/37e4b28de240e973475dc4bc2fda91518d88f82597ad1a1d3cae7be5c1df6968cb996e630625531e1cdfadcc1252c7f09ade4be7778bfc4e83d0674033dbb5e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54631e06048badf84fc2c82f6b24ece586fb751376a99e928a0efb031d072d0095680a78a3136efe192b2c06ecf0495a653f204166d0f2f4c424bb21d03a29e7"} build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37e4b28de240e973475dc4bc2fda91518d88f82597ad1a1d3cae7be5c1df6968cb996e630625531e1cdfadcc1252c7f09ade4be7778bfc4e83d0674033dbb5e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54631e06048badf84fc2c82f6b24ece586fb751376a99e928a0efb031d072d0095680a78a3136efe192b2c06ecf0495a653f204166d0f2f4c424bb21d03a29e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:13:31.306541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:13:31.306984) ========= 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.WMJqH2re0I/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.GfBjB3czRE/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005223 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:13:31.356737) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:13:31.357051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:13:31.357447) ========= ========= Total master_lag: 0.17 =========