builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0055 starttime: 1460996351.8 results: success (0) buildid: 20160418074021 builduid: ecbcdfd5295149c499e3f2b34e05cf2a revision: 72bdf0a085a53a9ff83511bee4dcc353731866a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.798324) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.798786) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.799075) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.816547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.816912) ========= 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.ffvRlfshos/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.RFBxGdCMRg/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-18 09:19:11-- 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% 38.7M=0s 2016-04-18 09:19:11 (38.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087539 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.920965) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.921280) ========= 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.ffvRlfshos/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.RFBxGdCMRg/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.019756 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:11.969154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 09:19:11.969482) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 72bdf0a085a53a9ff83511bee4dcc353731866a7 --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 72bdf0a085a53a9ff83511bee4dcc353731866a7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffvRlfshos/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.RFBxGdCMRg/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-18 09:19:12,047 truncating revision to first 12 chars 2016-04-18 09:19:12,047 Setting DEBUG logging. 2016-04-18 09:19:12,047 attempt 1/10 2016-04-18 09:19:12,047 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/72bdf0a085a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 09:19:13,107 unpacking tar archive at: mozilla-aurora-72bdf0a085a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.309033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 09:19:13.295244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:13.295567) ========= script_repo_revision: 72bdf0a085a53a9ff83511bee4dcc353731866a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:13.299011) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-18 09:19:13.299304) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-18 09:19:17.471589) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-18 09:19:17.475962) ========= /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.ffvRlfshos/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.RFBxGdCMRg/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:17 INFO - MultiFileLogger online at 20160418 09:19:17 in /builds/slave/test 09:19:17 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 09:19:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:19:17 INFO - {'append_to_log': False, 09:19:17 INFO - 'base_work_dir': '/builds/slave/test', 09:19:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:19:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:19:17 INFO - 'branch': 'Mozilla-Aurora', 09:19:17 INFO - 'buildbot_json_path': 'buildprops.json', 09:19:17 INFO - 'config_files': ('talos/mac_config.py',), 09:19:17 INFO - 'default_actions': ('clobber', 09:19:17 INFO - 'read-buildbot-config', 09:19:17 INFO - 'download-and-extract', 09:19:17 INFO - 'populate-webroot', 09:19:17 INFO - 'create-virtualenv', 09:19:17 INFO - 'install', 09:19:17 INFO - 'run-tests'), 09:19:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:19:17 INFO - 'download_minidump_stackwalk': True, 09:19:17 INFO - 'download_symbols': 'ondemand', 09:19:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:19:17 INFO - 'tooltool.py': '/tools/tooltool.py', 09:19:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:19:17 INFO - '/tools/misc-python/virtualenv.py')}, 09:19:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:19:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:19:17 INFO - 'installer_path': 'installer.exe', 09:19:17 INFO - 'log_level': 'info', 09:19:17 INFO - 'log_name': 'talos', 09:19:17 INFO - 'log_to_console': True, 09:19:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:19:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:19:17 INFO - 'opt_config_files': (), 09:19:17 INFO - 'pip_index': False, 09:19:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:17 INFO - 'cmd': ('bash', 09:19:17 INFO - '-c', 09:19:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:17 INFO - 'enabled': True, 09:19:17 INFO - 'halt_on_failure': False, 09:19:17 INFO - 'name': 'check_screen_resolution'},), 09:19:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:17 INFO - 'cmd': ('bash', 09:19:17 INFO - '-c', 09:19:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:17 INFO - 'enabled': True, 09:19:17 INFO - 'halt_on_failure': False, 09:19:17 INFO - 'name': 'check_screen_resolution'},), 09:19:17 INFO - 'run_cmd_checks_enabled': True, 09:19:17 INFO - 'sps_profile': False, 09:19:17 INFO - 'sps_profile_interval': 0, 09:19:17 INFO - 'suite': 'dromaeojs', 09:19:17 INFO - 'system_bits': '32', 09:19:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:19:17 INFO - 'title': 't-yosemite-r7-0055', 09:19:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:19:17 INFO - 'use_talos_json': True, 09:19:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:19:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:19:17 INFO - 'work_dir': 'build'} 09:19:17 INFO - ##### 09:19:17 INFO - ##### Running clobber step. 09:19:17 INFO - ##### 09:19:17 INFO - Running pre-action listener: _resource_record_pre_action 09:19:17 INFO - Running main action method: clobber 09:19:17 INFO - rmtree: /builds/slave/test/build 09:19:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:19:18 INFO - Running post-action listener: _resource_record_post_action 09:19:18 INFO - ##### 09:19:18 INFO - ##### Running read-buildbot-config step. 09:19:18 INFO - ##### 09:19:18 INFO - Running pre-action listener: _resource_record_pre_action 09:19:18 INFO - Running main action method: read_buildbot_config 09:19:18 INFO - Using buildbot properties: 09:19:18 INFO - { 09:19:18 INFO - "project": "", 09:19:18 INFO - "product": "firefox", 09:19:18 INFO - "script_repo_revision": "production", 09:19:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:19:18 INFO - "repository": "", 09:19:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:19:18 INFO - "buildid": "20160418074021", 09:19:18 INFO - "pgo_build": "False", 09:19:18 INFO - "basedir": "/builds/slave/test", 09:19:18 INFO - "buildnumber": 185, 09:19:18 INFO - "slavename": "t-yosemite-r7-0055", 09:19:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:19:18 INFO - "platform": "yosemite_r7", 09:19:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:19:18 INFO - "branch": "mozilla-aurora", 09:19:18 INFO - "repo_path": "releases/mozilla-aurora", 09:19:18 INFO - "revision": "72bdf0a085a53a9ff83511bee4dcc353731866a7", 09:19:18 INFO - "stage_platform": "macosx64", 09:19:18 INFO - "builduid": "ecbcdfd5295149c499e3f2b34e05cf2a", 09:19:18 INFO - "slavebuilddir": "test" 09:19:18 INFO - } 09:19:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 09:19:18 INFO - Running post-action listener: _resource_record_post_action 09:19:18 INFO - ##### 09:19:18 INFO - ##### Running download-and-extract step. 09:19:18 INFO - ##### 09:19:18 INFO - Running pre-action listener: _resource_record_pre_action 09:19:18 INFO - Running main action method: download_and_extract 09:19:18 INFO - mkdir: /builds/slave/test/build/tests 09:19:18 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')]} 09:19:18 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:19:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 09:19:19 INFO - Downloaded 1277 bytes. 09:19:19 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:19 INFO - Using the following test package requirements: 09:19:19 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:19:19 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 09:19:19 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 09:19:19 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'jsshell-mac.zip'], 09:19:19 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 09:19:19 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:19:19 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 09:19:19 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 09:19:19 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 09:19:19 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:19:19 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:19 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 09:19:19 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:19 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:19:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:19:20 INFO - Downloaded 17911052 bytes. 09:19:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:19:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:19:23 INFO - Return code: 0 09:19:23 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:23 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:19:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:19:25 INFO - Downloaded 17911052 bytes. 09:19:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:19:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:19:28 INFO - Return code: 0 09:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:28 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:19:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:19:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:19:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:19:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:19:29 INFO - Downloaded 10845168 bytes. 09:19:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:19:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:19:29 INFO - Return code: 0 09:19:29 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')]} 09:19:29 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:19:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 09:19:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 09:19:32 INFO - Downloaded 115243518 bytes. 09:19:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:32 INFO - mkdir: /builds/slave/test/properties 09:19:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:19:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:19:32 INFO - Contents: 09:19:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:33 INFO - Running post-action listener: _resource_record_post_action 09:19:33 INFO - Running post-action listener: set_extra_try_arguments 09:19:33 INFO - ##### 09:19:33 INFO - ##### Running populate-webroot step. 09:19:33 INFO - ##### 09:19:33 INFO - Running pre-action listener: _resource_record_pre_action 09:19:33 INFO - Running main action method: populate_webroot 09:19:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:19:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:19:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:19:33 INFO - '--tppagecycles', 09:19:33 INFO - '7'], 09:19:33 INFO - 'tests': ['tsvgm']}}, 09:19:33 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:19:33 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:19:33 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:19:33 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:19:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'tests': ['damp', 'tps']}, 09:19:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['damp', 'tps']}, 09:19:33 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:19:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:19:33 INFO - 'other': {'tests': ['a11yr', 09:19:33 INFO - 'ts_paint', 09:19:33 INFO - 'tpaint', 09:19:33 INFO - 'sessionrestore', 09:19:33 INFO - 'sessionrestore_no_auto_restore', 09:19:33 INFO - 'tabpaint']}, 09:19:33 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['a11yr', 09:19:33 INFO - 'ts_paint', 09:19:33 INFO - 'tpaint', 09:19:33 INFO - 'sessionrestore', 09:19:33 INFO - 'sessionrestore_no_auto_restore', 09:19:33 INFO - 'tabpaint']}, 09:19:33 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['a11yr', 09:19:33 INFO - 'ts_paint', 09:19:33 INFO - 'tpaint', 09:19:33 INFO - 'sessionrestore', 09:19:33 INFO - 'sessionrestore_no_auto_restore', 09:19:33 INFO - 'tabpaint']}, 09:19:33 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['a11yr', 09:19:33 INFO - 'ts_paint', 09:19:33 INFO - 'tpaint', 09:19:33 INFO - 'sessionrestore', 09:19:33 INFO - 'sessionrestore_no_auto_restore', 09:19:33 INFO - 'tabpaint']}, 09:19:33 INFO - 'other_l64': {'tests': ['a11yr', 09:19:33 INFO - 'ts_paint', 09:19:33 INFO - 'tpaint', 09:19:33 INFO - 'sessionrestore', 09:19:33 INFO - 'sessionrestore_no_auto_restore', 09:19:33 INFO - 'tabpaint']}, 09:19:33 INFO - 'other_nol64': {'tests': ['a11yr', 09:19:33 INFO - 'ts_paint', 09:19:33 INFO - 'tpaint', 09:19:33 INFO - 'sessionrestore', 09:19:33 INFO - 'sessionrestore_no_auto_restore', 09:19:33 INFO - 'tabpaint']}, 09:19:33 INFO - 'svgr': {'tests': ['tsvgx', 09:19:33 INFO - 'tsvgr_opacity', 09:19:33 INFO - 'tart', 09:19:33 INFO - 'tscrollx', 09:19:33 INFO - 'cart']}, 09:19:33 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['tsvgx', 09:19:33 INFO - 'tsvgr_opacity', 09:19:33 INFO - 'tart', 09:19:33 INFO - 'tscrollx', 09:19:33 INFO - 'cart']}, 09:19:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'tests': ['tp5o']}, 09:19:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'talos_options': ['--e10s'], 09:19:33 INFO - 'tests': ['tp5o']}, 09:19:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'talos_options': ['--xperf_path', 09:19:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:33 INFO - 'tests': ['tp5n']}, 09:19:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:33 INFO - 'talos_options': ['--e10s', 09:19:33 INFO - '--xperf_path', 09:19:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:33 INFO - 'tests': ['tp5n']}}, 09:19:33 INFO - 'talos.zip': {'path': '', 09:19:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:19:33 INFO - Running post-action listener: _resource_record_post_action 09:19:33 INFO - ##### 09:19:33 INFO - ##### Running create-virtualenv step. 09:19:33 INFO - ##### 09:19:33 INFO - Running pre-action listener: _resource_record_pre_action 09:19:33 INFO - Running main action method: create_virtualenv 09:19:33 INFO - Creating virtualenv /builds/slave/test/build/venv 09:19:33 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 09:19:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:19:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:19:34 INFO - Using real prefix '/tools/python27' 09:19:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:19:34 INFO - Installing distribute.............................................................................................................................................................................................done. 09:19:37 INFO - Installing pip.................done. 09:19:37 INFO - Return code: 0 09:19:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:37 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:37 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 09:19:37 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 09:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:37 INFO - 'HOME': '/Users/cltbld', 09:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:37 INFO - 'LOGNAME': 'cltbld', 09:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:37 INFO - 'MOZ_NO_REMOTE': '1', 09:19:37 INFO - 'NO_EM_RESTART': '1', 09:19:37 INFO - 'PAGER': '/bin/cat', 09:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:37 INFO - 'PWD': '/builds/slave/test', 09:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:37 INFO - 'SHELL': '/bin/bash', 09:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:37 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:37 INFO - 'USER': 'cltbld', 09:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:37 INFO - 'XPC_FLAGS': '0x0', 09:19:37 INFO - 'XPC_SERVICE_NAME': '0', 09:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:37 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 09:19:37 INFO - Cleaning up... 09:19:37 INFO - Return code: 0 09:19:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:37 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:37 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 09:19:37 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 09:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:37 INFO - 'HOME': '/Users/cltbld', 09:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:37 INFO - 'LOGNAME': 'cltbld', 09:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:37 INFO - 'MOZ_NO_REMOTE': '1', 09:19:37 INFO - 'NO_EM_RESTART': '1', 09:19:37 INFO - 'PAGER': '/bin/cat', 09:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:37 INFO - 'PWD': '/builds/slave/test', 09:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:37 INFO - 'SHELL': '/bin/bash', 09:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:37 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:37 INFO - 'USER': 'cltbld', 09:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:37 INFO - 'XPC_FLAGS': '0x0', 09:19:37 INFO - 'XPC_SERVICE_NAME': '0', 09:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:38 INFO - Downloading/unpacking psutil>=0.7.1 09:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:19:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:41 INFO - Installing collected packages: psutil 09:19:41 INFO - Running setup.py install for psutil 09:19:41 INFO - building 'psutil._psutil_osx' extension 09:19:41 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 09:19:41 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 09:19:41 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 09:19:41 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 09:19:41 INFO - building 'psutil._psutil_posix' extension 09:19:41 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 09:19:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:19:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:19:41 INFO - ^ 09:19:41 INFO - 1 warning generated. 09:19:41 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 09:19:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:42 INFO - Successfully installed psutil 09:19:42 INFO - Cleaning up... 09:19:42 INFO - Return code: 0 09:19:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:19:42 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')]} 09:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:42 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')]} 09:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:42 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:42 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 09:19:42 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 09:19:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:42 INFO - 'HOME': '/Users/cltbld', 09:19:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:42 INFO - 'LOGNAME': 'cltbld', 09:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:42 INFO - 'MOZ_NO_REMOTE': '1', 09:19:42 INFO - 'NO_EM_RESTART': '1', 09:19:42 INFO - 'PAGER': '/bin/cat', 09:19:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:42 INFO - 'PWD': '/builds/slave/test', 09:19:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:42 INFO - 'SHELL': '/bin/bash', 09:19:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:42 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:42 INFO - 'USER': 'cltbld', 09:19:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:42 INFO - 'XPC_FLAGS': '0x0', 09:19:42 INFO - 'XPC_SERVICE_NAME': '0', 09:19:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:19:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:19:44 INFO - Installing collected packages: mozsystemmonitor 09:19:44 INFO - Running setup.py install for mozsystemmonitor 09:19:44 INFO - Successfully installed mozsystemmonitor 09:19:44 INFO - Cleaning up... 09:19:45 INFO - Return code: 0 09:19:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:19:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:19:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:45 INFO - 'HOME': '/Users/cltbld', 09:19:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:45 INFO - 'LOGNAME': 'cltbld', 09:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:45 INFO - 'MOZ_NO_REMOTE': '1', 09:19:45 INFO - 'NO_EM_RESTART': '1', 09:19:45 INFO - 'PAGER': '/bin/cat', 09:19:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:45 INFO - 'PWD': '/builds/slave/test', 09:19:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:45 INFO - 'SHELL': '/bin/bash', 09:19:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:45 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:45 INFO - 'USER': 'cltbld', 09:19:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:45 INFO - 'XPC_FLAGS': '0x0', 09:19:45 INFO - 'XPC_SERVICE_NAME': '0', 09:19:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:19:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:19:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19: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 09:19:48 INFO - Downloading docopt-0.6.1.tar.gz 09:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:19:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:19:49 INFO - Running setup.py install for blobuploader 09:19:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:19:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:19:49 INFO - Running setup.py install for requests 09:19:49 INFO - Running setup.py install for docopt 09:19:49 INFO - Successfully installed blobuploader requests docopt 09:19:49 INFO - Cleaning up... 09:19:49 INFO - Return code: 0 09:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:49 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')]} 09:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:49 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')]} 09:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:49 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:49 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 09:19:49 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 09:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:49 INFO - 'HOME': '/Users/cltbld', 09:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:49 INFO - 'LOGNAME': 'cltbld', 09:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:49 INFO - 'MOZ_NO_REMOTE': '1', 09:19:49 INFO - 'NO_EM_RESTART': '1', 09:19:49 INFO - 'PAGER': '/bin/cat', 09:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:49 INFO - 'PWD': '/builds/slave/test', 09:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:49 INFO - 'SHELL': '/bin/bash', 09:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:49 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:49 INFO - 'USER': 'cltbld', 09:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:49 INFO - 'XPC_FLAGS': '0x0', 09:19:49 INFO - 'XPC_SERVICE_NAME': '0', 09:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-LQJBwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-lXk_Y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-H0Mjab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-fS0Zrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-tcUKj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-1peD9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-U7jGpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-VUplQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-kWoS9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-gKA4i9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-YByNdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-KHZx45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-gdjiRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-e9AVcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-XL9vlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-AFgGpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:51 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-wzOZ98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:19:51 INFO - Running setup.py install for manifestparser 09:19:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:19:52 INFO - Running setup.py install for mozcrash 09:19:52 INFO - Running setup.py install for mozdebug 09:19:52 INFO - Running setup.py install for mozdevice 09:19:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:19:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:19:52 INFO - Running setup.py install for mozfile 09:19:52 INFO - Running setup.py install for mozhttpd 09:19:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:19:52 INFO - Running setup.py install for mozinfo 09:19:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:19:52 INFO - Running setup.py install for mozInstall 09:19:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Running setup.py install for mozleak 09:19:53 INFO - Running setup.py install for mozlog 09:19:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Running setup.py install for moznetwork 09:19:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Running setup.py install for mozprocess 09:19:53 INFO - Running setup.py install for mozprofile 09:19:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:19:53 INFO - Running setup.py install for mozrunner 09:19:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:19:54 INFO - Running setup.py install for mozscreenshot 09:19:54 INFO - Running setup.py install for moztest 09:19:54 INFO - Running setup.py install for mozversion 09:19:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:19:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:19:54 INFO - Cleaning up... 09:19:54 INFO - Return code: 0 09:19:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:54 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:54 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 09:19:54 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 09:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:54 INFO - 'HOME': '/Users/cltbld', 09:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:54 INFO - 'LOGNAME': 'cltbld', 09:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:54 INFO - 'MOZ_NO_REMOTE': '1', 09:19:54 INFO - 'NO_EM_RESTART': '1', 09:19:54 INFO - 'PAGER': '/bin/cat', 09:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:54 INFO - 'PWD': '/builds/slave/test', 09:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:54 INFO - 'SHELL': '/bin/bash', 09:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:54 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:54 INFO - 'USER': 'cltbld', 09:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:54 INFO - 'XPC_FLAGS': '0x0', 09:19:54 INFO - 'XPC_SERVICE_NAME': '0', 09:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ZDUUFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:54 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)) 09:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-IscFcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:54 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-3x5uON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-tqyHie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-PojPdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-juuZfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Wzf4xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-yxQvNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-hdojnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-bjDx6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-IUaIGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:55 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)) 09:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-jFR0Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:56 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)) 09:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-740rYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:56 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)) 09:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-bHtb5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:56 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)) 09:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-wMg7S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:56 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)) 09:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-YPZftl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:56 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)) 09:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:56 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-AJ5CJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:56 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)) 09:19:56 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)) 09:19:56 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)) 09:19:56 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)) 09:19:56 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)) 09:19:56 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)) 09:19:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:58 INFO - Downloading blessings-1.6.tar.gz 09:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:59 INFO - Installing collected packages: blessings 09:19:59 INFO - Running setup.py install for blessings 09:19:59 INFO - Successfully installed blessings 09:19:59 INFO - Cleaning up... 09:19:59 INFO - Return code: 0 09:19:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:59 INFO - Reading from file tmpfile_stdout 09:19:59 INFO - Current package versions: 09:19:59 INFO - blessings == 1.6 09:19:59 INFO - blobuploader == 1.2.4 09:19:59 INFO - docopt == 0.6.1 09:19:59 INFO - manifestparser == 1.1 09:19:59 INFO - mozInstall == 1.12 09:19:59 INFO - mozcrash == 0.17 09:19:59 INFO - mozdebug == 0.1 09:19:59 INFO - mozdevice == 0.48 09:19:59 INFO - mozfile == 1.2 09:19:59 INFO - mozhttpd == 0.7 09:19:59 INFO - mozinfo == 0.9 09:19:59 INFO - mozleak == 0.1 09:19:59 INFO - mozlog == 3.1 09:19:59 INFO - moznetwork == 0.27 09:19:59 INFO - mozprocess == 0.22 09:19:59 INFO - mozprofile == 0.28 09:19:59 INFO - mozrunner == 6.11 09:19:59 INFO - mozscreenshot == 0.1 09:19:59 INFO - mozsystemmonitor == 0.0 09:19:59 INFO - moztest == 0.7 09:19:59 INFO - mozversion == 1.4 09:19:59 INFO - psutil == 3.1.1 09:19:59 INFO - requests == 1.2.3 09:19:59 INFO - wsgiref == 0.1.2 09:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:59 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')]} 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:59 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')]} 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:59 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:59 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 09:19:59 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 09:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:59 INFO - 'HOME': '/Users/cltbld', 09:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:59 INFO - 'LOGNAME': 'cltbld', 09:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:59 INFO - 'MOZ_NO_REMOTE': '1', 09:19:59 INFO - 'NO_EM_RESTART': '1', 09:19:59 INFO - 'PAGER': '/bin/cat', 09:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:59 INFO - 'PWD': '/builds/slave/test', 09:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:59 INFO - 'SHELL': '/bin/bash', 09:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:59 INFO - 'USER': 'cltbld', 09:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:59 INFO - 'XPC_FLAGS': '0x0', 09:19:59 INFO - 'XPC_SERVICE_NAME': '0', 09:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 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)) 09:19:59 INFO - Cleaning up... 09:19:59 INFO - Return code: 0 09:19:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:59 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')]} 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:59 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')]} 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:59 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:19:59 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 09:19:59 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 09:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:59 INFO - 'HOME': '/Users/cltbld', 09:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:59 INFO - 'LOGNAME': 'cltbld', 09:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:59 INFO - 'MOZ_NO_REMOTE': '1', 09:19:59 INFO - 'NO_EM_RESTART': '1', 09:19:59 INFO - 'PAGER': '/bin/cat', 09:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:59 INFO - 'PWD': '/builds/slave/test', 09:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:59 INFO - 'SHELL': '/bin/bash', 09:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:19:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:59 INFO - 'USER': 'cltbld', 09:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:59 INFO - 'XPC_FLAGS': '0x0', 09:19:59 INFO - 'XPC_SERVICE_NAME': '0', 09:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:00 INFO - Downloading/unpacking jsonschema 09:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:02 INFO - Downloading/unpacking functools32 (from jsonschema) 09:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:03 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:03 INFO - warning: no files found matching '*.txt' 09:20:03 INFO - no previously-included directories found matching 'build' 09:20:03 INFO - no previously-included directories found matching 'dist' 09:20:03 INFO - no previously-included directories found matching '.git*' 09:20:03 INFO - Installing collected packages: jsonschema, functools32 09:20:03 INFO - Running setup.py install for functools32 09:20:03 INFO - warning: no files found matching '*.txt' 09:20:03 INFO - no previously-included directories found matching 'build' 09:20:03 INFO - no previously-included directories found matching 'dist' 09:20:03 INFO - no previously-included directories found matching '.git*' 09:20:03 INFO - Successfully installed jsonschema functools32 09:20:03 INFO - Cleaning up... 09:20:03 INFO - Return code: 0 09:20:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:03 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 0x104967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10496e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa471c7a790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, '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.ffvRlfshos/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.RFBxGdCMRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 09:20:03 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 09:20:03 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 09:20:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:20:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:03 INFO - 'HOME': '/Users/cltbld', 09:20:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:03 INFO - 'LOGNAME': 'cltbld', 09:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:03 INFO - 'MOZ_NO_REMOTE': '1', 09:20:03 INFO - 'NO_EM_RESTART': '1', 09:20:03 INFO - 'PAGER': '/bin/cat', 09:20:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:03 INFO - 'PWD': '/builds/slave/test', 09:20:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:03 INFO - 'SHELL': '/bin/bash', 09:20:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:20:03 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:20:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:03 INFO - 'USER': 'cltbld', 09:20:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:03 INFO - 'XPC_FLAGS': '0x0', 09:20:03 INFO - 'XPC_SERVICE_NAME': '0', 09:20:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:03 INFO - Downloading/unpacking flake8 09:20:03 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 09:20:03 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 09:20:03 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 09:20:03 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 09:20:03 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 09:20:03 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 09:20:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:20:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:20:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:20:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:20:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:20:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:20:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:20:07 INFO - no previously-included directories found matching 'docs/_build' 09:20:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:20:07 INFO - Running setup.py install for pep8 09:20:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:20:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:20:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:20:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:20:07 INFO - no previously-included directories found matching 'docs/_build' 09:20:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:20:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:20:07 INFO - Cleaning up... 09:20:07 INFO - Return code: 0 09:20:07 INFO - Running post-action listener: _resource_record_post_action 09:20:07 INFO - Running post-action listener: _start_resource_monitoring 09:20:07 INFO - Starting resource monitoring. 09:20:07 INFO - ##### 09:20:07 INFO - ##### Running install step. 09:20:07 INFO - ##### 09:20:07 INFO - Running pre-action listener: _resource_record_pre_action 09:20:07 INFO - Running main action method: install 09:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:07 INFO - Reading from file tmpfile_stdout 09:20:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:20:08 INFO - Reading from file tmpfile_stdout 09:20:08 INFO - Output received: 09:20:08 INFO - Usage: mozinstall [options] installer 09:20:08 INFO - Options: 09:20:08 INFO - -h, --help show this help message and exit 09:20:08 INFO - -d DEST, --destination=DEST 09:20:08 INFO - Directory to install application into. [default: 09:20:08 INFO - "/builds/slave/test"] 09:20:08 INFO - --app=APP Application being installed. [default: firefox] 09:20:08 INFO - mkdir: /builds/slave/test/build/application 09:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:20:39 INFO - Reading from file tmpfile_stdout 09:20:39 INFO - Output received: 09:20:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:20:39 INFO - Running post-action listener: _resource_record_post_action 09:20:39 INFO - ##### 09:20:39 INFO - ##### Running run-tests step. 09:20:39 INFO - ##### 09:20:39 INFO - Running pre-action listener: _resource_record_pre_action 09:20:39 INFO - Running main action method: run_tests 09:20:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:39 INFO - 2016-04-18 09:20:39.861 screenresolution[2377:16632] starting screenresolution argv=screenresolution get 09:20:39 INFO - 2016-04-18 09:20:39.864 screenresolution[2377:16632] Display 0: 1600x1200x32@60 09:20:39 INFO - 2016-04-18 09:20:39.869 screenresolution[2378:16635] starting screenresolution argv=screenresolution list 09:20:39 INFO - Available Modes on Display 0 09:20:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:40 INFO - Intel Iris: 09:20:40 INFO - Chipset Model: Intel Iris 09:20:40 INFO - Type: GPU 09:20:40 INFO - Bus: Built-In 09:20:40 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:40 INFO - Vendor: Intel (0x8086) 09:20:40 INFO - Device ID: 0x0a2e 09:20:40 INFO - Revision ID: 0x0009 09:20:40 INFO - Displays: 09:20:40 INFO - Display: 09:20:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:40 INFO - Display Serial Number: 1600x1200 60 09:20:40 INFO - Main Display: Yes 09:20:40 INFO - Mirror: Off 09:20:40 INFO - Online: Yes 09:20:40 INFO - Rotation: Supported 09:20:40 INFO - Return code: 0 09:20:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:20:40 INFO - Python 2.7.3 09:20:40 INFO - Return code: 0 09:20:40 INFO - grabbing minidump binary from tooltool 09:20:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:40 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 0x10480bd30>, '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 0x10466fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104802b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:20:40 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 09:20:40 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 09:20:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:20:40 INFO - Return code: 0 09:20:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:20:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:20:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:20:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:20:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:20:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:20:41 INFO - Return code: 0 09:20:41 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/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 09:20:41 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/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0055 --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 09:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffvRlfshos/Render', 09:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:41 INFO - 'HOME': '/Users/cltbld', 09:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:41 INFO - 'LOGNAME': 'cltbld', 09:20:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:20:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:41 INFO - 'MOZ_NO_REMOTE': '1', 09:20:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:20:41 INFO - 'NO_EM_RESTART': '1', 09:20:41 INFO - 'PAGER': '/bin/cat', 09:20:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:41 INFO - 'PWD': '/builds/slave/test', 09:20:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:41 INFO - 'SHELL': '/bin/bash', 09:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RFBxGdCMRg/Listeners', 09:20:41 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 09:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:41 INFO - 'USER': 'cltbld', 09:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:41 INFO - 'XPC_FLAGS': '0x0', 09:20:41 INFO - 'XPC_SERVICE_NAME': '0', 09:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:41 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/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--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 09:20:41 INFO - mozversion application_buildid: 20160418074021 09:20:41 INFO - mozversion application_changeset: 72bdf0a085a53a9ff83511bee4dcc353731866a7 09:20:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:20:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:20:41 INFO - mozversion application_name: Firefox 09:20:41 INFO - mozversion application_remotingname: firefox-dev 09:20:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:20:41 INFO - mozversion application_vendor: Mozilla 09:20:41 INFO - mozversion application_version: 47.0a2 09:20:41 INFO - mozversion platform_buildid: 20160418074021 09:20:41 INFO - mozversion platform_changeset: 72bdf0a085a53a9ff83511bee4dcc353731866a7 09:20:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:20:41 INFO - mozversion platform_version: 47.0a2 09:20:41 INFO - using testdate: 1460996441 09:20:41 INFO - actual date: 1460996441 09:20:41 INFO - starting webserver on 'localhost:49822' 09:20:41 INFO - SUITE-START | Running 2 tests 09:20:41 INFO - TEST-START | dromaeo_css 09:20:41 INFO - operating with platform_type : mac_ 09:20:41 INFO - Initialising browser for dromaeo_css test... 09:20:41 INFO - TEST-INFO | started process 2396 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmp0hhEHV/profile http://localhost:49822/getInfo.html) 09:20:43 INFO - PROCESS | 2396 | __metrics Screen width/height:1600/1200 09:20:43 INFO - PROCESS | 2396 | colorDepth:24 09:20:43 INFO - PROCESS | 2396 | Browser inner width/height: 1024/689 09:20:43 INFO - PROCESS | 2396 | __metrics 09:20:43 INFO - PROCESS | 2396 | 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" 09:20:43 INFO - PROCESS | 2396 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:20:44 INFO - TEST-INFO | 2396: exit 0 09:20:44 INFO - Browser initialized. 09:20:44 INFO - Running cycle 1/1 for dromaeo_css test... 09:20:44 INFO - TEST-INFO | started process 2398 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmp0hhEHV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:56 INFO - PROCESS | 2398 | RSS: Main: 207499264 09:20:56 INFO - PROCESS | 2398 | 09:21:47 INFO - PROCESS | 2398 | 1460996507268 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] 09:23:57 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49822/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49822/tests/dromaeo/cssquery-ext.html) 09:23:58 INFO - PROCESS | 2398 | RSS: Main: 321826816 09:23:58 INFO - PROCESS | 2398 | 09:27:01 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49822/tests/dromaeo/cssquery-ext.html (next: http://localhost:49822/tests/dromaeo/cssquery-jquery.html) 09:27:01 INFO - PROCESS | 2398 | RSS: Main: 335794176 09:27:01 INFO - PROCESS | 2398 | 09:30:03 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49822/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49822/tests/dromaeo/cssquery-mootools.html) 09:30:04 INFO - PROCESS | 2398 | RSS: Main: 346951680 09:30:04 INFO - PROCESS | 2398 | 09:33:06 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49822/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49822/tests/dromaeo/cssquery-prototype.html) 09:33:06 INFO - PROCESS | 2398 | RSS: Main: 304001024 09:33:06 INFO - PROCESS | 2398 | 09:36:09 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49822/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49822/tests/dromaeo/cssquery-yui.html) 09:36:10 INFO - PROCESS | 2398 | RSS: Main: 305623040 09:36:10 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:49822/tests/dromaeo/cssquery-yui.html (next: http://localhost:49822/tests/dromaeo/cssquery-dojo.html) 09:39:13 INFO - PROCESS | 2398 | RSS: Main: 352239616 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | __start_tp_report 09:39:13 INFO - PROCESS | 2398 | _x_x_mozilla_page_load 09:39:13 INFO - PROCESS | 2398 | _x_x_mozilla_page_load_details 09:39:13 INFO - PROCESS | 2398 | |i|pagename|runs| 09:39:13 INFO - PROCESS | 2398 | |0;dojo.html;2977.9702202977965;3378.3581119587275;3450.982745086276;3469.8785542506016;3489.1626009757665;5670.6030577859565;5672.914906276404;5685.289338832646;5691.459311365424;5701.771929122835;5683.545316374691;5730.598858079936;5733.713314334277;5736.569757268201;5738.856528586777;5098.490150984909;5157.277981082651;5157.76790044447;5228.398734145565;5237.319148510688;5170.767315470813;5203.843884683447;5211.244369566411;5215.087359712054;5229.058769421503;4919.647097048322;5185.55922746567;5222.164453687411;5235.371755389339;5243.030039442696;4355.477342718878;4380.233459144637;4750.691205071676;5235.39792923811;5277.208418737182;5076.44159142494;5168.844934651929;5199.6360254782185;5202.271681964528;5242.685438873642;5586.944130558723;5617.241672374218;5629.493345598875;5633.295838020247;5637;6082.635041897456;6304.936950630527;6306.211723534559;6307.18006659136;6329.1772069631115;6246.469050130744;6248.750049997994;6268.15379923709;6310.842228944267;6324.525660575429;5814;5842.182094506772;5859.8535036624;5862.144726067048;5910.7931222407005;6185.381461853869;6200.906986395207;6277.466415354746;6282.874342513124;6283.748650054038;6130.795626845448;6158.261008678987;6170.938290617037;6174.6912654368;6178.320384757673;6293.653849038255;6297.905531417033;6307.810765677037;6307.968460157762;6313.3686631336495;6050.636961782219;6160.199174107337;6207.944649409611;6234.5635805494085;6250.874982500324;5280.498352656491;6224.782132625336;6266.874662506724;6290.056491526216;6297.811065668037;4901;4911.803527858846;4911.852644420673;4916.754162291871;4929.063477939252;3821.7515861468914;4346.500152482505;4376.474823021258;4451.443569553806;4467.843625473093;5716.428357164334;5724.341700704389;5728.398518155534;5739.053056245672;5762.596618236683;6314.810555683337;6315.431611155018;6317.810465685945;6318.6524741138755;6343.841403964938;5542.445755424425;5687.146927960839;5687.857803554862;5705.400932902068;5747.195392645033;5311.389190243094;5338.97330513345;5341.732913354317;5356.1697936819855;5377.704226267593;6253.093301471261;6289.308176100534;6299.370062993756;6317.5893566918;6323.588966717149;6117.3402608778015;6219.813405597839;6300.873982520416;6325.462335701517;6329.683515824098;6279.434850863261;6286.119943208046;6291.276503202191;6306.243250809932;6309.432151106423;6193.907091393723;6216.906746398898;6222.62664240147;6230.719617617308;6237.220347456568;5868.735906884115;5900.763969441346;5980.162777211107;6042.546808989431;6071.848203794941;6115.205023346936;6121.112438696453;6133.478654314434;6149.169862068564;6154.9384506154365;5560.304961879765;6075.908861367171;6095.634261944298;6095.969520152371;6097.2683278006925;5880.323762767336;5963.582549221513;5981.431763982415;5993.3706960768495;6034.24571928509;6043.214382130295;6044.969775151096;6067.271927368744;6096.847578810565;6100.481459076028;5790.536757059341;5882.588218824812;5896.086106653475;5899.321578018411;5919.171316015675 09:39:13 INFO - PROCESS | 2398 | |1;ext.html;556.9721513924303;587.0665641629807;593.397701333147;669.9147381242386;669.9631520266385;31601.525977110363;32170.03489895304;32258.83870580647;32289.838550807213;32399.51400728985;42069.15861682765;42298.57701422985;42334.72995810131;42367.57632423683;42392.576074239245;9607.56765945534;9821.754456138618;9832.26258030647;9833.131704976031;9840.950795246014;9586.90413095867;9751.463669498175;9756.4633945133;9764.609415623367;9779.706608801747;9746.90253097467;9780.364276322052;9803.90196098037;9910.950445247763;9937.602495900155;9807.901920980807;9872.062154095349;9874.210063194963;9887.90112098877;9902.356346837505;9659;9798.65704700332;9816.165625921805;9822.557984890696;9891.703248902544;42571;42909.785451072545;43000;43072.56927430717;43210.70367888968;3399.9830000850084;3495.318412909484;3519.208178159919;3544.2557063016798;3736.4955730976244;25130.67112690779;29562;29824.701752982626;30231.244218894484;30463.543046854316;2777.264209868832;2783.109404990404;2799.5800629905675;2806.256342069353;2807.6209711688866;491723;695402;695688;698641;714110.4294478494;73568;74004.62997684977;74565.25434745691;74597.25402746012;74888;75828.62085689646;76690.23309766832;76978;77159.61420192863;78378;17593.824061759475;17889.642207155783;17912.641747165246;17934;17951.461456156336;14024.649383765487;14032.298385080705;14072.155670659793;14106.576802695936;14115.78826317606;23065.7693423067;23185.420364491023;23393.84183135289;23443.648345274836;23459;9040.728778136667;9349.39228950116;9390.34267601275;9403.43579385239;9420.717378478657;944.593824655391;1015.2537884654773;1029.5418538750184;1045.4250162410644;1104.045001074065;30177.396452071276;30262.940797072;30699.154432537744;30779.69220307813;31121.68878311233;1096.0026375997804;1129.796766443735;1151.648747132116;1177.5054477119631;1181.527389044389;15059.623509412353;15415.614609634624;15498.225088745743;15827.683446331239;15913.920430398006;550038;555277;556470;557079;559061;62315.6884215576;62650;62680;62775.6861215691;62836.37163628397;1409.344654735553;1528.99235503824;1569.7723830044579;1590.8886377953663;1671.0057515778092;29645.25886852846;30710.539341909443;30751.694762732786;30847.63546428821;31017.8449107753;48879.75560122177;49705.75735606639;49901;49993;50018.24972625341;374249;377070;377115;377501;377851.3322300223;29219.85390073036;35493.467597986564;35554.8222258887;36212.63787362093;36821;36707.89876303715;45379.546204538856;45828.625141245815;46482.53517464782;46838.531614683416;36490.8175459121;37649;37853.242935141694;37964.62035379611;38518;36724.265514690094;37554.62445375621;37711;38190;38234;37442;38080;38134.618653814214;38367.6163238364;38852.222955540165;28208.632557214594;31413;31755.29496410078;33108.006759797245;33639.83180084084;25209.24372268835;25266.747332527175;25420.237392878244;25475.745242548077;25876.48247035011 09:39:13 INFO - PROCESS | 2398 | |2;jquery.html;375.7125798763945;379.2945122072945;380.5851621732313;385.51040178972687;385.9228154369125;20096.59806803863;21788.128474861005;22093.889530552366;22528.324150275475;22588.20941267056;21443.356699299005;21546.138154473803;21976.34070977869;22033.669494957587;22330.776692233074;5814.622049566786;5837.066069428893;6008.489278411318;6060.575759696823;6061.242344706911;4545.409096817409;5875.88248235035;5935.673537955411;5946.791862284821;6137.754489820401;5775.451332123442;5859.6484210947265;5882.2353094097925;5895.410458954092;5902.586818922677;5704.11586204137;5835.708214589275;5861.677607731573;5896.616719913192;5901.675407852566;4728.865072382603;4935.457099719028;5068.619853511002;5129.307543481619;5146.871328216787;26637.200883973317;26752.19743407701;26999.460010799674;27113.72886271132;27153.86423067892;2186.9562608747738;2275.5676421479866;2278.111536500766;2401.363638635763;2406.6991626046743;13713.725725485534;15180.240987950558;15219.315130819139;15241.695166096615;15582.766258506019;1696.4316953820485;1723.6811189929779;1741.3731056819536;1745.642143360608;1806.9186886590003;84031;99369.50315248377;101229;103210;103242.48378758058;9231.35380523357;10504;10703.357798532114;10892.61875834342;10907.563697452171;11261.66215013551;13578.117422367515;13578.253196074113;13587.32063396846;13605.455781768618;8792.076831932683;8973.50645714492;8976.70701866706;9057.592408341641;9164.770880727901;9385.5307234638;9397.436153830791;9419.90580094204;9457.905420945703;9478.620855165855;6318.71565779541;6358.904616430757;6360.236771587439;6363.268224154282;6402.487800976004;1622.4321487479287;1821.897751860132;1825.2607693884;1857.9163937622839;1901.4010586665156;868.522312727997;969.030969030969;983.4246965525105;989.6536212325628;1020.6733845169474;20741.896290518453;21928.903554822195;21999.450013749465;22099.78451185168;22106.668399974013;1024.9077583017565;1043.3479075577764;1045.7385653586603;1085.5332207150843;1116.9720756980978;9740.554848053509;11555.88444115548;11868.287902725864;11886.167968242313;12019.579314723942;674234.6288268528;675761;687060;692583.5370823113;694654;111216.44391778151;112998;113732;114729.42635286937;120526;1078.3314345106085;1090.345792524479;1135.3131355529952;1152.3898370191523;1153.7000379901285;16986.065766382966;18485.89084654925;18585;19050.333238336592;19451.027448627796;42277.57722422737;50650.746746266035;52348.738256308476;53191;54000;72454.55090898047;76133;76866.23133768591;78839;79644;6668.833279167866;7835.921640783519;8221.876671850046;8233.50598964064;8609.268212202032;9472.28957828152;12893.742125157632;13460.326983650973;13924.4430222794;13946.30268486592;10534.36793792375;11463.140264480031;11832.467538960595;11888.167828252286;12163.209391389531;9413.917399499145;11397.658070257905;11775;11919.106067045179;12047.81928271059;6847.452203823583;9988.300818942604;11685.883141168479;11872.406379681153;12099.153059285765;8147;8269.09040005611;8423.031351394424;8495.980482342158;8603.52680602573;10432.843507347548;11886.108541859569;12009.63971080869;12061.276323420623;12063.39683015828 09:39:13 INFO - PROCESS | 2398 | |3;mootools.html;1298.863619319972;1637.0586912525298;1653.5370096373017;2032.5731596364762;2110.7783682713307;8427.410081294312;8933.821323573533;9114.179723824855;9124.954375228115;9128.406653567521;8856.335774816882;9142.040085790979;9153.130452607009;9156.313276504272;9159.175674189322;2185.267935241696;2493.0651005872796;2553.0426090216215;2556.833805802626;2584.5735453650127;2574.214864466346;2581.380468687511;2583.599542070982;2584.625229341744;2594.792416606667;2480.541099896525;2578.355411147213;2580.6774153230845;2581.5224183526016;2581.586946088626;2362.9248691845255;2376.6197408414655;2563.218218443374;2577.8195526313166;2585.5863061910004;2537.835040722347;2553.885075171622;2575.2789219018705;2582.7029891562333;2586.8189226754134;9083.137101975302;9129.81740365189;9134.680286190018;9142.040085790963;9165.816683666357;5466.835994920159;5590.245316882209;5601.271834661468;5604.8318550443555;5607.579431542651;3628.2743451309843;3669.486271921933;3687.4284485904727;3688.3360995020885;3714.53568303962;935.6865450074233;1069.2354966199127;1086.7989421956884;1093.9945300273528;1118.8769235384102;12334.75330493394;12348.629541113782;12369.505219791197;12370.690732731664;12388.194767340094;4967.379077615298;4988.625853060962;5064.898702026013;5076.466970967996;5092;10496.737581560523;10502.002309780559;10523.052925236765;10524.526396312185;10534.051935325857;5705.543556515552;5711.057675483498;5714.257146570919;5719.7140142992685;5719.799807006735;4624.167649822997;4806.351142595775;5009.198528235464;5164.04465173944;5170.327857378592;4044.636577796799;4099.442211959496;4124.505059392892;4148.605882441163;4150.64719498846;1340.8886615448055;1363.6659018540522;1366.6241783509415;1367.6785955300497;1374.896882733799;1408.3310427546833;1421.6161636358331;1445.147363055802;1446.681730019394;1476.320892389513;8502.319814414832;8560.186782255676;8582.098879617675;8583.957080214559;8588.699395521127;524.9238860365226;532.5207313417955;535.8419266316446;538.8922215556904;543.5135553679435;6369.331220221903;6375.3624637536805;6405.935940640534;6410.038494225903;6415.390537898892;10782.56869725218;11202.991730744272;11294.983451489405;11328.546858125748;11365.886341136647;10386.896131038593;10755.300905441121;11132.05377542918;11168.497417616227;11179.944100279447;8534.231919127309;8588.141185881363;8592.8711069334;8593.097724738938;8629.39594228398;6989.860202796017;8293.875591866248;8306.003279606486;8362.832743345221;8396.790080247798;9442.291828112782;9777.462239576891;9809.117179453884;9882.555285012335;10048.196144308584;9878.753031174278;10052.798944021224;10301.175905927657;10317.74205644835;10328.173746100445;6868;7391.1869694334655;7600.935868978458;7710.036245469317;7730.420218483749;8529.914700852913;8535.103814099679;8584.613692383982;8595;8622.439541429787;7505.174430812715;7560.614150204307;7672.808179795326;7742.574158421341;7956.2043795619975;7358.66885990121;7426.962865185856;7436;7535.171131175676;7610.505317154589;7956.761297161094;8021.117677055636;8030.83938321242;8043.959780201061;8055.154208808225;7186.353228209487;7235.2402997684485;7317.378022868116;7331.3768329692575;7346.6326683666675;7395.408367330493;7408.111026676834;7448.813779655552;7453.366463850633;7475.738349157753 09:39:13 INFO - PROCESS | 2398 | |4;prototype.html;335.83040564514926;338.6342749830184;345.23185911347247;346.7659329952282;349.9352619765344;6684.5989240645595;6712.765053223138;6741.089952856363;6754.088198093256;6755.1556055493065;5653.519450846683;6525.314841941599;6616.96691516543;6642.734290628369;6665.700043498042;4737.573618374346;4862.343583616202;4869.780859861315;5019.32239147715;5063.873403164914;4975.7263350515705;5013.818988739909;5027.547520723134;5033.320501732274;5038;4968.130577149002;4974.87562810929;4975.776090075937;4978.904640978979;4980.626453016021;4718.457377401592;4801.375821143247;4879.975600121977;4906.386701662293;4916.827911023132;4212.810423530948;4216.873493795191;4226.894327641803;4231.090315582164;4239.173361194568;6263.6241825490615;6378.808635740936;6395.6482393468805;6402.775902843378;6414.679266036679;5886.735096920606;5889.79385721502;5894.734736936848;5911;5923.466887980059;5653.830385088454;7120.18117916441;7124.608146551937;7130.28697130288;7150.39221666159;4562.224421848294;4598.471175814757;4599.586037256663;4618;4625.976870115662;7359.632018399112;7492.250774922519;7520.548767073993;7525.5860927648955;7525.811854703621;4706.670533062653;4709.505501922317;4716.481187069419;4717.8112875485285;4720.527947205321;4758.500357462486;4772.618190544748;4779.617630589544;4785.353977213102;4787.162246606899;5696.458836410535;5697.857553561112;5710.828675139753;5716.685582293014;5723;5889.204957330706;6736.562123461959;7073.151221853411;7116.573005619679;7160.248173941765;1617.2156504095503;1658.336665333876;1658.3946859396233;1658.4195531563855;1660.2031025108015;985.7042887133831;1033.8190816607064;1037.5175543372368;1050.2858056521486;1070.3417398300146;5440.564754819605;6516.869662606721;6562.8359291018105;6591.34086591337;6614;7313.451491138186;7351.22812104732;7370.6314684266645;7378.262173782578;7388.926110738931;6579.934200658028;6582.3746744059235;6593.175853018372;6602.86794264112;6642.468602511787;3866.2267546490816;3897.0647044709067;3916.980415097906;3927.1752931884625;3935;7432.85134297311;7655.387568994467;7674.117476490275;7690.154083050972;7708.537487750753;6466.935330646727;7645.617719114022;7661.272179142972;7721.112072111779;7724.227577242231;7736.922630773733;7752.186020467882;7757.806054848674;7760.650770715331;7767.262110099531;7160.355567998697;7424.517406368569;7442;7451.664675089525;7458.664360103699;7269;7285.7814265572115;7286.48994570375;7303.379212766974;7318.048653675201;6518.315576864361;7618.047744031996;7740.496867703581;7749.070111586646;7772.183920688246;4663.790129444251;4699.624030077517;4721.858344249678;4725.952740472689;4734.313524538991;7214.74748383804;7414.443916706161;7414.851702966019;7424.071991001125;7442.106947166375;7070.964645176741;7111.608861512666;7119;7131.607761573163;7144.142702875688;7055.541389809646;7076.504644674823;7128.679209435484;7217.6030318333;7226.891596625949;7021.5787052777;7051.048108505286;7051.294870512907;7064.434845212473;7070.39901608369;6868.553544019623;7128.251533589106;7188.5327453715345;7201.279872012757;7210.4231661468;6682.164729408824;6813;6848.7260509579055;7011.263817299314;7017.122859642544 09:39:13 INFO - PROCESS | 2398 | |5;yui.html;742.8402893377922;757.7575175943698;770.9707540433521;785.5757890738997;794.285143370966;15363.3086511107;15778.605534861634;15779.368825246991;15789.84210157898;15800.68398632028;14463.34914928828;14520.854791452082;14544;14608.123512589253;14626;763.8663233934067;781.0315209140653;960.6205548808216;999.3703966501131;999.6451259802774;973.8052389522088;983.1544871410581;983.3706427886159;984.2273815055183;985.0100648847898;813.4305985809957;1044.926855120142;1049.790041991601;1049.9972526237464;1050.6322787024558;1024.7028361775076;1038.2939601071268;1038.7922415516928;1039.4179259614602;1039.8180318444279;770.0143815915637;772.8956590860219;773.9729109481198;774.6359211170741;775.2944820213635;15261.771073433907;15263.160526171056;15285.312160952784;15298.617534561614;15362.615934601612;13265.933670331684;13321.933390332986;13524.25616591087;13588;13854.93072534641;10493.947530262376;10525.052745252888;10598.576056957712;10828.458577071115;10841.295315804526;3020.8187508749324;3079.630444346693;3627.455881617752;3714.4242642390473;3823.1589050408866;88667;90678;90703.54648226783;91884;92843.53578232066;9935.850962235572;10012.799744005079;10115.544800483996;10130.341527800816;10845.783084338269;24140.275791726275;24168.758312417;24178.637320440208;24190.274291771275;24269.87865060699;7474.289942455458;7603.581803000888;7659.502132361378;7720;7791.4935529190425;3872.206197729459;4075.7146999709735;4217.683673724483;4252.255855225323;4266.509351424564;3786.4888240087234;3827.8468861245237;3828.693704503633;3834.4631751554803;3841.750286231386;632.4466092169351;641.1344684675744;645.5932762359777;670.8389986403228;702.8067281497629;507.24167369781674;566.3317285603028;619.1332135010931;628.1582679209881;645.141961191614;7890.368770498347;8340.207680270365;8358.498490090615;8457.584703220335;8462.915370846213;551.6524589508664;613.929398119213;615.8891399548124;619.0899377914459;623.1338439569003;3776.3013842439236;3978.6618137458063;4001.4397984282223;4047.1298670785923;4051.7366371186367;94970;98779;98819.50590247003;98836;99039.00960990442;80424;83739;85116.72324915131;86660;86945.26109477774;63825.680871595345;63841.99999999907;64381.35618643754;66287.66856165689;66842.66578667075;7744.65149068282;8528.403011789114;8609.91390086091;8670.653173872974;8940.195382415613;41764.58235417607;42320.788396057826;47260.7636961813;48908.51091489182;50268.49731502638;68557.31442685709;68686.65656671685;68958;70430.64784676043;74433.62783186266;11170.609028683957;11193.27243729155;11246.775064498828;11414.714632133931;11527.942360288145;11882.940585297018;12086.093542984487;12107.878921211028;12489.125761196628;12554.246745195318;11718.94140529326;11739.941300293443;11838.704032399259;11872.643820685393;11935.761284774082;11616.535338586362;11625.47685354178;11892.10809189297;12195.329256890956;12208;11393.202475826612;11650.941745291559;11760.411979401168;11914;12102.697432564257;9433.240801895077;9482.051794820463;9611.279154063614;9665.178459830711;9672.613095476383;9425;9557.713268601952;9617.519124043909;9630.807383852143;9839.212862971088 09:39:13 INFO - PROCESS | 2398 | __end_tp_report 09:39:13 INFO - PROCESS | 2398 | __start_cc_report 09:39:13 INFO - PROCESS | 2398 | _x_x_mozilla_cycle_collect,1015 09:39:13 INFO - PROCESS | 2398 | __end_cc_report 09:39:13 INFO - PROCESS | 2398 | __startTimestamp1460997553512__endTimestamp 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | ------- Summary: start ------- 09:39:13 INFO - PROCESS | 2398 | Number of tests: 6 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | [#0] dojo.html Cycles:165 Average:5716.27 Median:5900.76 stddev:672.57 (11.4%) stddev-sans-first:639.41 09:39:13 INFO - PROCESS | 2398 | Values: 2978.0 3378.4 3451.0 3469.9 3489.2 5670.6 5672.9 5685.3 5691.5 5701.8 5683.5 5730.6 5733.7 5736.6 5738.9 5098.5 5157.3 5157.8 5228.4 5237.3 5170.8 5203.8 5211.2 5215.1 5229.1 4919.6 5185.6 5222.2 5235.4 5243.0 4355.5 4380.2 4750.7 5235.4 5277.2 5076.4 5168.8 5199.6 5202.3 5242.7 5586.9 5617.2 5629.5 5633.3 5637.0 6082.6 6304.9 6306.2 6307.2 6329.2 6246.5 6248.8 6268.2 6310.8 6324.5 5814.0 5842.2 5859.9 5862.1 5910.8 6185.4 6200.9 6277.5 6282.9 6283.7 6130.8 6158.3 6170.9 6174.7 6178.3 6293.7 6297.9 6307.8 6308.0 6313.4 6050.6 6160.2 6207.9 6234.6 6250.9 5280.5 6224.8 6266.9 6290.1 6297.8 4901.0 4911.8 4911.9 4916.8 4929.1 3821.8 4346.5 4376.5 4451.4 4467.8 5716.4 5724.3 5728.4 5739.1 5762.6 6314.8 6315.4 6317.8 6318.7 6343.8 5542.4 5687.1 5687.9 5705.4 5747.2 5311.4 5339.0 5341.7 5356.2 5377.7 6253.1 6289.3 6299.4 6317.6 6323.6 6117.3 6219.8 6300.9 6325.5 6329.7 6279.4 6286.1 6291.3 6306.2 6309.4 6193.9 6216.9 6222.6 6230.7 6237.2 5868.7 5900.8 5980.2 6042.5 6071.8 6115.2 6121.1 6133.5 6149.2 6154.9 5560.3 6075.9 6095.6 6096.0 6097.3 5880.3 5963.6 5981.4 5993.4 6034.2 6043.2 6045.0 6067.3 6096.8 6100.5 5790.5 5882.6 5896.1 5899.3 5919.2 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | [#1] ext.html Cycles:180 Average:68113.99 Median:207852.29 stddev:145831.86 (70.2%) stddev-sans-first:146152.74 09:39:13 INFO - PROCESS | 2398 | Values: 557.0 587.1 593.4 669.9 670.0 31601.5 32170.0 32258.8 32289.8 32399.5 42069.2 42298.6 42334.7 42367.6 42392.6 9607.6 9821.8 9832.3 9833.1 9841.0 9586.9 9751.5 9756.5 9764.6 9779.7 9746.9 9780.4 9803.9 9911.0 9937.6 9807.9 9872.1 9874.2 9887.9 9902.4 9659.0 9798.7 9816.2 9822.6 9891.7 42571.0 42909.8 43000.0 43072.6 43210.7 3400.0 3495.3 3519.2 3544.3 3736.5 25130.7 29562.0 29824.7 30231.2 30463.5 2777.3 2783.1 2799.6 2806.3 2807.6 491723.0 695402.0 695688.0 698641.0 714110.4 73568.0 74004.6 74565.3 74597.3 74888.0 75828.6 76690.2 76978.0 77159.6 78378.0 17593.8 17889.6 17912.6 17934.0 17951.5 14024.6 14032.3 14072.2 14106.6 14115.8 23065.8 23185.4 23393.8 23443.6 23459.0 9040.7 9349.4 9390.3 9403.4 9420.7 944.6 1015.3 1029.5 1045.4 1104.0 30177.4 30262.9 30699.2 30779.7 31121.7 1096.0 1129.8 1151.6 1177.5 1181.5 15059.6 15415.6 15498.2 15827.7 15913.9 550038.0 555277.0 556470.0 557079.0 559061.0 62315.7 62650.0 62680.0 62775.7 62836.4 1409.3 1529.0 1569.8 1590.9 1671.0 29645.3 30710.5 30751.7 30847.6 31017.8 48879.8 49705.8 49901.0 49993.0 50018.2 374249.0 377070.0 377115.0 377501.0 377851.3 29219.9 35493.5 35554.8 36212.6 36821.0 36707.9 45379.5 45828.6 46482.5 46838.5 36490.8 37649.0 37853.2 37964.6 38518.0 36724.3 37554.6 37711.0 38190.0 38234.0 37442.0 38080.0 38134.6 38367.6 38852.2 28208.6 31413.0 31755.3 33108.0 33639.8 25209.2 25266.7 25420.2 25475.7 25876.5 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | [#2] jquery.html Cycles:180 Average:36736.42 Median:2339.74 stddev:112882.10 (4824.6%) stddev-sans-first:113165.74 09:39:13 INFO - PROCESS | 2398 | Values: 375.7 379.3 380.6 385.5 385.9 20096.6 21788.1 22093.9 22528.3 22588.2 21443.4 21546.1 21976.3 22033.7 22330.8 5814.6 5837.1 6008.5 6060.6 6061.2 4545.4 5875.9 5935.7 5946.8 6137.8 5775.5 5859.6 5882.2 5895.4 5902.6 5704.1 5835.7 5861.7 5896.6 5901.7 4728.9 4935.5 5068.6 5129.3 5146.9 26637.2 26752.2 26999.5 27113.7 27153.9 2187.0 2275.6 2278.1 2401.4 2406.7 13713.7 15180.2 15219.3 15241.7 15582.8 1696.4 1723.7 1741.4 1745.6 1806.9 84031.0 99369.5 101229.0 103210.0 103242.5 9231.4 10504.0 10703.4 10892.6 10907.6 11261.7 13578.1 13578.3 13587.3 13605.5 8792.1 8973.5 8976.7 9057.6 9164.8 9385.5 9397.4 9419.9 9457.9 9478.6 6318.7 6358.9 6360.2 6363.3 6402.5 1622.4 1821.9 1825.3 1857.9 1901.4 868.5 969.0 983.4 989.7 1020.7 20741.9 21928.9 21999.5 22099.8 22106.7 1024.9 1043.3 1045.7 1085.5 1117.0 9740.6 11555.9 11868.3 11886.2 12019.6 674234.6 675761.0 687060.0 692583.5 694654.0 111216.4 112998.0 113732.0 114729.4 120526.0 1078.3 1090.3 1135.3 1152.4 1153.7 16986.1 18485.9 18585.0 19050.3 19451.0 42277.6 50650.7 52348.7 53191.0 54000.0 72454.6 76133.0 76866.2 78839.0 79644.0 6668.8 7835.9 8221.9 8233.5 8609.3 9472.3 12893.7 13460.3 13924.4 13946.3 10534.4 11463.1 11832.5 11888.2 12163.2 9413.9 11397.7 11775.0 11919.1 12047.8 6847.5 9988.3 11685.9 11872.4 12099.2 8147.0 8269.1 8423.0 8496.0 8603.5 10432.8 11886.1 12009.6 12061.3 12063.4 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | [#3] mootools.html Cycles:180 Average:6266.23 Median:534.18 stddev:3323.86 (622.2%) stddev-sans-first:3312.20 09:39:13 INFO - PROCESS | 2398 | Values: 1298.9 1637.1 1653.5 2032.6 2110.8 8427.4 8933.8 9114.2 9125.0 9128.4 8856.3 9142.0 9153.1 9156.3 9159.2 2185.3 2493.1 2553.0 2556.8 2584.6 2574.2 2581.4 2583.6 2584.6 2594.8 2480.5 2578.4 2580.7 2581.5 2581.6 2362.9 2376.6 2563.2 2577.8 2585.6 2537.8 2553.9 2575.3 2582.7 2586.8 9083.1 9129.8 9134.7 9142.0 9165.8 5466.8 5590.2 5601.3 5604.8 5607.6 3628.3 3669.5 3687.4 3688.3 3714.5 935.7 1069.2 1086.8 1094.0 1118.9 12334.8 12348.6 12369.5 12370.7 12388.2 4967.4 4988.6 5064.9 5076.5 5092.0 10496.7 10502.0 10523.1 10524.5 10534.1 5705.5 5711.1 5714.3 5719.7 5719.8 4624.2 4806.4 5009.2 5164.0 5170.3 4044.6 4099.4 4124.5 4148.6 4150.6 1340.9 1363.7 1366.6 1367.7 1374.9 1408.3 1421.6 1445.1 1446.7 1476.3 8502.3 8560.2 8582.1 8584.0 8588.7 524.9 532.5 535.8 538.9 543.5 6369.3 6375.4 6405.9 6410.0 6415.4 10782.6 11203.0 11295.0 11328.5 11365.9 10386.9 10755.3 11132.1 11168.5 11179.9 8534.2 8588.1 8592.9 8593.1 8629.4 6989.9 8293.9 8306.0 8362.8 8396.8 9442.3 9777.5 9809.1 9882.6 10048.2 9878.8 10052.8 10301.2 10317.7 10328.2 6868.0 7391.2 7600.9 7710.0 7730.4 8529.9 8535.1 8584.6 8595.0 8622.4 7505.2 7560.6 7672.8 7742.6 7956.2 7358.7 7427.0 7436.0 7535.2 7610.5 7956.8 8021.1 8030.8 8044.0 8055.2 7186.4 7235.2 7317.4 7331.4 7346.6 7395.4 7408.1 7448.8 7453.4 7475.7 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | [#4] prototype.html Cycles:180 Average:5831.68 Median:6592.26 stddev:1852.71 (28.1%) stddev-sans-first:1811.40 09:39:13 INFO - PROCESS | 2398 | Values: 335.8 338.6 345.2 346.8 349.9 6684.6 6712.8 6741.1 6754.1 6755.2 5653.5 6525.3 6617.0 6642.7 6665.7 4737.6 4862.3 4869.8 5019.3 5063.9 4975.7 5013.8 5027.5 5033.3 5038.0 4968.1 4974.9 4975.8 4978.9 4980.6 4718.5 4801.4 4880.0 4906.4 4916.8 4212.8 4216.9 4226.9 4231.1 4239.2 6263.6 6378.8 6395.6 6402.8 6414.7 5886.7 5889.8 5894.7 5911.0 5923.5 5653.8 7120.2 7124.6 7130.3 7150.4 4562.2 4598.5 4599.6 4618.0 4626.0 7359.6 7492.3 7520.5 7525.6 7525.8 4706.7 4709.5 4716.5 4717.8 4720.5 4758.5 4772.6 4779.6 4785.4 4787.2 5696.5 5697.9 5710.8 5716.7 5723.0 5889.2 6736.6 7073.2 7116.6 7160.2 1617.2 1658.3 1658.4 1658.4 1660.2 985.7 1033.8 1037.5 1050.3 1070.3 5440.6 6516.9 6562.8 6591.3 6614.0 7313.5 7351.2 7370.6 7378.3 7388.9 6579.9 6582.4 6593.2 6602.9 6642.5 3866.2 3897.1 3917.0 3927.2 3935.0 7432.9 7655.4 7674.1 7690.2 7708.5 6466.9 7645.6 7661.3 7721.1 7724.2 7736.9 7752.2 7757.8 7760.7 7767.3 7160.4 7424.5 7442.0 7451.7 7458.7 7269.0 7285.8 7286.5 7303.4 7318.0 6518.3 7618.0 7740.5 7749.1 7772.2 4663.8 4699.6 4721.9 4726.0 4734.3 7214.7 7414.4 7414.9 7424.1 7442.1 7071.0 7111.6 7119.0 7131.6 7144.1 7055.5 7076.5 7128.7 7217.6 7226.9 7021.6 7051.0 7051.3 7064.4 7070.4 6868.6 7128.3 7188.5 7201.3 7210.4 6682.2 6813.0 6848.7 7011.3 7017.1 09:39:13 INFO - PROCESS | 2398 | 09:39:13 INFO - PROCESS | 2398 | [#5] yui.html Cycles:180 Average:19012.80 Median:48084.64 stddev:27038.62 (56.2%) stddev-sans-first:27079.67 09:39:13 INFO - PROCESS | 2398 | Values: 742.8 757.8 771.0 785.6 794.3 15363.3 15778.6 15779.4 15789.8 15800.7 14463.3 14520.9 14544.0 14608.1 14626.0 763.9 781.0 960.6 999.4 999.6 973.8 983.2 983.4 984.2 985.0 813.4 1044.9 1049.8 1050.0 1050.6 1024.7 1038.3 1038.8 1039.4 1039.8 770.0 772.9 774.0 774.6 775.3 15261.8 15263.2 15285.3 15298.6 15362.6 13265.9 13321.9 13524.3 13588.0 13854.9 10493.9 10525.1 10598.6 10828.5 10841.3 3020.8 3079.6 3627.5 3714.4 3823.2 88667.0 90678.0 90703.5 91884.0 92843.5 9935.9 10012.8 10115.5 10130.3 10845.8 24140.3 24168.8 24178.6 24190.3 24269.9 7474.3 7603.6 7659.5 7720.0 7791.5 3872.2 4075.7 4217.7 4252.3 4266.5 3786.5 3827.8 3828.7 3834.5 3841.8 632.4 641.1 645.6 670.8 702.8 507.2 566.3 619.1 628.2 645.1 7890.4 8340.2 8358.5 8457.6 8462.9 551.7 613.9 615.9 619.1 623.1 3776.3 3978.7 4001.4 4047.1 4051.7 94970.0 98779.0 98819.5 98836.0 99039.0 80424.0 83739.0 85116.7 86660.0 86945.3 63825.7 63842.0 64381.4 66287.7 66842.7 7744.7 8528.4 8609.9 8670.7 8940.2 41764.6 42320.8 47260.8 48908.5 50268.5 68557.3 68686.7 68958.0 70430.6 74433.6 11170.6 11193.3 11246.8 11414.7 11527.9 11882.9 12086.1 12107.9 12489.1 12554.2 11718.9 11739.9 11838.7 11872.6 11935.8 11616.5 11625.5 11892.1 12195.3 12208.0 11393.2 11650.9 11760.4 11914.0 12102.7 9433.2 9482.1 9611.3 9665.2 9672.6 9425.0 9557.7 9617.5 9630.8 9839.2 09:39:13 INFO - PROCESS | 2398 | -------- Summary: end -------- 09:39:13 INFO - PROCESS | 2398 | 09:39:14 INFO - TEST-INFO | 2398: exit 0 09:39:14 INFO - TEST-OK | dromaeo_css | took 1112454ms 09:39:14 INFO - TEST-START | kraken 09:39:14 INFO - operating with platform_type : mac_ 09:39:14 INFO - Initialising browser for kraken test... 09:39:14 INFO - TEST-INFO | started process 2401 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpkhT_BJ/profile http://localhost:49822/getInfo.html) 09:39:16 INFO - PROCESS | 2401 | __metrics Screen width/height:1600/1200 09:39:16 INFO - PROCESS | 2401 | colorDepth:24 09:39:16 INFO - PROCESS | 2401 | Browser inner width/height: 1024/689 09:39:16 INFO - PROCESS | 2401 | __metrics 09:39:16 INFO - PROCESS | 2401 | 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" 09:39:16 INFO - PROCESS | 2401 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:17 INFO - TEST-INFO | 2401: exit 0 09:39:17 INFO - Browser initialized. 09:39:17 INFO - Running cycle 1/1 for kraken test... 09:39:17 INFO - TEST-INFO | started process 2403 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpkhT_BJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:29 INFO - PROCESS | 2403 | RSS: Main: 207749120 09:39:29 INFO - PROCESS | 2403 | 09:39:31 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49822/tests/kraken/driver.html?testName=audio-beat-detection) 09:39:32 INFO - PROCESS | 2403 | RSS: Main: 262737920 09:39:32 INFO - PROCESS | 2403 | 09:39:34 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49822/tests/kraken/driver.html?testName=audio-dft) 09:39:35 INFO - PROCESS | 2403 | RSS: Main: 273354752 09:39:35 INFO - PROCESS | 2403 | 09:39:38 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49822/tests/kraken/driver.html?testName=audio-fft) 09:39:39 INFO - PROCESS | 2403 | RSS: Main: 263737344 09:39:39 INFO - PROCESS | 2403 | 09:39:41 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49822/tests/kraken/driver.html?testName=audio-oscillator) 09:39:41 INFO - PROCESS | 2403 | RSS: Main: 265076736 09:39:41 INFO - PROCESS | 2403 | 09:39:43 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49822/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:39:44 INFO - PROCESS | 2403 | RSS: Main: 264773632 09:39:44 INFO - PROCESS | 2403 | 09:39:47 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49822/tests/kraken/driver.html?testName=imaging-darkroom) 09:39:47 INFO - PROCESS | 2403 | RSS: Main: 265023488 09:39:47 INFO - PROCESS | 2403 | 09:39:50 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49822/tests/kraken/driver.html?testName=imaging-desaturate) 09:39:51 INFO - PROCESS | 2403 | RSS: Main: 265105408 09:39:51 INFO - PROCESS | 2403 | 09:39:54 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49822/tests/kraken/driver.html?testName=json-parse-financial) 09:39:54 INFO - PROCESS | 2403 | RSS: Main: 265474048 09:39:54 INFO - PROCESS | 2403 | 09:39:56 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49822/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:39:56 INFO - PROCESS | 2403 | RSS: Main: 300032000 09:39:56 INFO - PROCESS | 2403 | 09:39:58 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:39:59 INFO - PROCESS | 2403 | RSS: Main: 300994560 09:39:59 INFO - PROCESS | 2403 | 09:40:01 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:01 INFO - PROCESS | 2403 | RSS: Main: 305655808 09:40:01 INFO - PROCESS | 2403 | 09:40:04 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:40:05 INFO - PROCESS | 2403 | RSS: Main: 307716096 09:40:05 INFO - PROCESS | 2403 | 09:40:07 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:08 INFO - PROCESS | 2403 | RSS: Main: 307781632 09:40:08 INFO - PROCESS | 2403 | 09:40:09 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:49822/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49822/tests/kraken/driver.html?testName=ai-astar) 09:40:10 INFO - PROCESS | 2403 | RSS: Main: 370188288 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | __start_tp_report 09:40:10 INFO - PROCESS | 2403 | _x_x_mozilla_page_load 09:40:10 INFO - PROCESS | 2403 | _x_x_mozilla_page_load_details 09:40:10 INFO - PROCESS | 2403 | |i|pagename|runs| 09:40:10 INFO - PROCESS | 2403 | |0;ai-astar;114;115;108;110;133;112;120;111;111;110 09:40:10 INFO - PROCESS | 2403 | |1;audio-beat-detection;154;121;142;157;142;171;138;118;160;116 09:40:10 INFO - PROCESS | 2403 | |2;audio-dft;184;186;182;186;189;184;182;185;186;185 09:40:10 INFO - PROCESS | 2403 | |3;audio-fft;71;76;77;119;58;65;82;86;148;48 09:40:10 INFO - PROCESS | 2403 | |4;audio-oscillator;94;95;127;78;81;86;118;80;78;117 09:40:10 INFO - PROCESS | 2403 | |5;imaging-gaussian-blur;86;83;88;128;86;83;86;85;86;82 09:40:10 INFO - PROCESS | 2403 | |6;imaging-darkroom;93;92;102;148;91;92;92;93;92;92 09:40:10 INFO - PROCESS | 2403 | |7;imaging-desaturate;92;89;90;89;89;92;90;89;94;140 09:40:10 INFO - PROCESS | 2403 | |8;json-parse-financial;57;60;59;58;60;60;59;70;50;53 09:40:10 INFO - PROCESS | 2403 | |9;json-stringify-tinderbox;65;60;68;74;72;71;66;65;66;66 09:40:10 INFO - PROCESS | 2403 | |10;stanford-crypto-aes;81;79;81;79;79;79;77;78;79;84 09:40:10 INFO - PROCESS | 2403 | |11;stanford-crypto-ccm;122;118;126;106;137;124;103;124;127;137 09:40:10 INFO - PROCESS | 2403 | |12;stanford-crypto-pbkdf2;159;168;170;116;153;132;133;134;129;128 09:40:10 INFO - PROCESS | 2403 | |13;stanford-crypto-sha256-iterative;66;68;64;66;65;65;63;66;66;65 09:40:10 INFO - PROCESS | 2403 | __end_tp_report 09:40:10 INFO - PROCESS | 2403 | __start_cc_report 09:40:10 INFO - PROCESS | 2403 | _x_x_mozilla_cycle_collect,1224 09:40:10 INFO - PROCESS | 2403 | __end_cc_report 09:40:10 INFO - PROCESS | 2403 | __startTimestamp1460997610032__endTimestamp 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | ------- Summary: start ------- 09:40:10 INFO - PROCESS | 2403 | Number of tests: 14 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#0] ai-astar Cycles:10 Average:114.40 Median:113.00 stddev:7.35 (6.5%) stddev-sans-first:7.80 09:40:10 INFO - PROCESS | 2403 | Values: 114.0 115.0 108.0 110.0 133.0 112.0 120.0 111.0 111.0 110.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:148.00 stddev:18.98 (12.8%) stddev-sans-first:19.62 09:40:10 INFO - PROCESS | 2403 | Values: 154.0 121.0 142.0 157.0 142.0 171.0 138.0 118.0 160.0 116.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:2.08 (1.1%) stddev-sans-first:2.18 09:40:10 INFO - PROCESS | 2403 | Values: 184.0 186.0 182.0 186.0 189.0 184.0 182.0 185.0 186.0 185.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#3] audio-fft Cycles:10 Average:83.00 Median:73.50 stddev:29.69 (40.4%) stddev-sans-first:31.17 09:40:10 INFO - PROCESS | 2403 | Values: 71.0 76.0 77.0 119.0 58.0 65.0 82.0 86.0 148.0 48.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:80.50 stddev:18.61 (23.1%) stddev-sans-first:19.73 09:40:10 INFO - PROCESS | 2403 | Values: 94.0 95.0 127.0 78.0 81.0 86.0 118.0 80.0 78.0 117.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:86.00 stddev:13.72 (16.0%) stddev-sans-first:14.50 09:40:10 INFO - PROCESS | 2403 | Values: 86.0 83.0 88.0 128.0 86.0 83.0 86.0 85.0 86.0 82.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:92.00 stddev:17.61 (19.1%) stddev-sans-first:18.55 09:40:10 INFO - PROCESS | 2403 | Values: 93.0 92.0 102.0 148.0 91.0 92.0 92.0 93.0 92.0 92.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:90.00 stddev:15.76 (17.5%) stddev-sans-first:16.67 09:40:10 INFO - PROCESS | 2403 | Values: 92.0 89.0 90.0 89.0 89.0 92.0 90.0 89.0 94.0 140.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.50 stddev:5.21 (8.8%) stddev-sans-first:5.49 09:40:10 INFO - PROCESS | 2403 | Values: 57.0 60.0 59.0 58.0 60.0 60.0 59.0 70.0 50.0 53.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:67.00 stddev:4.08 (6.1%) stddev-sans-first:4.25 09:40:10 INFO - PROCESS | 2403 | Values: 65.0 60.0 68.0 74.0 72.0 71.0 66.0 65.0 66.0 66.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:1.96 (2.5%) stddev-sans-first:2.01 09:40:10 INFO - PROCESS | 2403 | Values: 81.0 79.0 81.0 79.0 79.0 79.0 77.0 78.0 79.0 84.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:125.00 stddev:11.21 (9.0%) stddev-sans-first:11.89 09:40:10 INFO - PROCESS | 2403 | Values: 122.0 118.0 126.0 106.0 137.0 124.0 103.0 124.0 127.0 137.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.20 Median:143.50 stddev:18.72 (13.0%) stddev-sans-first:18.85 09:40:10 INFO - PROCESS | 2403 | Values: 159.0 168.0 170.0 116.0 153.0 132.0 133.0 134.0 129.0 128.0 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - PROCESS | 2403 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:1.35 (2.0%) stddev-sans-first:1.41 09:40:10 INFO - PROCESS | 2403 | Values: 66.0 68.0 64.0 66.0 65.0 65.0 63.0 66.0 66.0 65.0 09:40:10 INFO - PROCESS | 2403 | -------- Summary: end -------- 09:40:10 INFO - PROCESS | 2403 | 09:40:10 INFO - TEST-INFO | 2403: exit 0 09:40:10 INFO - TEST-OK | kraken | took 56701ms 09:40:10 INFO - SUITE-END | took 1169s 09:40:11 INFO - Completed test suite (00:19:29) 09:40:11 INFO - javascript benchmark 09:40:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2977.9702202977965, 3378.3581119587275, 3450.982745086276, 3469.8785542506016, 3489.1626009757665, 5670.6030577859565, 5672.914906276404, 5685.289338832646, 5691.459311365424, 5701.771929122835, 5683.545316374691, 5730.598858079936, 5733.713314334277, 5736.569757268201, 5738.856528586777, 5098.490150984909, 5157.277981082651, 5157.76790044447, 5228.398734145565, 5237.319148510688, 5170.767315470813, 5203.843884683447, 5211.244369566411, 5215.087359712054, 5229.058769421503, 4919.647097048322, 5185.55922746567, 5222.164453687411, 5235.371755389339, 5243.030039442696, 4355.477342718878, 4380.233459144637, 4750.691205071676, 5235.39792923811, 5277.208418737182, 5076.44159142494, 5168.844934651929, 5199.6360254782185, 5202.271681964528, 5242.685438873642, 5586.944130558723, 5617.241672374218, 5629.493345598875, 5633.295838020247, 5637.0, 6082.635041897456, 6304.936950630527, 6306.211723534559, 6307.18006659136, 6329.1772069631115, 6246.469050130744, 6248.750049997994, 6268.15379923709, 6310.842228944267, 6324.525660575429, 5814.0, 5842.182094506772, 5859.8535036624, 5862.144726067048, 5910.7931222407005, 6185.381461853869, 6200.906986395207, 6277.466415354746, 6282.874342513124, 6283.748650054038, 6130.795626845448, 6158.261008678987, 6170.938290617037, 6174.6912654368, 6178.320384757673, 6293.653849038255, 6297.905531417033, 6307.810765677037, 6307.968460157762, 6313.3686631336495, 6050.636961782219, 6160.199174107337, 6207.944649409611, 6234.5635805494085, 6250.874982500324, 5280.498352656491, 6224.782132625336, 6266.874662506724, 6290.056491526216, 6297.811065668037, 4901.0, 4911.803527858846, 4911.852644420673, 4916.754162291871, 4929.063477939252, 3821.7515861468914, 4346.500152482505, 4376.474823021258, 4451.443569553806, 4467.843625473093, 5716.428357164334, 5724.341700704389, 5728.398518155534, 5739.053056245672, 5762.596618236683, 6314.810555683337, 6315.431611155018, 6317.810465685945, 6318.6524741138755, 6343.841403964938, 5542.445755424425, 5687.146927960839, 5687.857803554862, 5705.400932902068, 5747.195392645033, 5311.389190243094, 5338.97330513345, 5341.732913354317, 5356.1697936819855, 5377.704226267593, 6253.093301471261, 6289.308176100534, 6299.370062993756, 6317.5893566918, 6323.588966717149, 6117.3402608778015, 6219.813405597839, 6300.873982520416, 6325.462335701517, 6329.683515824098, 6279.434850863261, 6286.119943208046, 6291.276503202191, 6306.243250809932, 6309.432151106423, 6193.907091393723, 6216.906746398898, 6222.62664240147, 6230.719617617308, 6237.220347456568, 5868.735906884115, 5900.763969441346, 5980.162777211107, 6042.546808989431, 6071.848203794941, 6115.205023346936, 6121.112438696453, 6133.478654314434, 6149.169862068564, 6154.9384506154365, 5560.304961879765, 6075.908861367171, 6095.634261944298, 6095.969520152371, 6097.2683278006925, 5880.323762767336, 5963.582549221513, 5981.431763982415, 5993.3706960768495, 6034.24571928509, 6043.214382130295, 6044.969775151096, 6067.271927368744, 6096.847578810565, 6100.481459076028, 5790.536757059341, 5882.588218824812, 5896.086106653475, 5899.321578018411, 5919.171316015675], "unit": "score", "name": "dojo.html", "value": 5671.328330527501}, {"lowerIsBetter": false, "replicates": [556.9721513924303, 587.0665641629807, 593.397701333147, 669.9147381242386, 669.9631520266385, 31601.525977110363, 32170.03489895304, 32258.83870580647, 32289.838550807213, 32399.51400728985, 42069.15861682765, 42298.57701422985, 42334.72995810131, 42367.57632423683, 42392.576074239245, 9607.56765945534, 9821.754456138618, 9832.26258030647, 9833.131704976031, 9840.950795246014, 9586.90413095867, 9751.463669498175, 9756.4633945133, 9764.609415623367, 9779.706608801747, 9746.90253097467, 9780.364276322052, 9803.90196098037, 9910.950445247763, 9937.602495900155, 9807.901920980807, 9872.062154095349, 9874.210063194963, 9887.90112098877, 9902.356346837505, 9659.0, 9798.65704700332, 9816.165625921805, 9822.557984890696, 9891.703248902544, 42571.0, 42909.785451072545, 43000.0, 43072.56927430717, 43210.70367888968, 3399.9830000850084, 3495.318412909484, 3519.208178159919, 3544.2557063016798, 3736.4955730976244, 25130.67112690779, 29562.0, 29824.701752982626, 30231.244218894484, 30463.543046854316, 2777.264209868832, 2783.109404990404, 2799.5800629905675, 2806.256342069353, 2807.6209711688866, 491723.0, 695402.0, 695688.0, 698641.0, 714110.4294478494, 73568.0, 74004.62997684977, 74565.25434745691, 74597.25402746012, 74888.0, 75828.62085689646, 76690.23309766832, 76978.0, 77159.61420192863, 78378.0, 17593.824061759475, 17889.642207155783, 17912.641747165246, 17934.0, 17951.461456156336, 14024.649383765487, 14032.298385080705, 14072.155670659793, 14106.576802695936, 14115.78826317606, 23065.7693423067, 23185.420364491023, 23393.84183135289, 23443.648345274836, 23459.0, 9040.728778136667, 9349.39228950116, 9390.34267601275, 9403.43579385239, 9420.717378478657, 944.593824655391, 1015.2537884654773, 1029.5418538750184, 1045.4250162410644, 1104.045001074065, 30177.396452071276, 30262.940797072, 30699.154432537744, 30779.69220307813, 31121.68878311233, 1096.0026375997804, 1129.796766443735, 1151.648747132116, 1177.5054477119631, 1181.527389044389, 15059.623509412353, 15415.614609634624, 15498.225088745743, 15827.683446331239, 15913.920430398006, 550038.0, 555277.0, 556470.0, 557079.0, 559061.0, 62315.6884215576, 62650.0, 62680.0, 62775.6861215691, 62836.37163628397, 1409.344654735553, 1528.99235503824, 1569.7723830044579, 1590.8886377953663, 1671.0057515778092, 29645.25886852846, 30710.539341909443, 30751.694762732786, 30847.63546428821, 31017.8449107753, 48879.75560122177, 49705.75735606639, 49901.0, 49993.0, 50018.24972625341, 374249.0, 377070.0, 377115.0, 377501.0, 377851.3322300223, 29219.85390073036, 35493.467597986564, 35554.8222258887, 36212.63787362093, 36821.0, 36707.89876303715, 45379.546204538856, 45828.625141245815, 46482.53517464782, 46838.531614683416, 36490.8175459121, 37649.0, 37853.242935141694, 37964.62035379611, 38518.0, 36724.265514690094, 37554.62445375621, 37711.0, 38190.0, 38234.0, 37442.0, 38080.0, 38134.618653814214, 38367.6163238364, 38852.222955540165, 28208.632557214594, 31413.0, 31755.29496410078, 33108.006759797245, 33639.83180084084, 25209.24372268835, 25266.747332527175, 25420.237392878244, 25475.745242548077, 25876.48247035011], "unit": "score", "name": "ext.html", "value": 20669.739519730563}, {"lowerIsBetter": false, "replicates": [375.7125798763945, 379.2945122072945, 380.5851621732313, 385.51040178972687, 385.9228154369125, 20096.59806803863, 21788.128474861005, 22093.889530552366, 22528.324150275475, 22588.20941267056, 21443.356699299005, 21546.138154473803, 21976.34070977869, 22033.669494957587, 22330.776692233074, 5814.622049566786, 5837.066069428893, 6008.489278411318, 6060.575759696823, 6061.242344706911, 4545.409096817409, 5875.88248235035, 5935.673537955411, 5946.791862284821, 6137.754489820401, 5775.451332123442, 5859.6484210947265, 5882.2353094097925, 5895.410458954092, 5902.586818922677, 5704.11586204137, 5835.708214589275, 5861.677607731573, 5896.616719913192, 5901.675407852566, 4728.865072382603, 4935.457099719028, 5068.619853511002, 5129.307543481619, 5146.871328216787, 26637.200883973317, 26752.19743407701, 26999.460010799674, 27113.72886271132, 27153.86423067892, 2186.9562608747738, 2275.5676421479866, 2278.111536500766, 2401.363638635763, 2406.6991626046743, 13713.725725485534, 15180.240987950558, 15219.315130819139, 15241.695166096615, 15582.766258506019, 1696.4316953820485, 1723.6811189929779, 1741.3731056819536, 1745.642143360608, 1806.9186886590003, 84031.0, 99369.50315248377, 101229.0, 103210.0, 103242.48378758058, 9231.35380523357, 10504.0, 10703.357798532114, 10892.61875834342, 10907.563697452171, 11261.66215013551, 13578.117422367515, 13578.253196074113, 13587.32063396846, 13605.455781768618, 8792.076831932683, 8973.50645714492, 8976.70701866706, 9057.592408341641, 9164.770880727901, 9385.5307234638, 9397.436153830791, 9419.90580094204, 9457.905420945703, 9478.620855165855, 6318.71565779541, 6358.904616430757, 6360.236771587439, 6363.268224154282, 6402.487800976004, 1622.4321487479287, 1821.897751860132, 1825.2607693884, 1857.9163937622839, 1901.4010586665156, 868.522312727997, 969.030969030969, 983.4246965525105, 989.6536212325628, 1020.6733845169474, 20741.896290518453, 21928.903554822195, 21999.450013749465, 22099.78451185168, 22106.668399974013, 1024.9077583017565, 1043.3479075577764, 1045.7385653586603, 1085.5332207150843, 1116.9720756980978, 9740.554848053509, 11555.88444115548, 11868.287902725864, 11886.167968242313, 12019.579314723942, 674234.6288268528, 675761.0, 687060.0, 692583.5370823113, 694654.0, 111216.44391778151, 112998.0, 113732.0, 114729.42635286937, 120526.0, 1078.3314345106085, 1090.345792524479, 1135.3131355529952, 1152.3898370191523, 1153.7000379901285, 16986.065766382966, 18485.89084654925, 18585.0, 19050.333238336592, 19451.027448627796, 42277.57722422737, 50650.746746266035, 52348.738256308476, 53191.0, 54000.0, 72454.55090898047, 76133.0, 76866.23133768591, 78839.0, 79644.0, 6668.833279167866, 7835.921640783519, 8221.876671850046, 8233.50598964064, 8609.268212202032, 9472.28957828152, 12893.742125157632, 13460.326983650973, 13924.4430222794, 13946.30268486592, 10534.36793792375, 11463.140264480031, 11832.467538960595, 11888.167828252286, 12163.209391389531, 9413.917399499145, 11397.658070257905, 11775.0, 11919.106067045179, 12047.81928271059, 6847.452203823583, 9988.300818942604, 11685.883141168479, 11872.406379681153, 12099.153059285765, 8147.0, 8269.09040005611, 8423.031351394424, 8495.980482342158, 8603.52680602573, 10432.843507347548, 11886.108541859569, 12009.63971080869, 12061.276323420623, 12063.39683015828], "unit": "score", "name": "jquery.html", "value": 9807.421007099696}, {"lowerIsBetter": false, "replicates": [1298.863619319972, 1637.0586912525298, 1653.5370096373017, 2032.5731596364762, 2110.7783682713307, 8427.410081294312, 8933.821323573533, 9114.179723824855, 9124.954375228115, 9128.406653567521, 8856.335774816882, 9142.040085790979, 9153.130452607009, 9156.313276504272, 9159.175674189322, 2185.267935241696, 2493.0651005872796, 2553.0426090216215, 2556.833805802626, 2584.5735453650127, 2574.214864466346, 2581.380468687511, 2583.599542070982, 2584.625229341744, 2594.792416606667, 2480.541099896525, 2578.355411147213, 2580.6774153230845, 2581.5224183526016, 2581.586946088626, 2362.9248691845255, 2376.6197408414655, 2563.218218443374, 2577.8195526313166, 2585.5863061910004, 2537.835040722347, 2553.885075171622, 2575.2789219018705, 2582.7029891562333, 2586.8189226754134, 9083.137101975302, 9129.81740365189, 9134.680286190018, 9142.040085790963, 9165.816683666357, 5466.835994920159, 5590.245316882209, 5601.271834661468, 5604.8318550443555, 5607.579431542651, 3628.2743451309843, 3669.486271921933, 3687.4284485904727, 3688.3360995020885, 3714.53568303962, 935.6865450074233, 1069.2354966199127, 1086.7989421956884, 1093.9945300273528, 1118.8769235384102, 12334.75330493394, 12348.629541113782, 12369.505219791197, 12370.690732731664, 12388.194767340094, 4967.379077615298, 4988.625853060962, 5064.898702026013, 5076.466970967996, 5092.0, 10496.737581560523, 10502.002309780559, 10523.052925236765, 10524.526396312185, 10534.051935325857, 5705.543556515552, 5711.057675483498, 5714.257146570919, 5719.7140142992685, 5719.799807006735, 4624.167649822997, 4806.351142595775, 5009.198528235464, 5164.04465173944, 5170.327857378592, 4044.636577796799, 4099.442211959496, 4124.505059392892, 4148.605882441163, 4150.64719498846, 1340.8886615448055, 1363.6659018540522, 1366.6241783509415, 1367.6785955300497, 1374.896882733799, 1408.3310427546833, 1421.6161636358331, 1445.147363055802, 1446.681730019394, 1476.320892389513, 8502.319814414832, 8560.186782255676, 8582.098879617675, 8583.957080214559, 8588.699395521127, 524.9238860365226, 532.5207313417955, 535.8419266316446, 538.8922215556904, 543.5135553679435, 6369.331220221903, 6375.3624637536805, 6405.935940640534, 6410.038494225903, 6415.390537898892, 10782.56869725218, 11202.991730744272, 11294.983451489405, 11328.546858125748, 11365.886341136647, 10386.896131038593, 10755.300905441121, 11132.05377542918, 11168.497417616227, 11179.944100279447, 8534.231919127309, 8588.141185881363, 8592.8711069334, 8593.097724738938, 8629.39594228398, 6989.860202796017, 8293.875591866248, 8306.003279606486, 8362.832743345221, 8396.790080247798, 9442.291828112782, 9777.462239576891, 9809.117179453884, 9882.555285012335, 10048.196144308584, 9878.753031174278, 10052.798944021224, 10301.175905927657, 10317.74205644835, 10328.173746100445, 6868.0, 7391.1869694334655, 7600.935868978458, 7710.036245469317, 7730.420218483749, 8529.914700852913, 8535.103814099679, 8584.613692383982, 8595.0, 8622.439541429787, 7505.174430812715, 7560.614150204307, 7672.808179795326, 7742.574158421341, 7956.2043795619975, 7358.66885990121, 7426.962865185856, 7436.0, 7535.171131175676, 7610.505317154589, 7956.761297161094, 8021.117677055636, 8030.83938321242, 8043.959780201061, 8055.154208808225, 7186.353228209487, 7235.2402997684485, 7317.378022868116, 7331.3768329692575, 7346.6326683666675, 7395.408367330493, 7408.111026676834, 7448.813779655552, 7453.366463850633, 7475.738349157753], "unit": "score", "name": "mootools.html", "value": 5025.9845376934645}, {"lowerIsBetter": false, "replicates": [335.83040564514926, 338.6342749830184, 345.23185911347247, 346.7659329952282, 349.9352619765344, 6684.5989240645595, 6712.765053223138, 6741.089952856363, 6754.088198093256, 6755.1556055493065, 5653.519450846683, 6525.314841941599, 6616.96691516543, 6642.734290628369, 6665.700043498042, 4737.573618374346, 4862.343583616202, 4869.780859861315, 5019.32239147715, 5063.873403164914, 4975.7263350515705, 5013.818988739909, 5027.547520723134, 5033.320501732274, 5038.0, 4968.130577149002, 4974.87562810929, 4975.776090075937, 4978.904640978979, 4980.626453016021, 4718.457377401592, 4801.375821143247, 4879.975600121977, 4906.386701662293, 4916.827911023132, 4212.810423530948, 4216.873493795191, 4226.894327641803, 4231.090315582164, 4239.173361194568, 6263.6241825490615, 6378.808635740936, 6395.6482393468805, 6402.775902843378, 6414.679266036679, 5886.735096920606, 5889.79385721502, 5894.734736936848, 5911.0, 5923.466887980059, 5653.830385088454, 7120.18117916441, 7124.608146551937, 7130.28697130288, 7150.39221666159, 4562.224421848294, 4598.471175814757, 4599.586037256663, 4618.0, 4625.976870115662, 7359.632018399112, 7492.250774922519, 7520.548767073993, 7525.5860927648955, 7525.811854703621, 4706.670533062653, 4709.505501922317, 4716.481187069419, 4717.8112875485285, 4720.527947205321, 4758.500357462486, 4772.618190544748, 4779.617630589544, 4785.353977213102, 4787.162246606899, 5696.458836410535, 5697.857553561112, 5710.828675139753, 5716.685582293014, 5723.0, 5889.204957330706, 6736.562123461959, 7073.151221853411, 7116.573005619679, 7160.248173941765, 1617.2156504095503, 1658.336665333876, 1658.3946859396233, 1658.4195531563855, 1660.2031025108015, 985.7042887133831, 1033.8190816607064, 1037.5175543372368, 1050.2858056521486, 1070.3417398300146, 5440.564754819605, 6516.869662606721, 6562.8359291018105, 6591.34086591337, 6614.0, 7313.451491138186, 7351.22812104732, 7370.6314684266645, 7378.262173782578, 7388.926110738931, 6579.934200658028, 6582.3746744059235, 6593.175853018372, 6602.86794264112, 6642.468602511787, 3866.2267546490816, 3897.0647044709067, 3916.980415097906, 3927.1752931884625, 3935.0, 7432.85134297311, 7655.387568994467, 7674.117476490275, 7690.154083050972, 7708.537487750753, 6466.935330646727, 7645.617719114022, 7661.272179142972, 7721.112072111779, 7724.227577242231, 7736.922630773733, 7752.186020467882, 7757.806054848674, 7760.650770715331, 7767.262110099531, 7160.355567998697, 7424.517406368569, 7442.0, 7451.664675089525, 7458.664360103699, 7269.0, 7285.7814265572115, 7286.48994570375, 7303.379212766974, 7318.048653675201, 6518.315576864361, 7618.047744031996, 7740.496867703581, 7749.070111586646, 7772.183920688246, 4663.790129444251, 4699.624030077517, 4721.858344249678, 4725.952740472689, 4734.313524538991, 7214.74748383804, 7414.443916706161, 7414.851702966019, 7424.071991001125, 7442.106947166375, 7070.964645176741, 7111.608861512666, 7119.0, 7131.607761573163, 7144.142702875688, 7055.541389809646, 7076.504644674823, 7128.679209435484, 7217.6030318333, 7226.891596625949, 7021.5787052777, 7051.048108505286, 7051.294870512907, 7064.434845212473, 7070.39901608369, 6868.553544019623, 7128.251533589106, 7188.5327453715345, 7201.279872012757, 7210.4231661468, 6682.164729408824, 6813.0, 6848.7260509579055, 7011.263817299314, 7017.122859642544], "unit": "score", "name": "prototype.html", "value": 5210.7606891507885}, {"lowerIsBetter": false, "replicates": [742.8402893377922, 757.7575175943698, 770.9707540433521, 785.5757890738997, 794.285143370966, 15363.3086511107, 15778.605534861634, 15779.368825246991, 15789.84210157898, 15800.68398632028, 14463.34914928828, 14520.854791452082, 14544.0, 14608.123512589253, 14626.0, 763.8663233934067, 781.0315209140653, 960.6205548808216, 999.3703966501131, 999.6451259802774, 973.8052389522088, 983.1544871410581, 983.3706427886159, 984.2273815055183, 985.0100648847898, 813.4305985809957, 1044.926855120142, 1049.790041991601, 1049.9972526237464, 1050.6322787024558, 1024.7028361775076, 1038.2939601071268, 1038.7922415516928, 1039.4179259614602, 1039.8180318444279, 770.0143815915637, 772.8956590860219, 773.9729109481198, 774.6359211170741, 775.2944820213635, 15261.771073433907, 15263.160526171056, 15285.312160952784, 15298.617534561614, 15362.615934601612, 13265.933670331684, 13321.933390332986, 13524.25616591087, 13588.0, 13854.93072534641, 10493.947530262376, 10525.052745252888, 10598.576056957712, 10828.458577071115, 10841.295315804526, 3020.8187508749324, 3079.630444346693, 3627.455881617752, 3714.4242642390473, 3823.1589050408866, 88667.0, 90678.0, 90703.54648226783, 91884.0, 92843.53578232066, 9935.850962235572, 10012.799744005079, 10115.544800483996, 10130.341527800816, 10845.783084338269, 24140.275791726275, 24168.758312417, 24178.637320440208, 24190.274291771275, 24269.87865060699, 7474.289942455458, 7603.581803000888, 7659.502132361378, 7720.0, 7791.4935529190425, 3872.206197729459, 4075.7146999709735, 4217.683673724483, 4252.255855225323, 4266.509351424564, 3786.4888240087234, 3827.8468861245237, 3828.693704503633, 3834.4631751554803, 3841.750286231386, 632.4466092169351, 641.1344684675744, 645.5932762359777, 670.8389986403228, 702.8067281497629, 507.24167369781674, 566.3317285603028, 619.1332135010931, 628.1582679209881, 645.141961191614, 7890.368770498347, 8340.207680270365, 8358.498490090615, 8457.584703220335, 8462.915370846213, 551.6524589508664, 613.929398119213, 615.8891399548124, 619.0899377914459, 623.1338439569003, 3776.3013842439236, 3978.6618137458063, 4001.4397984282223, 4047.1298670785923, 4051.7366371186367, 94970.0, 98779.0, 98819.50590247003, 98836.0, 99039.00960990442, 80424.0, 83739.0, 85116.72324915131, 86660.0, 86945.26109477774, 63825.680871595345, 63841.99999999907, 64381.35618643754, 66287.66856165689, 66842.66578667075, 7744.65149068282, 8528.403011789114, 8609.91390086091, 8670.653173872974, 8940.195382415613, 41764.58235417607, 42320.788396057826, 47260.7636961813, 48908.51091489182, 50268.49731502638, 68557.31442685709, 68686.65656671685, 68958.0, 70430.64784676043, 74433.62783186266, 11170.609028683957, 11193.27243729155, 11246.775064498828, 11414.714632133931, 11527.942360288145, 11882.940585297018, 12086.093542984487, 12107.878921211028, 12489.125761196628, 12554.246745195318, 11718.94140529326, 11739.941300293443, 11838.704032399259, 11872.643820685393, 11935.761284774082, 11616.535338586362, 11625.47685354178, 11892.10809189297, 12195.329256890956, 12208.0, 11393.202475826612, 11650.941745291559, 11760.411979401168, 11914.0, 12102.697432564257, 9433.240801895077, 9482.051794820463, 9611.279154063614, 9665.178459830711, 9672.613095476383, 9425.0, 9557.713268601952, 9617.519124043909, 9630.807383852143, 9839.212862971088], "unit": "score", "name": "yui.html", "value": 7171.91508572797}], "name": "dromaeo_css", "value": 7745.711156202556}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 115.0, 108.0, 110.0, 133.0, 112.0, 120.0, 111.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 114.4}, {"lowerIsBetter": true, "replicates": [154.0, 121.0, 142.0, 157.0, 142.0, 171.0, 138.0, 118.0, 160.0, 116.0], "unit": "score", "name": "audio-beat-detection", "value": 141.9}, {"lowerIsBetter": true, "replicates": [184.0, 186.0, 182.0, 186.0, 189.0, 184.0, 182.0, 185.0, 186.0, 185.0], "unit": "score", "name": "audio-dft", "value": 184.9}, {"lowerIsBetter": true, "replicates": [71.0, 76.0, 77.0, 119.0, 58.0, 65.0, 82.0, 86.0, 148.0, 48.0], "unit": "score", "name": "audio-fft", "value": 83.0}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 127.0, 78.0, 81.0, 86.0, 118.0, 80.0, 78.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 95.4}, {"lowerIsBetter": true, "replicates": [86.0, 83.0, 88.0, 128.0, 86.0, 83.0, 86.0, 85.0, 86.0, 82.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.3}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 102.0, 148.0, 91.0, 92.0, 92.0, 93.0, 92.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 98.7}, {"lowerIsBetter": true, "replicates": [92.0, 89.0, 90.0, 89.0, 89.0, 92.0, 90.0, 89.0, 94.0, 140.0], "unit": "score", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [57.0, 60.0, 59.0, 58.0, 60.0, 60.0, 59.0, 70.0, 50.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 58.6}, {"lowerIsBetter": true, "replicates": [65.0, 60.0, 68.0, 74.0, 72.0, 71.0, 66.0, 65.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.3}, {"lowerIsBetter": true, "replicates": [81.0, 79.0, 81.0, 79.0, 79.0, 79.0, 77.0, 78.0, 79.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 79.6}, {"lowerIsBetter": true, "replicates": [122.0, 118.0, 126.0, 106.0, 137.0, 124.0, 103.0, 124.0, 127.0, 137.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.4}, {"lowerIsBetter": true, "replicates": [159.0, 168.0, 170.0, 116.0, 153.0, 132.0, 133.0, 134.0, 129.0, 128.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 142.2}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 64.0, 66.0, 65.0, 65.0, 63.0, 66.0, 66.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.4}], "name": "kraken", "value": 1438.5000000000002}]} 09:40:11 INFO - Return code: 0 09:40:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:40:11 INFO - # TBPL SUCCESS # 09:40:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:11 INFO - 2016-04-18 09:40:11.329 screenresolution[2407:18525] starting screenresolution argv=screenresolution get 09:40:11 INFO - 2016-04-18 09:40:11.331 screenresolution[2407:18525] Display 0: 1600x1200x32@60 09:40:11 INFO - 2016-04-18 09:40:11.337 screenresolution[2408:18528] starting screenresolution argv=screenresolution list 09:40:11 INFO - Available Modes on Display 0 09:40:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:11 INFO - Intel Iris: 09:40:11 INFO - Chipset Model: Intel Iris 09:40:11 INFO - Type: GPU 09:40:11 INFO - Bus: Built-In 09:40:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:11 INFO - Vendor: Intel (0x8086) 09:40:11 INFO - Device ID: 0x0a2e 09:40:11 INFO - Revision ID: 0x0009 09:40:11 INFO - Displays: 09:40:11 INFO - Display: 09:40:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:11 INFO - Display Serial Number: 1600x1200 60 09:40:11 INFO - Main Display: Yes 09:40:11 INFO - Mirror: Off 09:40:11 INFO - Online: Yes 09:40:11 INFO - Rotation: Supported 09:40:11 INFO - Return code: 0 09:40:11 INFO - Running post-action listener: _resource_record_post_action 09:40:11 INFO - Running post-run listener: _resource_record_post_run 09:40:12 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 385024; Write bytes: 662119424; Read time: 5; Write time: 1869 09:40:12 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361945600; Write bytes: 363755520; Read time: 28232; Write time: 718 09:40:12 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 385024; Write bytes: 297073664; Read time: 5; Write time: 1147 09:40:12 INFO - Running post-run listener: _upload_blobber_files 09:40:12 INFO - Blob upload gear active. 09:40:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:40:12 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 09:40:12 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'] 09:40:12 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 09:40:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:13 INFO - (blobuploader) - INFO - Done attempting. 09:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:40:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:14 INFO - (blobuploader) - INFO - Done attempting. 09:40:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:40:14 INFO - Return code: 0 09:40:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:40:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:40:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/97d4da2acb51fa338968407b4cce99d2687880df76f861fb3380ccf408a10ecf58339a8b97b120e989088137f332c8f5fac7b5f7a542048112aa335ac73c4b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e8d8e3ba8a0f70fb50f6e7e595d7ec7bd8b1ebb2b164f6fbb7b8aad7288e6b76699c7964150bf716efce6f816f27a0ccd7e1e460e0775e12d876b685f735413"} 09:40:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:40:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:40:14 INFO - Contents: 09:40:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/97d4da2acb51fa338968407b4cce99d2687880df76f861fb3380ccf408a10ecf58339a8b97b120e989088137f332c8f5fac7b5f7a542048112aa335ac73c4b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e8d8e3ba8a0f70fb50f6e7e595d7ec7bd8b1ebb2b164f6fbb7b8aad7288e6b76699c7964150bf716efce6f816f27a0ccd7e1e460e0775e12d876b685f735413"} 09:40:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:40:14 INFO - Copying logs to upload dir... 09:40:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:40:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.413195 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-18 09:40:14.931674) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:14.935128) ========= 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.ffvRlfshos/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.RFBxGdCMRg/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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/97d4da2acb51fa338968407b4cce99d2687880df76f861fb3380ccf408a10ecf58339a8b97b120e989088137f332c8f5fac7b5f7a542048112aa335ac73c4b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e8d8e3ba8a0f70fb50f6e7e595d7ec7bd8b1ebb2b164f6fbb7b8aad7288e6b76699c7964150bf716efce6f816f27a0ccd7e1e460e0775e12d876b685f735413"} build_url:https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011307 build_url: 'https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/97d4da2acb51fa338968407b4cce99d2687880df76f861fb3380ccf408a10ecf58339a8b97b120e989088137f332c8f5fac7b5f7a542048112aa335ac73c4b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e8d8e3ba8a0f70fb50f6e7e595d7ec7bd8b1ebb2b164f6fbb7b8aad7288e6b76699c7964150bf716efce6f816f27a0ccd7e1e460e0775e12d876b685f735413"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:14.963514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:14.963879) ========= 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.ffvRlfshos/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.RFBxGdCMRg/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005222 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:15.012652) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 09:40:15.012986) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 09:40:15.013387) ========= ========= Total master_lag: 0.16 =========