builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1464703225.52 results: success (0) buildid: 20160531060243 builduid: 95917a53c8c1452ba14fd68c836eeb67 revision: 9f96ed6259453ce242f41454ae822efc335e2e3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:25.521261) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:25.521709) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:25.522008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:26.093945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-31 07:00:26.094213) ========= 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.exDpBR4LC7/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.HX9U0pbWjh/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-31 07:00:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2016-05-31 07:00:26 (14.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170699 ========= master_lag: 3.68 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-31 07:00:29.948816) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:29.949532) ========= 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.exDpBR4LC7/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.HX9U0pbWjh/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.028041 ========= master_lag: 0.15 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:30.126690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 07:00:30.127018) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9f96ed6259453ce242f41454ae822efc335e2e3c --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 9f96ed6259453ce242f41454ae822efc335e2e3c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.exDpBR4LC7/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.HX9U0pbWjh/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-31 07:00:30,400 truncating revision to first 12 chars 2016-05-31 07:00:30,401 Setting DEBUG logging. 2016-05-31 07:00:30,401 attempt 1/10 2016-05-31 07:00:30,401 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9f96ed625945?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 07:00:31,215 unpacking tar archive at: mozilla-aurora-9f96ed625945/testing/mozharness/ program finished with exit code 0 elapsedTime=1.067196 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 07:00:31.410232) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:31.410630) ========= script_repo_revision: 9f96ed6259453ce242f41454ae822efc335e2e3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:31.411089) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:31.411386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 07:00:31.427606) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-31 07:00:31.427902) ========= /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.exDpBR4LC7/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.HX9U0pbWjh/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:00:31 INFO - MultiFileLogger online at 20160531 07:00:31 in /builds/slave/test 07:00:31 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 07:00:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:00:31 INFO - {'append_to_log': False, 07:00:31 INFO - 'base_work_dir': '/builds/slave/test', 07:00:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:00:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:00:31 INFO - 'branch': 'Mozilla-Aurora', 07:00:31 INFO - 'buildbot_json_path': 'buildprops.json', 07:00:31 INFO - 'config_files': ('talos/mac_config.py',), 07:00:31 INFO - 'default_actions': ('clobber', 07:00:31 INFO - 'read-buildbot-config', 07:00:31 INFO - 'download-and-extract', 07:00:31 INFO - 'populate-webroot', 07:00:31 INFO - 'create-virtualenv', 07:00:31 INFO - 'install', 07:00:31 INFO - 'run-tests'), 07:00:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:00:31 INFO - 'download_minidump_stackwalk': True, 07:00:31 INFO - 'download_symbols': 'ondemand', 07:00:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:00:31 INFO - 'tooltool.py': '/tools/tooltool.py', 07:00:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:00:31 INFO - '/tools/misc-python/virtualenv.py')}, 07:00:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:00:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:00:31 INFO - 'installer_path': 'installer.exe', 07:00:31 INFO - 'log_level': 'info', 07:00:31 INFO - 'log_name': 'talos', 07:00:31 INFO - 'log_to_console': True, 07:00:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:00:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:00:31 INFO - 'opt_config_files': (), 07:00:31 INFO - 'pip_index': False, 07:00:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:00:31 INFO - 'cmd': ('bash', 07:00:31 INFO - '-c', 07:00:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:00:31 INFO - 'enabled': True, 07:00:31 INFO - 'halt_on_failure': False, 07:00:31 INFO - 'name': 'check_screen_resolution'},), 07:00:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:00:31 INFO - 'cmd': ('bash', 07:00:31 INFO - '-c', 07:00:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:00:31 INFO - 'enabled': True, 07:00:31 INFO - 'halt_on_failure': False, 07:00:31 INFO - 'name': 'check_screen_resolution'},), 07:00:31 INFO - 'run_cmd_checks_enabled': True, 07:00:31 INFO - 'sps_profile': False, 07:00:31 INFO - 'sps_profile_interval': 0, 07:00:31 INFO - 'suite': 'dromaeojs', 07:00:31 INFO - 'system_bits': '32', 07:00:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:00:31 INFO - 'title': 't-yosemite-r7-0173', 07:00:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:00:31 INFO - 'use_talos_json': True, 07:00:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:00:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:00:31 INFO - 'work_dir': 'build'} 07:00:31 INFO - ##### 07:00:31 INFO - ##### Running clobber step. 07:00:31 INFO - ##### 07:00:31 INFO - Running pre-action listener: _resource_record_pre_action 07:00:31 INFO - Running main action method: clobber 07:00:31 INFO - rmtree: /builds/slave/test/build 07:00:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:00:33 INFO - Running post-action listener: _resource_record_post_action 07:00:33 INFO - ##### 07:00:33 INFO - ##### Running read-buildbot-config step. 07:00:33 INFO - ##### 07:00:33 INFO - Running pre-action listener: _resource_record_pre_action 07:00:33 INFO - Running main action method: read_buildbot_config 07:00:33 INFO - Using buildbot properties: 07:00:33 INFO - { 07:00:33 INFO - "project": "", 07:00:33 INFO - "product": "firefox", 07:00:33 INFO - "script_repo_revision": "production", 07:00:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:00:33 INFO - "repository": "", 07:00:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:00:33 INFO - "buildid": "20160531060243", 07:00:33 INFO - "pgo_build": "False", 07:00:33 INFO - "basedir": "/builds/slave/test", 07:00:33 INFO - "buildnumber": 252, 07:00:33 INFO - "slavename": "t-yosemite-r7-0173", 07:00:33 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:00:33 INFO - "platform": "yosemite_r7", 07:00:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:00:33 INFO - "branch": "mozilla-aurora", 07:00:33 INFO - "repo_path": "releases/mozilla-aurora", 07:00:33 INFO - "revision": "9f96ed6259453ce242f41454ae822efc335e2e3c", 07:00:33 INFO - "stage_platform": "macosx64", 07:00:33 INFO - "builduid": "95917a53c8c1452ba14fd68c836eeb67", 07:00:33 INFO - "slavebuilddir": "test" 07:00:33 INFO - } 07:00:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 07:00:33 INFO - Running post-action listener: _resource_record_post_action 07:00:33 INFO - ##### 07:00:33 INFO - ##### Running download-and-extract step. 07:00:33 INFO - ##### 07:00:33 INFO - Running pre-action listener: _resource_record_pre_action 07:00:33 INFO - Running main action method: download_and_extract 07:00:33 INFO - mkdir: /builds/slave/test/build/tests 07:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:33 INFO - https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:00:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:00:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:00:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 07:00:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 07:00:36 INFO - Downloaded 1199 bytes. 07:00:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 07:00:36 INFO - Using the following test package requirements: 07:00:36 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:00:36 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 07:00:36 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 07:00:36 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'jsshell-mac.zip'], 07:00:36 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 07:00:36 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:00:36 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 07:00:36 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 07:00:36 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 07:00:36 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:00:36 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 07:00:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:00:36 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')]} 07:00:36 INFO - https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:00:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:00:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:00:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:00:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:00:42 INFO - Downloaded 18463799 bytes. 07:00:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:00:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:00:45 INFO - Return code: 0 07:00:45 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:00: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')]} 07:00:45 INFO - https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:00:50 INFO - Downloaded 18463799 bytes. 07:00:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:00:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:00:53 INFO - Return code: 0 07:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:53 INFO - https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:00:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:00:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:00:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:00:54 INFO - Downloaded 10840145 bytes. 07:00:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:00:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:00:55 INFO - Return code: 0 07:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:55 INFO - https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 07:00:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:00:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:00:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 07:00:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 07:00:58 INFO - Downloaded 114155695 bytes. 07:00:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:00:58 INFO - mkdir: /builds/slave/test/properties 07:00:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:58 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:58 INFO - Contents: 07:00:58 INFO - build_url:https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:00:59 INFO - Running post-action listener: _resource_record_post_action 07:00:59 INFO - Running post-action listener: set_extra_try_arguments 07:00:59 INFO - ##### 07:00:59 INFO - ##### Running populate-webroot step. 07:00:59 INFO - ##### 07:00:59 INFO - Running pre-action listener: _resource_record_pre_action 07:00:59 INFO - Running main action method: populate_webroot 07:00:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:59 INFO - '--tppagecycles', 07:00:59 INFO - '7'], 07:00:59 INFO - 'tests': ['tsvgm']}}, 07:00:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['damp', 'tps']}, 07:00:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'tests': ['damp', 'tps']}, 07:00:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['dromaeo_dom']}, 07:00:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['a11yr', 07:00:59 INFO - 'ts_paint', 07:00:59 INFO - 'tpaint', 07:00:59 INFO - 'sessionrestore', 07:00:59 INFO - 'sessionrestore_no_auto_restore', 07:00:59 INFO - 'tabpaint']}, 07:00:59 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:59 INFO - 'ts_paint', 07:00:59 INFO - 'tpaint', 07:00:59 INFO - 'sessionrestore', 07:00:59 INFO - 'sessionrestore_no_auto_restore', 07:00:59 INFO - 'tabpaint']}, 07:00:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:59 INFO - 'ts_paint', 07:00:59 INFO - 'tpaint', 07:00:59 INFO - 'sessionrestore', 07:00:59 INFO - 'sessionrestore_no_auto_restore', 07:00:59 INFO - 'tabpaint']}, 07:00:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:59 INFO - 'ts_paint', 07:00:59 INFO - 'tpaint', 07:00:59 INFO - 'sessionrestore', 07:00:59 INFO - 'sessionrestore_no_auto_restore', 07:00:59 INFO - 'tabpaint']}, 07:00:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['a11yr', 07:00:59 INFO - 'ts_paint', 07:00:59 INFO - 'tpaint', 07:00:59 INFO - 'sessionrestore', 07:00:59 INFO - 'sessionrestore_no_auto_restore', 07:00:59 INFO - 'tabpaint']}, 07:00:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['a11yr', 07:00:59 INFO - 'ts_paint', 07:00:59 INFO - 'tpaint', 07:00:59 INFO - 'sessionrestore', 07:00:59 INFO - 'sessionrestore_no_auto_restore', 07:00:59 INFO - 'tabpaint']}, 07:00:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['tsvgx', 07:00:59 INFO - 'tsvgr_opacity', 07:00:59 INFO - 'tart', 07:00:59 INFO - 'tscrollx', 07:00:59 INFO - 'cart']}, 07:00:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:59 INFO - 'tsvgr_opacity', 07:00:59 INFO - 'tart', 07:00:59 INFO - 'tscrollx', 07:00:59 INFO - 'cart']}, 07:00:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'talos_options': ['--disable-e10s'], 07:00:59 INFO - 'tests': ['tp5o']}, 07:00:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'tests': ['tp5o']}, 07:00:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'talos_options': ['--disable-e10s', 07:00:59 INFO - '--xperf_path', 07:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:59 INFO - 'tests': ['tp5n']}, 07:00:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:59 INFO - 'talos_options': ['--xperf_path', 07:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:59 INFO - 'tests': ['tp5n']}}, 07:00:59 INFO - 'talos.zip': {'path': '', 07:00:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:59 INFO - Running post-action listener: _resource_record_post_action 07:00:59 INFO - ##### 07:00:59 INFO - ##### Running create-virtualenv step. 07:00:59 INFO - ##### 07:00:59 INFO - Running pre-action listener: _resource_record_pre_action 07:00:59 INFO - Running main action method: create_virtualenv 07:00:59 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:59 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 07:00:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:59 INFO - Using real prefix '/tools/python27' 07:00:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:01:00 INFO - Installing distribute.............................................................................................................................................................................................done. 07:01:02 INFO - Installing pip.................done. 07:01:02 INFO - Return code: 0 07:01:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:02 INFO - 'HOME': '/Users/cltbld', 07:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:02 INFO - 'LOGNAME': 'cltbld', 07:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:02 INFO - 'MOZ_NO_REMOTE': '1', 07:01:02 INFO - 'NO_EM_RESTART': '1', 07:01:02 INFO - 'PAGER': '/bin/cat', 07:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:02 INFO - 'PWD': '/builds/slave/test', 07:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:02 INFO - 'SHELL': '/bin/bash', 07:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:02 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:02 INFO - 'USER': 'cltbld', 07:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:02 INFO - 'XPC_FLAGS': '0x0', 07:01:02 INFO - 'XPC_SERVICE_NAME': '0', 07:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:03 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 07:01:03 INFO - Cleaning up... 07:01:03 INFO - Return code: 0 07:01:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:01: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')]} 07:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01: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')]} 07:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01: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', '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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01: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', 'psutil>=0.7.1'] in /builds/slave/test/build 07:01: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 psutil>=0.7.1 07:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:03 INFO - 'HOME': '/Users/cltbld', 07:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:03 INFO - 'LOGNAME': 'cltbld', 07:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:03 INFO - 'MOZ_NO_REMOTE': '1', 07:01:03 INFO - 'NO_EM_RESTART': '1', 07:01:03 INFO - 'PAGER': '/bin/cat', 07:01:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:03 INFO - 'PWD': '/builds/slave/test', 07:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:03 INFO - 'SHELL': '/bin/bash', 07:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:03 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:03 INFO - 'USER': 'cltbld', 07:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:03 INFO - 'XPC_FLAGS': '0x0', 07:01:03 INFO - 'XPC_SERVICE_NAME': '0', 07:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:03 INFO - Downloading/unpacking psutil>=0.7.1 07:01: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 07:01: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 07:01: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 07:01: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 07:01: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 07:01: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 07:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:01:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:01:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:01:06 INFO - Installing collected packages: psutil 07:01:06 INFO - Running setup.py install for psutil 07:01:06 INFO - building 'psutil._psutil_osx' extension 07:01:06 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 07:01:07 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 07:01:07 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 07:01:07 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 07:01:07 INFO - building 'psutil._psutil_posix' extension 07:01:07 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 07:01:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:01:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:01:07 INFO - ^ 07:01:07 INFO - 1 warning generated. 07:01:07 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 07:01:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:01:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:01:08 INFO - Successfully installed psutil 07:01:08 INFO - Cleaning up... 07:01:08 INFO - Return code: 0 07:01:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:01:08 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')]} 07:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:08 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')]} 07:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:08 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:08 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 07:01:08 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 07:01:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:08 INFO - 'HOME': '/Users/cltbld', 07:01:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:08 INFO - 'LOGNAME': 'cltbld', 07:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:08 INFO - 'MOZ_NO_REMOTE': '1', 07:01:08 INFO - 'NO_EM_RESTART': '1', 07:01:08 INFO - 'PAGER': '/bin/cat', 07:01:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:08 INFO - 'PWD': '/builds/slave/test', 07:01:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:08 INFO - 'SHELL': '/bin/bash', 07:01:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:08 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:08 INFO - 'USER': 'cltbld', 07:01:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:08 INFO - 'XPC_FLAGS': '0x0', 07:01:08 INFO - 'XPC_SERVICE_NAME': '0', 07:01:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:01:08 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 07:01:08 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 07:01:08 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 07:01:08 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 07:01:08 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 07:01:08 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 07:01:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:01:11 INFO - Installing collected packages: mozsystemmonitor 07:01:11 INFO - Running setup.py install for mozsystemmonitor 07:01:11 INFO - Successfully installed mozsystemmonitor 07:01:11 INFO - Cleaning up... 07:01:11 INFO - Return code: 0 07:01:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:01:11 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')]} 07:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:11 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')]} 07:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:11 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:11 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 07:01:11 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 07:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:11 INFO - 'HOME': '/Users/cltbld', 07:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:11 INFO - 'LOGNAME': 'cltbld', 07:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:11 INFO - 'MOZ_NO_REMOTE': '1', 07:01:11 INFO - 'NO_EM_RESTART': '1', 07:01:11 INFO - 'PAGER': '/bin/cat', 07:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:11 INFO - 'PWD': '/builds/slave/test', 07:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:11 INFO - 'SHELL': '/bin/bash', 07:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:11 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:11 INFO - 'USER': 'cltbld', 07:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:11 INFO - 'XPC_FLAGS': '0x0', 07:01:11 INFO - 'XPC_SERVICE_NAME': '0', 07:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:11 INFO - Downloading/unpacking blobuploader==1.2.4 07:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:13 INFO - Downloading blobuploader-1.2.4.tar.gz 07:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:01:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:01:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:14 INFO - Downloading docopt-0.6.1.tar.gz 07:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:01:15 INFO - Installing collected packages: blobuploader, requests, docopt 07:01:15 INFO - Running setup.py install for blobuploader 07:01:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:01:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:01:15 INFO - Running setup.py install for requests 07:01:15 INFO - Running setup.py install for docopt 07:01:15 INFO - Successfully installed blobuploader requests docopt 07:01:15 INFO - Cleaning up... 07:01:16 INFO - Return code: 0 07:01:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:16 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:16 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 07:01:16 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 07:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:16 INFO - 'HOME': '/Users/cltbld', 07:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:16 INFO - 'LOGNAME': 'cltbld', 07:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:16 INFO - 'MOZ_NO_REMOTE': '1', 07:01:16 INFO - 'NO_EM_RESTART': '1', 07:01:16 INFO - 'PAGER': '/bin/cat', 07:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:16 INFO - 'PWD': '/builds/slave/test', 07:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:16 INFO - 'SHELL': '/bin/bash', 07:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:16 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:16 INFO - 'USER': 'cltbld', 07:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:16 INFO - 'XPC_FLAGS': '0x0', 07:01:16 INFO - 'XPC_SERVICE_NAME': '0', 07:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-fsYwwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-s4trnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GJtRad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-_uz4Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8IJ4Je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Q_UG7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-WsaKZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:01:16 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-hsY6l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-OP9IJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-3aYjyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-FfnaSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5Da8xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zMcE53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-H1hkYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-sASWr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-EgLmH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:01:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-LVU1Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:01:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:01:18 INFO - Running setup.py install for manifestparser 07:01:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:01:18 INFO - Running setup.py install for mozcrash 07:01:18 INFO - Running setup.py install for mozdebug 07:01:18 INFO - Running setup.py install for mozdevice 07:01:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:01:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:01:18 INFO - Running setup.py install for mozfile 07:01:18 INFO - Running setup.py install for mozhttpd 07:01:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:01:18 INFO - Running setup.py install for mozinfo 07:01:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Running setup.py install for mozInstall 07:01:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Running setup.py install for mozleak 07:01:19 INFO - Running setup.py install for mozlog 07:01:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Running setup.py install for moznetwork 07:01:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Running setup.py install for mozprocess 07:01:19 INFO - Running setup.py install for mozprofile 07:01:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:01:19 INFO - Running setup.py install for mozrunner 07:01:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:01:20 INFO - Running setup.py install for mozscreenshot 07:01:20 INFO - Running setup.py install for moztest 07:01:20 INFO - Running setup.py install for mozversion 07:01:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:01:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:01:20 INFO - Cleaning up... 07:01:20 INFO - Return code: 0 07:01:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:20 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:20 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 07:01:20 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 07:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:20 INFO - 'HOME': '/Users/cltbld', 07:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:20 INFO - 'LOGNAME': 'cltbld', 07:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:20 INFO - 'MOZ_NO_REMOTE': '1', 07:01:20 INFO - 'NO_EM_RESTART': '1', 07:01:20 INFO - 'PAGER': '/bin/cat', 07:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:20 INFO - 'PWD': '/builds/slave/test', 07:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:20 INFO - 'SHELL': '/bin/bash', 07:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:20 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:20 INFO - 'USER': 'cltbld', 07:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:20 INFO - 'XPC_FLAGS': '0x0', 07:01:20 INFO - 'XPC_SERVICE_NAME': '0', 07:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:01:20 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Qc7QYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-uMg7bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ffzhgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-aZCsLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-PFLaEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-uIQNw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-81WYhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-2NqJ9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-74HfVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-RwKbim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:01:21 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)) 07:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:01:21 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-oe951e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:01:22 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)) 07:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:01:22 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-P4mS4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:01:22 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)) 07:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:01:22 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-vapBpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:01:22 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)) 07:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:01:22 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GmXK8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:01:22 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)) 07:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:22 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-oSaF1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:22 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)) 07:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:01:22 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-IsyzeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:01:22 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)) 07:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:01:22 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-S0BGyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:01:22 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)) 07:01:22 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)) 07:01:22 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)) 07:01:22 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)) 07:01:22 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)) 07:01:22 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)) 07:01:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:25 INFO - Downloading blessings-1.6.tar.gz 07:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:01:25 INFO - Installing collected packages: blessings 07:01:25 INFO - Running setup.py install for blessings 07:01:25 INFO - Successfully installed blessings 07:01:25 INFO - Cleaning up... 07:01:25 INFO - Return code: 0 07:01:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:01:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:01:25 INFO - Reading from file tmpfile_stdout 07:01:25 INFO - Current package versions: 07:01:25 INFO - blessings == 1.6 07:01:25 INFO - blobuploader == 1.2.4 07:01:25 INFO - docopt == 0.6.1 07:01:25 INFO - manifestparser == 1.1 07:01:25 INFO - mozInstall == 1.12 07:01:25 INFO - mozcrash == 0.17 07:01:25 INFO - mozdebug == 0.1 07:01:25 INFO - mozdevice == 0.48 07:01:25 INFO - mozfile == 1.2 07:01:25 INFO - mozhttpd == 0.7 07:01:25 INFO - mozinfo == 0.9 07:01:25 INFO - mozleak == 0.1 07:01:25 INFO - mozlog == 3.1 07:01:25 INFO - moznetwork == 0.27 07:01:25 INFO - mozprocess == 0.22 07:01:25 INFO - mozprofile == 0.28 07:01:25 INFO - mozrunner == 6.11 07:01:25 INFO - mozscreenshot == 0.1 07:01:25 INFO - mozsystemmonitor == 0.0 07:01:25 INFO - moztest == 0.7 07:01:25 INFO - mozversion == 1.4 07:01:25 INFO - psutil == 3.1.1 07:01:25 INFO - requests == 1.2.3 07:01:25 INFO - wsgiref == 0.1.2 07:01:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:25 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:25 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 07:01:25 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 07:01:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:25 INFO - 'HOME': '/Users/cltbld', 07:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:25 INFO - 'LOGNAME': 'cltbld', 07:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:25 INFO - 'MOZ_NO_REMOTE': '1', 07:01:25 INFO - 'NO_EM_RESTART': '1', 07:01:25 INFO - 'PAGER': '/bin/cat', 07:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:25 INFO - 'PWD': '/builds/slave/test', 07:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:25 INFO - 'SHELL': '/bin/bash', 07:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:25 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:25 INFO - 'USER': 'cltbld', 07:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:25 INFO - 'XPC_FLAGS': '0x0', 07:01:25 INFO - 'XPC_SERVICE_NAME': '0', 07:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 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)) 07:01:25 INFO - Cleaning up... 07:01:26 INFO - Return code: 0 07:01:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:26 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:26 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 07:01:26 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 07:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:26 INFO - 'HOME': '/Users/cltbld', 07:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:26 INFO - 'LOGNAME': 'cltbld', 07:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:26 INFO - 'MOZ_NO_REMOTE': '1', 07:01:26 INFO - 'NO_EM_RESTART': '1', 07:01:26 INFO - 'PAGER': '/bin/cat', 07:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:26 INFO - 'PWD': '/builds/slave/test', 07:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:26 INFO - 'SHELL': '/bin/bash', 07:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:26 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:26 INFO - 'USER': 'cltbld', 07:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:26 INFO - 'XPC_FLAGS': '0x0', 07:01:26 INFO - 'XPC_SERVICE_NAME': '0', 07:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:26 INFO - Downloading/unpacking jsonschema 07:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:01:28 INFO - Downloading/unpacking functools32 (from jsonschema) 07:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:29 INFO - Downloading functools32-3.2.3-2.tar.gz 07:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:01:29 INFO - warning: no files found matching '*.txt' 07:01:29 INFO - no previously-included directories found matching 'build' 07:01:29 INFO - no previously-included directories found matching 'dist' 07:01:29 INFO - no previously-included directories found matching '.git*' 07:01:29 INFO - Installing collected packages: jsonschema, functools32 07:01:29 INFO - Running setup.py install for functools32 07:01:29 INFO - warning: no files found matching '*.txt' 07:01:29 INFO - no previously-included directories found matching 'build' 07:01:29 INFO - no previously-included directories found matching 'dist' 07:01:29 INFO - no previously-included directories found matching '.git*' 07:01:29 INFO - Successfully installed jsonschema functools32 07:01:29 INFO - Cleaning up... 07:01:29 INFO - Return code: 0 07:01:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:01: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')]} 07:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01: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')]} 07:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:29 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 0x1037daa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037ea420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81c8473ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, '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.exDpBR4LC7/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.HX9U0pbWjh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 07:01:29 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 07:01:29 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 07:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:29 INFO - 'HOME': '/Users/cltbld', 07:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:29 INFO - 'LOGNAME': 'cltbld', 07:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:29 INFO - 'MOZ_NO_REMOTE': '1', 07:01:29 INFO - 'NO_EM_RESTART': '1', 07:01:29 INFO - 'PAGER': '/bin/cat', 07:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:29 INFO - 'PWD': '/builds/slave/test', 07:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:29 INFO - 'SHELL': '/bin/bash', 07:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:01:29 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:29 INFO - 'USER': 'cltbld', 07:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:29 INFO - 'XPC_FLAGS': '0x0', 07:01:29 INFO - 'XPC_SERVICE_NAME': '0', 07:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:29 INFO - Downloading/unpacking flake8 07:01:29 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 07:01:29 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 07:01:29 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 07:01:29 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 07:01:29 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 07:01:29 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 07:01:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:01:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:01:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:32 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 07:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:01:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:01:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:01:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:01:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:01:33 INFO - no previously-included directories found matching 'docs/_build' 07:01:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:01:33 INFO - Running setup.py install for pep8 07:01:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:01:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:01:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:01:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:01:33 INFO - no previously-included directories found matching 'docs/_build' 07:01:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:01:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:01:33 INFO - Cleaning up... 07:01:33 INFO - Return code: 0 07:01:33 INFO - Running post-action listener: _resource_record_post_action 07:01:33 INFO - Running post-action listener: _start_resource_monitoring 07:01:33 INFO - Starting resource monitoring. 07:01:33 INFO - ##### 07:01:33 INFO - ##### Running install step. 07:01:33 INFO - ##### 07:01:33 INFO - Running pre-action listener: _resource_record_pre_action 07:01:33 INFO - Running main action method: install 07:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:01:34 INFO - Reading from file tmpfile_stdout 07:01:34 INFO - Detecting whether we're running mozinstall >=1.0... 07:01:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:01:34 INFO - Reading from file tmpfile_stdout 07:01:34 INFO - Output received: 07:01:34 INFO - Usage: mozinstall [options] installer 07:01:34 INFO - Options: 07:01:34 INFO - -h, --help show this help message and exit 07:01:34 INFO - -d DEST, --destination=DEST 07:01:34 INFO - Directory to install application into. [default: 07:01:34 INFO - "/builds/slave/test"] 07:01:34 INFO - --app=APP Application being installed. [default: firefox] 07:01:34 INFO - mkdir: /builds/slave/test/build/application 07:01:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:02:05 INFO - Reading from file tmpfile_stdout 07:02:05 INFO - Output received: 07:02:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:02:05 INFO - Running post-action listener: _resource_record_post_action 07:02:05 INFO - ##### 07:02:05 INFO - ##### Running run-tests step. 07:02:05 INFO - ##### 07:02:05 INFO - Running pre-action listener: _resource_record_pre_action 07:02:05 INFO - Running main action method: run_tests 07:02:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:02:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:02:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:02:05 INFO - 2016-05-31 07:02:05.514 screenresolution[1654:8027] starting screenresolution argv=screenresolution get 07:02:05 INFO - 2016-05-31 07:02:05.516 screenresolution[1654:8027] Display 0: 1600x1200x32@60 07:02:05 INFO - 2016-05-31 07:02:05.521 screenresolution[1655:8029] starting screenresolution argv=screenresolution list 07:02:05 INFO - Available Modes on Display 0 07:02:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:02:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:02:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:02:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:02:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:02:05 INFO - Intel Iris: 07:02:05 INFO - Chipset Model: Intel Iris 07:02:05 INFO - Type: GPU 07:02:05 INFO - Bus: Built-In 07:02:05 INFO - VRAM (Dynamic, Max): 1536 MB 07:02:05 INFO - Vendor: Intel (0x8086) 07:02:05 INFO - Device ID: 0x0a2e 07:02:05 INFO - Revision ID: 0x0009 07:02:05 INFO - Displays: 07:02:05 INFO - Display: 07:02:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:02:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:02:05 INFO - Display Serial Number: 1600x1200 60 07:02:05 INFO - Main Display: Yes 07:02:05 INFO - Mirror: Off 07:02:05 INFO - Online: Yes 07:02:05 INFO - Rotation: Supported 07:02:05 INFO - Return code: 0 07:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:02:05 INFO - Python 2.7.3 07:02:05 INFO - Return code: 0 07:02:05 INFO - grabbing minidump binary from tooltool 07:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:05 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 0x103679e00>, '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 0x1034e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103674b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:02:05 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 07:02:05 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 07:02:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:02:05 INFO - Return code: 0 07:02:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:02:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:02:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:02:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:02:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:02:06 INFO - Return code: 0 07:02:06 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/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 07:02:06 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/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 07:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exDpBR4LC7/Render', 07:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:06 INFO - 'HOME': '/Users/cltbld', 07:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:06 INFO - 'LOGNAME': 'cltbld', 07:02:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:02:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:06 INFO - 'MOZ_NO_REMOTE': '1', 07:02:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:02:06 INFO - 'NO_EM_RESTART': '1', 07:02:06 INFO - 'PAGER': '/bin/cat', 07:02:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:06 INFO - 'PWD': '/builds/slave/test', 07:02:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:06 INFO - 'SHELL': '/bin/bash', 07:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HX9U0pbWjh/Listeners', 07:02:06 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 07:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:06 INFO - 'USER': 'cltbld', 07:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:06 INFO - 'XPC_FLAGS': '0x0', 07:02:06 INFO - 'XPC_SERVICE_NAME': '0', 07:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:06 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/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 07:02:07 INFO - mozversion application_buildid: 20160531060243 07:02:07 INFO - mozversion application_changeset: 9f96ed6259453ce242f41454ae822efc335e2e3c 07:02:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:02:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:02:07 INFO - mozversion application_name: Firefox 07:02:07 INFO - mozversion application_remotingname: firefox-dev 07:02:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:02:07 INFO - mozversion application_vendor: Mozilla 07:02:07 INFO - mozversion application_version: 48.0a2 07:02:07 INFO - mozversion platform_buildid: 20160531060243 07:02:07 INFO - mozversion platform_changeset: 9f96ed6259453ce242f41454ae822efc335e2e3c 07:02:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:02:07 INFO - mozversion platform_version: 48.0a2 07:02:07 INFO - using testdate: 1464703327 07:02:07 INFO - actual date: 1464703327 07:02:07 INFO - starting webserver on 'localhost:49278' 07:02:07 INFO - SUITE-START | Running 2 tests 07:02:07 INFO - TEST-START | dromaeo_css 07:02:07 INFO - operating with platform_type : mac_ 07:02:07 INFO - Initialising browser for dromaeo_css test... 07:02:07 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpLiXmtQ/profile http://localhost:49278/getInfo.html) 07:02:09 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 07:02:09 INFO - PROCESS | 1673 | colorDepth:24 07:02:09 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 07:02:09 INFO - PROCESS | 1673 | __metrics 07:02:09 INFO - PROCESS | 1673 | 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" 07:02:09 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:02:10 INFO - TEST-INFO | 1673: exit 0 07:02:10 INFO - Browser initialized. 07:02:10 INFO - Running cycle 1/1 for dromaeo_css test... 07:02:10 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpLiXmtQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:02:22 INFO - PROCESS | 1676 | RSS: Main: 211464192 07:02:22 INFO - PROCESS | 1676 | 07:03:13 INFO - PROCESS | 1676 | 1464703393987 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] 07:05:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 07:05:25 INFO - PROCESS | 1676 | RSS: Main: 300412928 07:05:25 INFO - PROCESS | 1676 | 07:08:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 07:08:29 INFO - PROCESS | 1676 | RSS: Main: 352137216 07:08:29 INFO - PROCESS | 1676 | 07:11:31 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 07:11:32 INFO - PROCESS | 1676 | RSS: Main: 363393024 07:11:32 INFO - PROCESS | 1676 | 07:14:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 07:14:34 INFO - PROCESS | 1676 | RSS: Main: 353566720 07:14:34 INFO - PROCESS | 1676 | 07:17:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 07:17:38 INFO - PROCESS | 1676 | RSS: Main: 324280320 07:17:38 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 07:20:41 INFO - PROCESS | 1676 | RSS: Main: 369487872 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | __start_tp_report 07:20:41 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 07:20:41 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 07:20:41 INFO - PROCESS | 1676 | |i|pagename|runs| 07:20:41 INFO - PROCESS | 1676 | |0;dojo.html;3351.4805205193197;3410.7782994105364;3420.1962538803377;3450.7411944104197;3489.441689329708;5583.35791383991;5608.663480191191;5615.5507559395255;5617.354004289508;5641.069223578104;5589.049861523541;5618.747156377963;5641.266635337411;5641.68970706611;5644.943550564503;4613.1926912790295;5071.644984851062;5129.82045628404;5146.356705411824;5226.477352264762;4872.512748725135;5199.6360254782185;5201.453847346012;5202.401723801754;5204.115300398922;5143.974280128594;5181.378234611851;5182.896342073156;5200.401953775327;5205.765740541666;5174.24973378858;5209.843704688866;5215.295935048758;5233.633645644806;5278.97360513195;5015.799368025275;5074.340335756328;5102.897942041175;5133.255677926717;5143.408508021588;5452.073147564884;5474.124140137559;5478.096114141202;5519.116941289374;5526.50261476465;6091.238595175603;6134.355892631254;6168.75324987;6168.84577885552;6174.783882564088;6012.068129439944;6104.850025216341;6112.235970503687;6221.440070393686;6246.87506249877;5663.122216056518;5712.200291959129;5723.227364305849;5738;5748.051571490699;6268.310485846553;6302.464290535264;6304.306526282106;6304.905426418607;6308.243010838637;5838.503727183237;6079.148919151322;6087.391260873966;6093.329733729286;6106.511479081662;6080.452759251689;6118.619179173164;6212.9378706213265;6244.344343843922;6258.874822503524;6061.424164704346;6099.268087829489;6126.387361263838;6141.692915354214;6143.938560614426;5992.250968628921;6141.631502109888;6179.227596550431;6184.350643182401;6202.038684003986;4823.252395878644;4849.903001940012;4893.4617192108835;4919.729414882216;4951.430585482643;4363.214621368184;4363.454568178978;4372.672049596293;4402.097569998206;4418;5498.670079795145;5551.639143455663;5629.605927585029;5632.690202038845;5648.5763567731765;6150.538709596798;6222.595531290452;6240.875182496325;6264.968675156595;6272.529560282997;5607.130894711327;5610;5612.35457922336;5616.213730077792;5627.071533196977;5232.2674825524455;5306.8407947761625;5315.654482458628;5318.787248510093;5328.520433160957;6229.5327850410495;6281.842953926097;6284.591501552384;6298.370162983665;6305.779297724557;6107.992181290215;6125.509959203164;6152.692365381802;6155.569110162246;6157.107219453064;6199.5040396767245;6236.407541283571;6294.653794041372;6305.306416294296;6307.684615769285;6090.634561926298;6099.603525770988;6115.205023346936;6118.296395914527;6122.398155112157;5144.485551444825;6181.412765787243;6199.225096862892;6201.3178550358525;6209.937900620936;5700.25896633435;5775.104858746901;5919.852003699942;5997.0404735242155;6006.549508786771;5290.365156181129;5306.681599104066;6049.18336024631;6052.727627256696;6067.696615169312;5578.665280083208;5876.764929402776;5883.2057672213705;5908.143319218603;5915;5850.94149058504;5878.882422351444;5962.135490353961;5977.910331344946;6022.217111775616;5773.826785196451;5835.416458354131;5836.416358364299;5840.883182336414;5851.912221316598 07:20:41 INFO - PROCESS | 1676 | |1;ext.html;503.3230305239453;531.8379341139611;534.0680512505676;545.0951420641735;582.7086456771614;22644.773552264473;37750.24499510008;38404.03989900254;38445.61554384456;38500.80749596248;41702;42274.73175804731;42277.7886110569;42341.36487952683;42497.15005699884;8398.328133749286;8506.36202284831;9399.999999999984;9654.13112819846;9707.223422126248;11398.658040258806;11641;11648.650540483757;11650.766984660302;11686.532538698442;11376.943115284454;11461.484233209525;11464.942675286611;11476;11476.655700329004;8572.957135214347;11371;11458.083353331713;11669.124815638776;11691.240069395548;11249.043831274352;11363;11375.601853935073;11378.31730096197;11416.60041898538;38099.857004289915;38401.23197536061;38497.23005539901;38534.42198367027;38560.035999099964;3182.2203560127687;3201.6158061032593;3240.238543942172;3269.869205231788;3280.130765347185;28199.85900070507;28266.858665706746;28777.85611071952;28812.423751524853;29043.12870613885;2736.65791776028;2741.0954385053083;2773.55623100303;2804.9719502804915;2813.8030337876357;430142;437197;469767;470450;474185;35655;61325.693371532856;62501;62616;62641;35784.92645220648;35793.28413431717;35839.104022399646;35898;36066.819665902025;15343.309551070228;15642.374305027672;15657.843421565867;15658.295376708076;15721.449749258722;12375;12564.497420103093;13254.204747714975;13506.729865402833;13526.25605591702;8145.79635509117;8188.50868947865;8198;8254.752357429286;8267.62795674184;3406.6082400524256;3464.272502774395;3472.6700963408434;3481.3037392521596;3513.332466831294;978.9412635241908;1002.5538635307364;1032.4837581209395;1057.437158660378;1122.7193201699574;29426.705732942824;29526.704732952825;29915;30054.398912021636;30257.848710756305;1097.1990446973755;1099.5656715597295;1166.632510759108;1196.2941864300105;1205.6744678936814;14515.201663908376;14545.34545945435;14983.550493485212;15371.077735335915;15504.844951550565;618350;982768;997216;1001376.9931150298;1001562;61277;61439;61992.69003655043;62037.75924481485;62328.376716232255;1451.4847229233892;1462.4661998370518;1466.765317549187;1494.5292232946588;1496.132243298884;28782.424351512433;30520.694793051785;30751.538726918665;30875.536866947463;31075.689243108183;53194.2020869695;53354.19968700395;53720.462795371546;53804.461955381514;53835.19247211217;494500;496516;497740;497898;498798;23389.41526461852;23716.05135794618;24706.50586988214;27951.580726288714;28033.299167520974;42095.57904420917;42867.785661071495;43412.78293608512;43467.782661086494;43597.34603980879;32725.004208462626;36209.63790362063;37352.626473736;37447.81276093602;37964.86105416842;30952.84523577368;36240.45639315465;36250.456243155844;36997;37024.62975370319;36655;36898.44652330164;38056.23887522179;38637.42043869288;39294.41058384184;30087.849560752933;31334.843325783226;31844.840775795972;31850.681493184773;31861.362772743953;24299.39251518726;25167.37081572975;25441.49117017686;25479.36301592475;25972.870135649955 07:20:41 INFO - PROCESS | 1676 | |2;jquery.html;405.08652987513153;405.95128584569846;406.80270069016524;410.9876703698888;416.04310086800353;13097.345132743372;13413.463461461553;19852.70220946685;20488.89755551222;20494.28270010548;19989.700154497696;20378.388648340537;20430.182792688312;20451.875146866918;21295.36113916585;5236.607254455912;5421.674699518022;5443.428440013801;5447.863803404926;5449.700266485342;3879.243547508237;4067.5932406759384;5661.575381846357;5938.079597662348;5948.524118070544;5702.201691763156;5931.911021334683;5940.673262970535;5946.732397042144;5971.10433434986;5657.547396208293;5917.911231331534;5927.1998280232265;5937.287525496925;5949.077892926603;5717.656940583537;5759.280089988752;5759.827205183851;5760.8271751847515;5767;13088.803667944989;13293;13758.65603359914;13805.309734513234;13855.792163117561;2168.8048075673264;2197.0113448948036;2272.5909336319455;2293.323469576479;2726;9357.859632105456;9555.761105972406;9904.513845740912;10012.04885535873;10164.542595583216;1722.3799432204396;1771.3003363671278;1793.1841012339485;1815.3374018483291;1818.8908665480114;84741;109906;111728.4413577935;114405.42797286043;117515;11419;11669.708257293465;11686.532538698528;11790.351530665896;11829.526818927146;15079.623009424633;15082.17048062367;15144.318505667272;15150.54548363551;15161.469348572968;8841.69054083104;8946.821063578693;9057.275417966546;9068.501232432147;9072.455652660861;8978.551072446351;8991.325650576233;8993.100689930954;9081.636734530544;9092.181703646704;4023.5775243599587;4631.166390049823;4635.096156249567;4731.4558825735485;4755.286706993909;1749.2046911352688;1941.2332128809041;1987.9900600496906;1998.6902030185345;2021.2723419569238;881.7442941547008;1020.6733845169623;1043.3322673488974;1064.122099268107;1098.9340639561651;14729.747971422335;20856.359796917775;21233.68149477759;21242.681359779617;21421.89289053576;870.6430363550913;955.1546881010356;984.7833476635273;989.8812142542797;1003.7189586915675;9451.952740236393;11048.723781905355;11414.486348114355;11418.257813242113;11428.542858285742;487181;580302;589888;599313;600697;115118;118279;118580;119192.62999836444;120855;1026.440589878518;1026.97432564185;1076.3703233608426;1094.9452527373758;1096.5230124895695;18208.99850508235;18438.170282337596;18774.436766897015;18862.622747544698;19122.426327210207;52442.475575245284;52604.94790104253;52649.736751316;53159;54623.726881365335;73288.63355683188;75383.85388803668;76132;76234.23765762271;76496;6664.366885145903;6747.561408508432;7832.647530861011;8076.394270429624;8442.577871106298;10333;12562.057845661795;12728;12839.614811555668;12867.034972377296;9455.716328510156;10417.281148610395;10991.120710342993;11054.834177487182;11111.44442777874;8811.735647930573;10876.021158095493;10922.781544369227;11185.552577897119;11305.378204198849;11359.772804543698;11387.715307117389;11431.542738290374;11622.941885290858;11689.532418703157;8094.87857682147;8339.54132522717;8345.499270043816;8381.16188381157;8553.87169192475;10037.799244015225;11121.999020088231;11370.886291136983;11436.256643318491;11482.827757583476 07:20:41 INFO - PROCESS | 1676 | |3;mootools.html;1430.8569143085685;1562.9374825006996;1581.0513691784931;1810.1039985207317;1964.4597735622701;7836.824476328547;8559.78600534986;8697.478151310917;8721.258693011087;8733.606987685553;8425.410221284512;8707.73876783696;8710.869336959935;8715.389922705413;8715.651373945035;2153.967690484644;2217.5010622609943;2221.611218036845;2434.8782560871928;2467.1858286765414;2064.3187748043183;2235.094786611417;2420.7942324902406;2479.1818699829014;2489.2781093482868;1966.4100769769084;2427.7086749589985;2432.4526981429212;2437.9512409751796;2443.242594795619;2144.7426308842882;2416.1905761569806;2426.2842461473906;2427.526632306701;2439.7926176275128;2389.617661174204;2452.350127216289;2457.606782914725;2462.2859370782453;2465.285067330472;8564.443311184816;8655.6970506032;8660;8661.220490155853;8662.783430414227;5226.503482169188;5327.334083239595;5331.573474122061;5334.866628334284;5363.678179309254;3365.9495107573407;3395.6604339566093;3487.319972605343;3498.5276987606603;3504.6320136385566;1029.7065336379096;1047.7485403503103;1048.8741351037925;1071.3250652089134;1096.6107032003683;12022.39888005605;12029.759404811855;12032.759344813143;12040.518379264819;12053.57812476559;4665.9766701166955;4694.460137084211;4810.542998415145;4821.614270858323;4839.370881785346;9451.905480945241;10033.849492257623;10036.749081273027;10038;10073.1437827784;4943;5386.784528618811;5391.78432862689;5407.486288802557;5444.945550544523;4249.46881639795;4413.977930110394;4895.779689913962;4925.261210818406;4942.752862356868;3984.30274701926;4018.4976877890263;4019.738716983387;4037.515498140242;4114.444549985774;1330.6872884872048;1356.0643156222372;1357.314556149158;1370.0751992405087;1374.656335916021;887.658251573149;895.7447127568742;931.4830269200659;937.7059657672369;957.6600306890954;5799.144273832333;5877.735501902424;5912.320083190402;5942.405759424109;6019.367966363557;927.2489283680105;952.4713783849892;952.5999080386255;957.712686194139;971.9076687714655;3735.6637902588895;3955.5253369595257;3976.284268831638;4034.334334834778;4040.1111755413763;10258.487075646188;10730.356178629307;10731.302465339884;10758.838617420744;10768.300060496043;9524.571394287274;10255.28213025096;10411.895881041244;10522.1056210223;10583.941605839354;8153.714619988272;8163.0612479564425;8228.506289622523;8247.628856701464;8290.709825156091;4729.266963620506;5556.972215138899;5808.5062769665055;5939;5989.880202395841;9105.453672779655;9358.92372377158;9443.386179898285;9444.716658500256;9455.479948602893;9103.49930753815;9578;9582.952085239529;9587.952060239653;9812.803743924938;6633.502487313374;6803.149606299213;7432.516886402366;7534.660940257592;7574.810629734301;7989.960050199712;8112.553809540532;8176.264136227768;8177.182281771775;8196.959015205124;6209.2548894133;7180.964095179491;7248.130224373109;7342.926570734225;7343.816404589928;7141.9642901785155;7162.534435261691;7227.747028853965;7289.088863892013;7304.342609164988;6839.384455399038;6871.553349032297;6872.793816185523;6977.476689248225;6993.160820701345;4783.976080119577;4874.731889746098;4879.584920373052;4901.2893130496595;4907.877303067452;4906.2640603909695;4919.1883339248625;4975.402951645826;5011.899762004812;5112.233165025127 07:20:41 INFO - PROCESS | 1676 | |4;prototype.html;319.88963807486425;325.31845783084435;333.9432296509593;334.3897387268235;339.47381558584186;5640.915386269204;6691.895837186865;6710.228323742759;6715.992601109836;6755.763548275811;6293.559450838443;6494.51291153163;6498.187726534184;6515.3612786821295;6633.668316584163;3944.960550394488;4177.5613560576085;4799.376081109468;4836.347093142434;4859.125357435661;4783.71297722136;4858.1983972644475;4862.683925544846;4869.099216644914;4905.75471226439;4800.975995120019;4821.30091136785;4823.734694591796;4824.348712923746;4848.17581011229;4633.2818413146015;4732.2428411454;4737.028909073631;4785.019071090418;4791.856244312677;4076.775777332275;4126.4938297521485;4203.26442872496;4215.747055176699;4239.59723826236;6340.682965851689;6359.713812878432;6365.427111559982;6369.394907483782;6390.744370225186;5809.8547536311935;5821.912671309934;5828.300603927513;5830.271216097988;5836.445537673914;5842.977478941169;6993.89509157363;7062.258462861429;7063.540869843495;7067.57594544329;4372.19114463822;4547.272436410159;4549.61328287096;4607.424071991001;4612;7307.707691692326;7440.516366436218;7453.515521491086;7468.4025277977635;7473.775786726407;4654.045920586272;4654.25531914892;4655.790489427984;4656.790444429941;4659.464161621457;3970.543387510473;4677.9942312403;4702.9764851176205;4721.409823772028;4726.740029298421;5584.6090773646265;5590.630295577609;5590.888182236333;5597.132444471114;5608.074667679869;6623.039659249382;6831.2144103427745;7094.11323584552;7101.680424380916;7121.643917804089;1429.2139323372103;1620.7568864670159;1635.7731701224081;1639.0739232333694;1639.8278180791083;1024.1857723110145;1026.4662375564665;1043.4052590023614;1044.9425281609433;1048.2662136504478;6433.228012638513;6550;6551.6069035857045;6585.86828263432;6750.054992301082;7263.745768898063;7406.629668516553;7424.591647459333;7437.702491900372;7444.516106453063;6625.86748265032;6630.7679231226675;6632.43624291931;6636.5354425190735;6637.601743895285;3859.6526312632;3912.295786758411;3913.745606535566;3916.2559113768293;3917.5103112110987;7451.441141914378;7681.500702454323;7715.652795624211;7723.266289702469;7749.7675069748;7522.774316770506;7547.16981132075;7552.320291173821;7568.508046976929;7574.545527268381;7672.232776722283;7703.306702396699;7773.222677732295;7790.766277011699;7802.141764405911;7397.852042959003;7406.851862962602;7409.962950185214;7410.62946852666;7418.443616728875;7004.824879378057;7270.272972702687;7270.636468176675;7299.233580473974;7318.853622927618;6019.6990150493175;6763;7708.267714567107;7727.609030374587;7741.845163096819;4604.815807367668;4652.069586082865;4654.20878450671;4658.976705116453;4659.627229821674;5944.562729280974;7333.74331898381;7336.816579585553;7341.70633174667;7347.632618369167;5992.880142397041;6813.625250611264;6841.965790171216;7086.610236437045;7097.432205423657;7030.156381234285;7053.1536215652395;7075.433965282868;7076.5846830633045;7081.044059051962;6934.375906168469;6937.583744975318;6939.306069393222;6939.4795390346935;6948.062011628567;5694.943050569441;7014.579125252501;7031.507794454339;7055.576665400093;7094;5953.285605727167;6591.703373348115;6639.269680335063;6893.20728116273;6921.2386637468835 07:20:41 INFO - PROCESS | 1676 | |5;yui.html;676.8138761840494;686.9622170780605;710.1513691139091;712.8823744082226;767.2174382130225;13048.543300984467;13832.446702131916;14602.634934126652;14902.031367961075;14992.700145997087;13772.931135344334;13872.514461993807;13874.306284685776;13885.861141388583;13901.235432051233;742.9777106686781;754.6038329876803;756.9016027916391;911.307406371156;946.6733976778;718.5580867766338;733.2923728601899;740.0933856026378;913.4382354851751;936.7049379445486;941.5480569326727;969.0212884986145;971.3297824501071;971.9854202186938;975.2539307429822;901.1169054326797;933.1927882381725;963.5182408795603;964.0889359555223;964.2286171063156;573.1088157914442;576.3113079869557;579.8637320229743;583.8890610783938;653.7385045981598;11441.656750297505;14694.191819449927;14866.702665946728;14873.553793386216;14901.180435076067;12416.75166496665;12487.562935297312;12638.431270592846;12867.87132128676;13042.804357934638;9899.505024748734;9915.90084099157;10175.694729158064;10190.388576685422;10591.523381447852;3467.5064300603517;3471.8090505022215;3513.0690367052753;3555.9466608000903;3559.0212691509782;81449;81527.5923620384;81980.95047623858;82157.58921205356;82336;9513.619455221851;9600.231981441468;9681.419114853132;9693.176080033136;9762.560684769202;21016.579668406546;21482.462938426663;21534.353969380943;21603.89198054031;21614.35156945294;7407.259274072658;7565.205653406423;7612.7716168515035;7618.771436856903;7752.728654497066;4063.532693740258;4077.4903137107863;4125.834966601363;4299.118680670455;4353.477582690098;3575.73182011345;3580.4092324766643;3631.3100510902846;3635.0003748969243;3651.744377893575;655.1875674164003;666.7133132752866;679.49038221334;687.4088284075691;701.5687996487109;486.72986492497006;505.7774579185147;552.4447929830575;596.4691424632081;611.6055144431816;8095.2714255717265;8129.227723366271;8184.713535026245;8330.875036874451;8331.791705207419;471.33541706194103;474.2885671492761;602.8442388844445;613.6379536073649;618.935011823761;3369.921625079951;3765.45400916871;3824.0822202671716;3902.453656488094;3989.5412027616617;85939.14060859436;88355.37334699923;88714.55642721875;88836;89326;68913.3108668907;69542.30457695358;71211.64394177996;72085.6395718018;72286.63856680683;48504.51495485;66762.66474670479;67059.3294067053;67532;68401.65799170973;7534.962325188339;7670.979667282739;7817.37461003107;7888.211178882066;8104.98687664042;46649.766751167386;47062.529374707185;47439.52560474489;47450.813729657035;48157.27764083471;60787.69606152118;63992.3600764005;64160.35839641731;64570.35429645831;66231.00653490105;9800.215982721507;9989.550470229002;10065.69802905914;10181.083702466814;10210.948945255226;10642.04221620058;10839.512221950188;10923.344599324066;10930.56277748881;11043.116550675766;10101.191904647463;10355.792884142124;10494.790104198026;10530.894691053298;10631.787364252827;10104.393736375841;10352.378857268588;10463.68608941733;10495;10673.89326106729;10252.077313041862;10322.793544128925;10395.94802025985;10542.420166890895;10792;8715.782105447415;8841;8990.145936136056;9028.458292502472;9066.546672666473;8348.165183481604;8494.91505084966;8499.830003399773;8655.6970506032;8812.339074569305 07:20:41 INFO - PROCESS | 1676 | __end_tp_report 07:20:41 INFO - PROCESS | 1676 | __start_cc_report 07:20:41 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1242 07:20:41 INFO - PROCESS | 1676 | __end_cc_report 07:20:41 INFO - PROCESS | 1676 | __startTimestamp1464704441906__endTimestamp 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | ------- Summary: start ------- 07:20:41 INFO - PROCESS | 1676 | Number of tests: 6 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5655.55 Median:5835.42 stddev:624.79 (10.7%) stddev-sans-first:600.00 07:20:41 INFO - PROCESS | 1676 | Values: 3351.5 3410.8 3420.2 3450.7 3489.4 5583.4 5608.7 5615.6 5617.4 5641.1 5589.0 5618.7 5641.3 5641.7 5644.9 4613.2 5071.6 5129.8 5146.4 5226.5 4872.5 5199.6 5201.5 5202.4 5204.1 5144.0 5181.4 5182.9 5200.4 5205.8 5174.2 5209.8 5215.3 5233.6 5279.0 5015.8 5074.3 5102.9 5133.3 5143.4 5452.1 5474.1 5478.1 5519.1 5526.5 6091.2 6134.4 6168.8 6168.8 6174.8 6012.1 6104.9 6112.2 6221.4 6246.9 5663.1 5712.2 5723.2 5738.0 5748.1 6268.3 6302.5 6304.3 6304.9 6308.2 5838.5 6079.1 6087.4 6093.3 6106.5 6080.5 6118.6 6212.9 6244.3 6258.9 6061.4 6099.3 6126.4 6141.7 6143.9 5992.3 6141.6 6179.2 6184.4 6202.0 4823.3 4849.9 4893.5 4919.7 4951.4 4363.2 4363.5 4372.7 4402.1 4418.0 5498.7 5551.6 5629.6 5632.7 5648.6 6150.5 6222.6 6240.9 6265.0 6272.5 5607.1 5610.0 5612.4 5616.2 5627.1 5232.3 5306.8 5315.7 5318.8 5328.5 6229.5 6281.8 6284.6 6298.4 6305.8 6108.0 6125.5 6152.7 6155.6 6157.1 6199.5 6236.4 6294.7 6305.3 6307.7 6090.6 6099.6 6115.2 6118.3 6122.4 5144.5 6181.4 6199.2 6201.3 6209.9 5700.3 5775.1 5919.9 5997.0 6006.5 5290.4 5306.7 6049.2 6052.7 6067.7 5578.7 5876.8 5883.2 5908.1 5915.0 5850.9 5878.9 5962.1 5977.9 6022.2 5773.8 5835.4 5836.4 5840.9 5851.9 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:73378.89 Median:17997.44 stddev:179973.46 (1000.0%) stddev-sans-first:180395.16 07:20:41 INFO - PROCESS | 1676 | Values: 503.3 531.8 534.1 545.1 582.7 22644.8 37750.2 38404.0 38445.6 38500.8 41702.0 42274.7 42277.8 42341.4 42497.2 8398.3 8506.4 9400.0 9654.1 9707.2 11398.7 11641.0 11648.7 11650.8 11686.5 11376.9 11461.5 11464.9 11476.0 11476.7 8573.0 11371.0 11458.1 11669.1 11691.2 11249.0 11363.0 11375.6 11378.3 11416.6 38099.9 38401.2 38497.2 38534.4 38560.0 3182.2 3201.6 3240.2 3269.9 3280.1 28199.9 28266.9 28777.9 28812.4 29043.1 2736.7 2741.1 2773.6 2805.0 2813.8 430142.0 437197.0 469767.0 470450.0 474185.0 35655.0 61325.7 62501.0 62616.0 62641.0 35784.9 35793.3 35839.1 35898.0 36066.8 15343.3 15642.4 15657.8 15658.3 15721.4 12375.0 12564.5 13254.2 13506.7 13526.3 8145.8 8188.5 8198.0 8254.8 8267.6 3406.6 3464.3 3472.7 3481.3 3513.3 978.9 1002.6 1032.5 1057.4 1122.7 29426.7 29526.7 29915.0 30054.4 30257.8 1097.2 1099.6 1166.6 1196.3 1205.7 14515.2 14545.3 14983.6 15371.1 15504.8 618350.0 982768.0 997216.0 1001377.0 1001562.0 61277.0 61439.0 61992.7 62037.8 62328.4 1451.5 1462.5 1466.8 1494.5 1496.1 28782.4 30520.7 30751.5 30875.5 31075.7 53194.2 53354.2 53720.5 53804.5 53835.2 494500.0 496516.0 497740.0 497898.0 498798.0 23389.4 23716.1 24706.5 27951.6 28033.3 42095.6 42867.8 43412.8 43467.8 43597.3 32725.0 36209.6 37352.6 37447.8 37964.9 30952.8 36240.5 36250.5 36997.0 37024.6 36655.0 36898.4 38056.2 38637.4 39294.4 30087.8 31334.8 31844.8 31850.7 31861.4 24299.4 25167.4 25441.5 25479.4 25972.9 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:33219.72 Median:21269.02 stddev:95475.15 (448.9%) stddev-sans-first:95711.19 07:20:41 INFO - PROCESS | 1676 | Values: 405.1 406.0 406.8 411.0 416.0 13097.3 13413.5 19852.7 20488.9 20494.3 19989.7 20378.4 20430.2 20451.9 21295.4 5236.6 5421.7 5443.4 5447.9 5449.7 3879.2 4067.6 5661.6 5938.1 5948.5 5702.2 5931.9 5940.7 5946.7 5971.1 5657.5 5917.9 5927.2 5937.3 5949.1 5717.7 5759.3 5759.8 5760.8 5767.0 13088.8 13293.0 13758.7 13805.3 13855.8 2168.8 2197.0 2272.6 2293.3 2726.0 9357.9 9555.8 9904.5 10012.0 10164.5 1722.4 1771.3 1793.2 1815.3 1818.9 84741.0 109906.0 111728.4 114405.4 117515.0 11419.0 11669.7 11686.5 11790.4 11829.5 15079.6 15082.2 15144.3 15150.5 15161.5 8841.7 8946.8 9057.3 9068.5 9072.5 8978.6 8991.3 8993.1 9081.6 9092.2 4023.6 4631.2 4635.1 4731.5 4755.3 1749.2 1941.2 1988.0 1998.7 2021.3 881.7 1020.7 1043.3 1064.1 1098.9 14729.7 20856.4 21233.7 21242.7 21421.9 870.6 955.2 984.8 989.9 1003.7 9452.0 11048.7 11414.5 11418.3 11428.5 487181.0 580302.0 589888.0 599313.0 600697.0 115118.0 118279.0 118580.0 119192.6 120855.0 1026.4 1027.0 1076.4 1094.9 1096.5 18209.0 18438.2 18774.4 18862.6 19122.4 52442.5 52604.9 52649.7 53159.0 54623.7 73288.6 75383.9 76132.0 76234.2 76496.0 6664.4 6747.6 7832.6 8076.4 8442.6 10333.0 12562.1 12728.0 12839.6 12867.0 9455.7 10417.3 10991.1 11054.8 11111.4 8811.7 10876.0 10922.8 11185.6 11305.4 11359.8 11387.7 11431.5 11622.9 11689.5 8094.9 8339.5 8345.5 8381.2 8553.9 10037.8 11122.0 11370.9 11436.3 11482.8 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5637.72 Median:4942.88 stddev:3127.45 (63.3%) stddev-sans-first:3120.24 07:20:41 INFO - PROCESS | 1676 | Values: 1430.9 1562.9 1581.1 1810.1 1964.5 7836.8 8559.8 8697.5 8721.3 8733.6 8425.4 8707.7 8710.9 8715.4 8715.7 2154.0 2217.5 2221.6 2434.9 2467.2 2064.3 2235.1 2420.8 2479.2 2489.3 1966.4 2427.7 2432.5 2438.0 2443.2 2144.7 2416.2 2426.3 2427.5 2439.8 2389.6 2452.4 2457.6 2462.3 2465.3 8564.4 8655.7 8660.0 8661.2 8662.8 5226.5 5327.3 5331.6 5334.9 5363.7 3365.9 3395.7 3487.3 3498.5 3504.6 1029.7 1047.7 1048.9 1071.3 1096.6 12022.4 12029.8 12032.8 12040.5 12053.6 4666.0 4694.5 4810.5 4821.6 4839.4 9451.9 10033.8 10036.7 10038.0 10073.1 4943.0 5386.8 5391.8 5407.5 5444.9 4249.5 4414.0 4895.8 4925.3 4942.8 3984.3 4018.5 4019.7 4037.5 4114.4 1330.7 1356.1 1357.3 1370.1 1374.7 887.7 895.7 931.5 937.7 957.7 5799.1 5877.7 5912.3 5942.4 6019.4 927.2 952.5 952.6 957.7 971.9 3735.7 3955.5 3976.3 4034.3 4040.1 10258.5 10730.4 10731.3 10758.8 10768.3 9524.6 10255.3 10411.9 10522.1 10583.9 8153.7 8163.1 8228.5 8247.6 8290.7 4729.3 5557.0 5808.5 5939.0 5989.9 9105.5 9358.9 9443.4 9444.7 9455.5 9103.5 9578.0 9583.0 9588.0 9812.8 6633.5 6803.1 7432.5 7534.7 7574.8 7990.0 8112.6 8176.3 8177.2 8197.0 6209.3 7181.0 7248.1 7342.9 7343.8 7142.0 7162.5 7227.7 7289.1 7304.3 6839.4 6871.6 6872.8 6977.5 6993.2 4784.0 4874.7 4879.6 4901.3 4907.9 4906.3 4919.2 4975.4 5011.9 5112.2 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5748.60 Median:6506.77 stddev:1854.16 (28.5%) stddev-sans-first:1814.03 07:20:41 INFO - PROCESS | 1676 | Values: 319.9 325.3 333.9 334.4 339.5 5640.9 6691.9 6710.2 6716.0 6755.8 6293.6 6494.5 6498.2 6515.4 6633.7 3945.0 4177.6 4799.4 4836.3 4859.1 4783.7 4858.2 4862.7 4869.1 4905.8 4801.0 4821.3 4823.7 4824.3 4848.2 4633.3 4732.2 4737.0 4785.0 4791.9 4076.8 4126.5 4203.3 4215.7 4239.6 6340.7 6359.7 6365.4 6369.4 6390.7 5809.9 5821.9 5828.3 5830.3 5836.4 5843.0 6993.9 7062.3 7063.5 7067.6 4372.2 4547.3 4549.6 4607.4 4612.0 7307.7 7440.5 7453.5 7468.4 7473.8 4654.0 4654.3 4655.8 4656.8 4659.5 3970.5 4678.0 4703.0 4721.4 4726.7 5584.6 5590.6 5590.9 5597.1 5608.1 6623.0 6831.2 7094.1 7101.7 7121.6 1429.2 1620.8 1635.8 1639.1 1639.8 1024.2 1026.5 1043.4 1044.9 1048.3 6433.2 6550.0 6551.6 6585.9 6750.1 7263.7 7406.6 7424.6 7437.7 7444.5 6625.9 6630.8 6632.4 6636.5 6637.6 3859.7 3912.3 3913.7 3916.3 3917.5 7451.4 7681.5 7715.7 7723.3 7749.8 7522.8 7547.2 7552.3 7568.5 7574.5 7672.2 7703.3 7773.2 7790.8 7802.1 7397.9 7406.9 7410.0 7410.6 7418.4 7004.8 7270.3 7270.6 7299.2 7318.9 6019.7 6763.0 7708.3 7727.6 7741.8 4604.8 4652.1 4654.2 4659.0 4659.6 5944.6 7333.7 7336.8 7341.7 7347.6 5992.9 6813.6 6842.0 7086.6 7097.4 7030.2 7053.2 7075.4 7076.6 7081.0 6934.4 6937.6 6939.3 6939.5 6948.1 5694.9 7014.6 7031.5 7055.6 7094.0 5953.3 6591.7 6639.3 6893.2 6921.2 07:20:41 INFO - PROCESS | 1676 | 07:20:41 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:17421.20 Median:616.29 stddev:24443.78 (3966.3%) stddev-sans-first:24480.02 07:20:41 INFO - PROCESS | 1676 | Values: 676.8 687.0 710.2 712.9 767.2 13048.5 13832.4 14602.6 14902.0 14992.7 13772.9 13872.5 13874.3 13885.9 13901.2 743.0 754.6 756.9 911.3 946.7 718.6 733.3 740.1 913.4 936.7 941.5 969.0 971.3 972.0 975.3 901.1 933.2 963.5 964.1 964.2 573.1 576.3 579.9 583.9 653.7 11441.7 14694.2 14866.7 14873.6 14901.2 12416.8 12487.6 12638.4 12867.9 13042.8 9899.5 9915.9 10175.7 10190.4 10591.5 3467.5 3471.8 3513.1 3555.9 3559.0 81449.0 81527.6 81981.0 82157.6 82336.0 9513.6 9600.2 9681.4 9693.2 9762.6 21016.6 21482.5 21534.4 21603.9 21614.4 7407.3 7565.2 7612.8 7618.8 7752.7 4063.5 4077.5 4125.8 4299.1 4353.5 3575.7 3580.4 3631.3 3635.0 3651.7 655.2 666.7 679.5 687.4 701.6 486.7 505.8 552.4 596.5 611.6 8095.3 8129.2 8184.7 8330.9 8331.8 471.3 474.3 602.8 613.6 618.9 3369.9 3765.5 3824.1 3902.5 3989.5 85939.1 88355.4 88714.6 88836.0 89326.0 68913.3 69542.3 71211.6 72085.6 72286.6 48504.5 66762.7 67059.3 67532.0 68401.7 7535.0 7671.0 7817.4 7888.2 8105.0 46649.8 47062.5 47439.5 47450.8 48157.3 60787.7 63992.4 64160.4 64570.4 66231.0 9800.2 9989.6 10065.7 10181.1 10210.9 10642.0 10839.5 10923.3 10930.6 11043.1 10101.2 10355.8 10494.8 10530.9 10631.8 10104.4 10352.4 10463.7 10495.0 10673.9 10252.1 10322.8 10395.9 10542.4 10792.0 8715.8 8841.0 8990.1 9028.5 9066.5 8348.2 8494.9 8499.8 8655.7 8812.3 07:20:41 INFO - PROCESS | 1676 | -------- Summary: end -------- 07:20:41 INFO - PROCESS | 1676 | 07:20:42 INFO - TEST-INFO | 1676: exit 0 07:20:42 INFO - TEST-OK | dromaeo_css | took 1115255ms 07:20:42 INFO - TEST-START | kraken 07:20:42 INFO - operating with platform_type : mac_ 07:20:42 INFO - Initialising browser for kraken test... 07:20:42 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpcBMb83/profile http://localhost:49278/getInfo.html) 07:20:44 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 07:20:44 INFO - PROCESS | 1681 | colorDepth:24 07:20:44 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 07:20:44 INFO - PROCESS | 1681 | __metrics 07:20:44 INFO - PROCESS | 1681 | 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" 07:20:44 ERROR - PROCESS | 1681 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:20:45 INFO - TEST-INFO | 1681: exit 0 07:20:45 INFO - Browser initialized. 07:20:45 INFO - Running cycle 1/1 for kraken test... 07:20:45 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpcBMb83/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:20:57 INFO - PROCESS | 1683 | RSS: Main: 211271680 07:20:57 INFO - PROCESS | 1683 | 07:21:00 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 07:21:01 INFO - PROCESS | 1683 | RSS: Main: 271990784 07:21:01 INFO - PROCESS | 1683 | 07:21:03 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 07:21:04 INFO - PROCESS | 1683 | RSS: Main: 286363648 07:21:04 INFO - PROCESS | 1683 | 07:21:07 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 07:21:08 INFO - PROCESS | 1683 | RSS: Main: 374284288 07:21:08 INFO - PROCESS | 1683 | 07:21:10 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 07:21:10 INFO - PROCESS | 1683 | RSS: Main: 278839296 07:21:10 INFO - PROCESS | 1683 | 07:21:12 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:21:13 INFO - PROCESS | 1683 | RSS: Main: 280649728 07:21:13 INFO - PROCESS | 1683 | 07:21:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 07:21:17 INFO - PROCESS | 1683 | RSS: Main: 318439424 07:21:17 INFO - PROCESS | 1683 | 07:21:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 07:21:20 INFO - PROCESS | 1683 | RSS: Main: 319266816 07:21:20 INFO - PROCESS | 1683 | 07:21:23 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 07:21:23 INFO - PROCESS | 1683 | RSS: Main: 318128128 07:21:23 INFO - PROCESS | 1683 | 07:21:25 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:21:26 INFO - PROCESS | 1683 | RSS: Main: 309612544 07:21:26 INFO - PROCESS | 1683 | 07:21:28 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:21:28 INFO - PROCESS | 1683 | RSS: Main: 316538880 07:21:28 INFO - PROCESS | 1683 | 07:21:30 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:21:31 INFO - PROCESS | 1683 | RSS: Main: 319414272 07:21:31 INFO - PROCESS | 1683 | 07:21:34 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:21:34 INFO - PROCESS | 1683 | RSS: Main: 324284416 07:21:34 INFO - PROCESS | 1683 | 07:21:37 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:21:38 INFO - PROCESS | 1683 | RSS: Main: 322314240 07:21:38 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 07:21:40 INFO - PROCESS | 1683 | RSS: Main: 386633728 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | __start_tp_report 07:21:40 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 07:21:40 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 07:21:40 INFO - PROCESS | 1683 | |i|pagename|runs| 07:21:40 INFO - PROCESS | 1683 | |0;ai-astar;114;114;120;113;113;111;116;113;114;135 07:21:40 INFO - PROCESS | 1683 | |1;audio-beat-detection;154;121;167;175;138;171;147;187;157;121 07:21:40 INFO - PROCESS | 1683 | |2;audio-dft;184;188;187;190;185;183;182;185;186;185 07:21:40 INFO - PROCESS | 1683 | |3;audio-fft;72;79;72;151;57;55;59;66;134;56 07:21:40 INFO - PROCESS | 1683 | |4;audio-oscillator;92;91;138;77;81;85;124;80;77;122 07:21:40 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;91;84;87;85;87;129;128;84;87;84 07:21:40 INFO - PROCESS | 1683 | |6;imaging-darkroom;92;93;93;94;92;93;94;93;92;93 07:21:40 INFO - PROCESS | 1683 | |7;imaging-desaturate;87;87;84;84;85;84;84;84;87;84 07:21:40 INFO - PROCESS | 1683 | |8;json-parse-financial;61;61;63;63;64;63;65;80;60;58 07:21:40 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;63;65;66;80;71;70;67;69;67;69 07:21:40 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;92;98;80;81;80;80;82;80;82;85 07:21:40 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;129;128;128;124;127;128;120;146;163;170 07:21:40 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;166;166;164;222;203;169;165;167;166;164 07:21:40 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;67;67;67;70;68;67;68;70;67;66 07:21:40 INFO - PROCESS | 1683 | __end_tp_report 07:21:40 INFO - PROCESS | 1683 | __start_cc_report 07:21:40 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1975 07:21:40 INFO - PROCESS | 1683 | __end_cc_report 07:21:40 INFO - PROCESS | 1683 | __startTimestamp1464704500356__endTimestamp 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | ------- Summary: start ------- 07:21:40 INFO - PROCESS | 1683 | Number of tests: 14 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:116.30 Median:114.00 stddev:6.99 (6.1%) stddev-sans-first:7.37 07:21:40 INFO - PROCESS | 1683 | Values: 114.0 114.0 120.0 113.0 113.0 111.0 116.0 113.0 114.0 135.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:153.80 Median:162.00 stddev:22.31 (13.8%) stddev-sans-first:23.66 07:21:40 INFO - PROCESS | 1683 | Values: 154.0 121.0 167.0 175.0 138.0 171.0 147.0 187.0 157.0 121.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:185.50 Median:185.50 stddev:2.37 (1.3%) stddev-sans-first:2.45 07:21:40 INFO - PROCESS | 1683 | Values: 184.0 188.0 187.0 190.0 185.0 183.0 182.0 185.0 186.0 185.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:80.10 Median:62.50 stddev:34.08 (54.5%) stddev-sans-first:36.02 07:21:40 INFO - PROCESS | 1683 | Values: 72.0 79.0 72.0 151.0 57.0 55.0 59.0 66.0 134.0 56.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:80.50 stddev:22.57 (28.0%) stddev-sans-first:23.87 07:21:40 INFO - PROCESS | 1683 | Values: 92.0 91.0 138.0 77.0 81.0 85.0 124.0 80.0 77.0 122.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:86.00 stddev:18.00 (20.9%) stddev-sans-first:19.04 07:21:40 INFO - PROCESS | 1683 | Values: 91.0 84.0 87.0 85.0 87.0 129.0 128.0 84.0 87.0 84.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 07:21:40 INFO - PROCESS | 1683 | Values: 92.0 93.0 93.0 94.0 92.0 93.0 94.0 93.0 92.0 93.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:84.50 stddev:1.41 (1.7%) stddev-sans-first:1.30 07:21:40 INFO - PROCESS | 1683 | Values: 87.0 87.0 84.0 84.0 85.0 84.0 84.0 84.0 87.0 84.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:63.00 stddev:6.05 (9.6%) stddev-sans-first:6.33 07:21:40 INFO - PROCESS | 1683 | Values: 61.0 61.0 63.0 63.0 64.0 63.0 65.0 80.0 60.0 58.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:4.64 (6.7%) stddev-sans-first:4.44 07:21:40 INFO - PROCESS | 1683 | Values: 63.0 65.0 66.0 80.0 71.0 70.0 67.0 69.0 67.0 69.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:82.00 stddev:6.16 (7.5%) stddev-sans-first:5.82 07:21:40 INFO - PROCESS | 1683 | Values: 92.0 98.0 80.0 81.0 80.0 80.0 82.0 80.0 82.0 85.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:136.30 Median:128.50 stddev:17.34 (13.5%) stddev-sans-first:18.19 07:21:40 INFO - PROCESS | 1683 | Values: 129.0 128.0 128.0 124.0 127.0 128.0 120.0 146.0 163.0 170.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:166.50 stddev:20.21 (12.1%) stddev-sans-first:21.16 07:21:40 INFO - PROCESS | 1683 | Values: 166.0 166.0 164.0 222.0 203.0 169.0 165.0 167.0 166.0 164.0 07:21:40 INFO - PROCESS | 1683 | 07:21:40 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.50 stddev:1.34 (2.0%) stddev-sans-first:1.39 07:21:40 INFO - PROCESS | 1683 | Values: 67.0 67.0 67.0 70.0 68.0 67.0 68.0 70.0 67.0 66.0 07:21:40 INFO - PROCESS | 1683 | -------- Summary: end -------- 07:21:40 INFO - PROCESS | 1683 | 07:21:41 INFO - TEST-INFO | 1683: exit 0 07:21:41 INFO - TEST-OK | kraken | took 58672ms 07:21:41 INFO - SUITE-END | took 1173s 07:21:41 INFO - Completed test suite (00:19:34) 07:21:41 INFO - javascript benchmark 07:21:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.4805205193197, 3410.7782994105364, 3420.1962538803377, 3450.7411944104197, 3489.441689329708, 5583.35791383991, 5608.663480191191, 5615.5507559395255, 5617.354004289508, 5641.069223578104, 5589.049861523541, 5618.747156377963, 5641.266635337411, 5641.68970706611, 5644.943550564503, 4613.1926912790295, 5071.644984851062, 5129.82045628404, 5146.356705411824, 5226.477352264762, 4872.512748725135, 5199.6360254782185, 5201.453847346012, 5202.401723801754, 5204.115300398922, 5143.974280128594, 5181.378234611851, 5182.896342073156, 5200.401953775327, 5205.765740541666, 5174.24973378858, 5209.843704688866, 5215.295935048758, 5233.633645644806, 5278.97360513195, 5015.799368025275, 5074.340335756328, 5102.897942041175, 5133.255677926717, 5143.408508021588, 5452.073147564884, 5474.124140137559, 5478.096114141202, 5519.116941289374, 5526.50261476465, 6091.238595175603, 6134.355892631254, 6168.75324987, 6168.84577885552, 6174.783882564088, 6012.068129439944, 6104.850025216341, 6112.235970503687, 6221.440070393686, 6246.87506249877, 5663.122216056518, 5712.200291959129, 5723.227364305849, 5738.0, 5748.051571490699, 6268.310485846553, 6302.464290535264, 6304.306526282106, 6304.905426418607, 6308.243010838637, 5838.503727183237, 6079.148919151322, 6087.391260873966, 6093.329733729286, 6106.511479081662, 6080.452759251689, 6118.619179173164, 6212.9378706213265, 6244.344343843922, 6258.874822503524, 6061.424164704346, 6099.268087829489, 6126.387361263838, 6141.692915354214, 6143.938560614426, 5992.250968628921, 6141.631502109888, 6179.227596550431, 6184.350643182401, 6202.038684003986, 4823.252395878644, 4849.903001940012, 4893.4617192108835, 4919.729414882216, 4951.430585482643, 4363.214621368184, 4363.454568178978, 4372.672049596293, 4402.097569998206, 4418.0, 5498.670079795145, 5551.639143455663, 5629.605927585029, 5632.690202038845, 5648.5763567731765, 6150.538709596798, 6222.595531290452, 6240.875182496325, 6264.968675156595, 6272.529560282997, 5607.130894711327, 5610.0, 5612.35457922336, 5616.213730077792, 5627.071533196977, 5232.2674825524455, 5306.8407947761625, 5315.654482458628, 5318.787248510093, 5328.520433160957, 6229.5327850410495, 6281.842953926097, 6284.591501552384, 6298.370162983665, 6305.779297724557, 6107.992181290215, 6125.509959203164, 6152.692365381802, 6155.569110162246, 6157.107219453064, 6199.5040396767245, 6236.407541283571, 6294.653794041372, 6305.306416294296, 6307.684615769285, 6090.634561926298, 6099.603525770988, 6115.205023346936, 6118.296395914527, 6122.398155112157, 5144.485551444825, 6181.412765787243, 6199.225096862892, 6201.3178550358525, 6209.937900620936, 5700.25896633435, 5775.104858746901, 5919.852003699942, 5997.0404735242155, 6006.549508786771, 5290.365156181129, 5306.681599104066, 6049.18336024631, 6052.727627256696, 6067.696615169312, 5578.665280083208, 5876.764929402776, 5883.2057672213705, 5908.143319218603, 5915.0, 5850.94149058504, 5878.882422351444, 5962.135490353961, 5977.910331344946, 6022.217111775616, 5773.826785196451, 5835.416458354131, 5836.416358364299, 5840.883182336414, 5851.912221316598], "value": 5616.946594139274, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.3230305239453, 531.8379341139611, 534.0680512505676, 545.0951420641735, 582.7086456771614, 22644.773552264473, 37750.24499510008, 38404.03989900254, 38445.61554384456, 38500.80749596248, 41702.0, 42274.73175804731, 42277.7886110569, 42341.36487952683, 42497.15005699884, 8398.328133749286, 8506.36202284831, 9399.999999999984, 9654.13112819846, 9707.223422126248, 11398.658040258806, 11641.0, 11648.650540483757, 11650.766984660302, 11686.532538698442, 11376.943115284454, 11461.484233209525, 11464.942675286611, 11476.0, 11476.655700329004, 8572.957135214347, 11371.0, 11458.083353331713, 11669.124815638776, 11691.240069395548, 11249.043831274352, 11363.0, 11375.601853935073, 11378.31730096197, 11416.60041898538, 38099.857004289915, 38401.23197536061, 38497.23005539901, 38534.42198367027, 38560.035999099964, 3182.2203560127687, 3201.6158061032593, 3240.238543942172, 3269.869205231788, 3280.130765347185, 28199.85900070507, 28266.858665706746, 28777.85611071952, 28812.423751524853, 29043.12870613885, 2736.65791776028, 2741.0954385053083, 2773.55623100303, 2804.9719502804915, 2813.8030337876357, 430142.0, 437197.0, 469767.0, 470450.0, 474185.0, 35655.0, 61325.693371532856, 62501.0, 62616.0, 62641.0, 35784.92645220648, 35793.28413431717, 35839.104022399646, 35898.0, 36066.819665902025, 15343.309551070228, 15642.374305027672, 15657.843421565867, 15658.295376708076, 15721.449749258722, 12375.0, 12564.497420103093, 13254.204747714975, 13506.729865402833, 13526.25605591702, 8145.79635509117, 8188.50868947865, 8198.0, 8254.752357429286, 8267.62795674184, 3406.6082400524256, 3464.272502774395, 3472.6700963408434, 3481.3037392521596, 3513.332466831294, 978.9412635241908, 1002.5538635307364, 1032.4837581209395, 1057.437158660378, 1122.7193201699574, 29426.705732942824, 29526.704732952825, 29915.0, 30054.398912021636, 30257.848710756305, 1097.1990446973755, 1099.5656715597295, 1166.632510759108, 1196.2941864300105, 1205.6744678936814, 14515.201663908376, 14545.34545945435, 14983.550493485212, 15371.077735335915, 15504.844951550565, 618350.0, 982768.0, 997216.0, 1001376.9931150298, 1001562.0, 61277.0, 61439.0, 61992.69003655043, 62037.75924481485, 62328.376716232255, 1451.4847229233892, 1462.4661998370518, 1466.765317549187, 1494.5292232946588, 1496.132243298884, 28782.424351512433, 30520.694793051785, 30751.538726918665, 30875.536866947463, 31075.689243108183, 53194.2020869695, 53354.19968700395, 53720.462795371546, 53804.461955381514, 53835.19247211217, 494500.0, 496516.0, 497740.0, 497898.0, 498798.0, 23389.41526461852, 23716.05135794618, 24706.50586988214, 27951.580726288714, 28033.299167520974, 42095.57904420917, 42867.785661071495, 43412.78293608512, 43467.782661086494, 43597.34603980879, 32725.004208462626, 36209.63790362063, 37352.626473736, 37447.81276093602, 37964.86105416842, 30952.84523577368, 36240.45639315465, 36250.456243155844, 36997.0, 37024.62975370319, 36655.0, 36898.44652330164, 38056.23887522179, 38637.42043869288, 39294.41058384184, 30087.849560752933, 31334.843325783226, 31844.840775795972, 31850.681493184773, 31861.362772743953, 24299.39251518726, 25167.37081572975, 25441.49117017686, 25479.36301592475, 25972.870135649955], "value": 18960.412582286837, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.08652987513153, 405.95128584569846, 406.80270069016524, 410.9876703698888, 416.04310086800353, 13097.345132743372, 13413.463461461553, 19852.70220946685, 20488.89755551222, 20494.28270010548, 19989.700154497696, 20378.388648340537, 20430.182792688312, 20451.875146866918, 21295.36113916585, 5236.607254455912, 5421.674699518022, 5443.428440013801, 5447.863803404926, 5449.700266485342, 3879.243547508237, 4067.5932406759384, 5661.575381846357, 5938.079597662348, 5948.524118070544, 5702.201691763156, 5931.911021334683, 5940.673262970535, 5946.732397042144, 5971.10433434986, 5657.547396208293, 5917.911231331534, 5927.1998280232265, 5937.287525496925, 5949.077892926603, 5717.656940583537, 5759.280089988752, 5759.827205183851, 5760.8271751847515, 5767.0, 13088.803667944989, 13293.0, 13758.65603359914, 13805.309734513234, 13855.792163117561, 2168.8048075673264, 2197.0113448948036, 2272.5909336319455, 2293.323469576479, 2726.0, 9357.859632105456, 9555.761105972406, 9904.513845740912, 10012.04885535873, 10164.542595583216, 1722.3799432204396, 1771.3003363671278, 1793.1841012339485, 1815.3374018483291, 1818.8908665480114, 84741.0, 109906.0, 111728.4413577935, 114405.42797286043, 117515.0, 11419.0, 11669.708257293465, 11686.532538698528, 11790.351530665896, 11829.526818927146, 15079.623009424633, 15082.17048062367, 15144.318505667272, 15150.54548363551, 15161.469348572968, 8841.69054083104, 8946.821063578693, 9057.275417966546, 9068.501232432147, 9072.455652660861, 8978.551072446351, 8991.325650576233, 8993.100689930954, 9081.636734530544, 9092.181703646704, 4023.5775243599587, 4631.166390049823, 4635.096156249567, 4731.4558825735485, 4755.286706993909, 1749.2046911352688, 1941.2332128809041, 1987.9900600496906, 1998.6902030185345, 2021.2723419569238, 881.7442941547008, 1020.6733845169623, 1043.3322673488974, 1064.122099268107, 1098.9340639561651, 14729.747971422335, 20856.359796917775, 21233.68149477759, 21242.681359779617, 21421.89289053576, 870.6430363550913, 955.1546881010356, 984.7833476635273, 989.8812142542797, 1003.7189586915675, 9451.952740236393, 11048.723781905355, 11414.486348114355, 11418.257813242113, 11428.542858285742, 487181.0, 580302.0, 589888.0, 599313.0, 600697.0, 115118.0, 118279.0, 118580.0, 119192.62999836444, 120855.0, 1026.440589878518, 1026.97432564185, 1076.3703233608426, 1094.9452527373758, 1096.5230124895695, 18208.99850508235, 18438.170282337596, 18774.436766897015, 18862.622747544698, 19122.426327210207, 52442.475575245284, 52604.94790104253, 52649.736751316, 53159.0, 54623.726881365335, 73288.63355683188, 75383.85388803668, 76132.0, 76234.23765762271, 76496.0, 6664.366885145903, 6747.561408508432, 7832.647530861011, 8076.394270429624, 8442.577871106298, 10333.0, 12562.057845661795, 12728.0, 12839.614811555668, 12867.034972377296, 9455.716328510156, 10417.281148610395, 10991.120710342993, 11054.834177487182, 11111.44442777874, 8811.735647930573, 10876.021158095493, 10922.781544369227, 11185.552577897119, 11305.378204198849, 11359.772804543698, 11387.715307117389, 11431.542738290374, 11622.941885290858, 11689.532418703157, 8094.87857682147, 8339.54132522717, 8345.499270043816, 8381.16188381157, 8553.87169192475, 10037.799244015225, 11121.999020088231, 11370.886291136983, 11436.256643318491, 11482.827757583476], "value": 9316.590448727271, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.8569143085685, 1562.9374825006996, 1581.0513691784931, 1810.1039985207317, 1964.4597735622701, 7836.824476328547, 8559.78600534986, 8697.478151310917, 8721.258693011087, 8733.606987685553, 8425.410221284512, 8707.73876783696, 8710.869336959935, 8715.389922705413, 8715.651373945035, 2153.967690484644, 2217.5010622609943, 2221.611218036845, 2434.8782560871928, 2467.1858286765414, 2064.3187748043183, 2235.094786611417, 2420.7942324902406, 2479.1818699829014, 2489.2781093482868, 1966.4100769769084, 2427.7086749589985, 2432.4526981429212, 2437.9512409751796, 2443.242594795619, 2144.7426308842882, 2416.1905761569806, 2426.2842461473906, 2427.526632306701, 2439.7926176275128, 2389.617661174204, 2452.350127216289, 2457.606782914725, 2462.2859370782453, 2465.285067330472, 8564.443311184816, 8655.6970506032, 8660.0, 8661.220490155853, 8662.783430414227, 5226.503482169188, 5327.334083239595, 5331.573474122061, 5334.866628334284, 5363.678179309254, 3365.9495107573407, 3395.6604339566093, 3487.319972605343, 3498.5276987606603, 3504.6320136385566, 1029.7065336379096, 1047.7485403503103, 1048.8741351037925, 1071.3250652089134, 1096.6107032003683, 12022.39888005605, 12029.759404811855, 12032.759344813143, 12040.518379264819, 12053.57812476559, 4665.9766701166955, 4694.460137084211, 4810.542998415145, 4821.614270858323, 4839.370881785346, 9451.905480945241, 10033.849492257623, 10036.749081273027, 10038.0, 10073.1437827784, 4943.0, 5386.784528618811, 5391.78432862689, 5407.486288802557, 5444.945550544523, 4249.46881639795, 4413.977930110394, 4895.779689913962, 4925.261210818406, 4942.752862356868, 3984.30274701926, 4018.4976877890263, 4019.738716983387, 4037.515498140242, 4114.444549985774, 1330.6872884872048, 1356.0643156222372, 1357.314556149158, 1370.0751992405087, 1374.656335916021, 887.658251573149, 895.7447127568742, 931.4830269200659, 937.7059657672369, 957.6600306890954, 5799.144273832333, 5877.735501902424, 5912.320083190402, 5942.405759424109, 6019.367966363557, 927.2489283680105, 952.4713783849892, 952.5999080386255, 957.712686194139, 971.9076687714655, 3735.6637902588895, 3955.5253369595257, 3976.284268831638, 4034.334334834778, 4040.1111755413763, 10258.487075646188, 10730.356178629307, 10731.302465339884, 10758.838617420744, 10768.300060496043, 9524.571394287274, 10255.28213025096, 10411.895881041244, 10522.1056210223, 10583.941605839354, 8153.714619988272, 8163.0612479564425, 8228.506289622523, 8247.628856701464, 8290.709825156091, 4729.266963620506, 5556.972215138899, 5808.5062769665055, 5939.0, 5989.880202395841, 9105.453672779655, 9358.92372377158, 9443.386179898285, 9444.716658500256, 9455.479948602893, 9103.49930753815, 9578.0, 9582.952085239529, 9587.952060239653, 9812.803743924938, 6633.502487313374, 6803.149606299213, 7432.516886402366, 7534.660940257592, 7574.810629734301, 7989.960050199712, 8112.553809540532, 8176.264136227768, 8177.182281771775, 8196.959015205124, 6209.2548894133, 7180.964095179491, 7248.130224373109, 7342.926570734225, 7343.816404589928, 7141.9642901785155, 7162.534435261691, 7227.747028853965, 7289.088863892013, 7304.342609164988, 6839.384455399038, 6871.553349032297, 6872.793816185523, 6977.476689248225, 6993.160820701345, 4783.976080119577, 4874.731889746098, 4879.584920373052, 4901.2893130496595, 4907.877303067452, 4906.2640603909695, 4919.1883339248625, 4975.402951645826, 5011.899762004812, 5112.233165025127], "value": 4563.043604339953, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.88963807486425, 325.31845783084435, 333.9432296509593, 334.3897387268235, 339.47381558584186, 5640.915386269204, 6691.895837186865, 6710.228323742759, 6715.992601109836, 6755.763548275811, 6293.559450838443, 6494.51291153163, 6498.187726534184, 6515.3612786821295, 6633.668316584163, 3944.960550394488, 4177.5613560576085, 4799.376081109468, 4836.347093142434, 4859.125357435661, 4783.71297722136, 4858.1983972644475, 4862.683925544846, 4869.099216644914, 4905.75471226439, 4800.975995120019, 4821.30091136785, 4823.734694591796, 4824.348712923746, 4848.17581011229, 4633.2818413146015, 4732.2428411454, 4737.028909073631, 4785.019071090418, 4791.856244312677, 4076.775777332275, 4126.4938297521485, 4203.26442872496, 4215.747055176699, 4239.59723826236, 6340.682965851689, 6359.713812878432, 6365.427111559982, 6369.394907483782, 6390.744370225186, 5809.8547536311935, 5821.912671309934, 5828.300603927513, 5830.271216097988, 5836.445537673914, 5842.977478941169, 6993.89509157363, 7062.258462861429, 7063.540869843495, 7067.57594544329, 4372.19114463822, 4547.272436410159, 4549.61328287096, 4607.424071991001, 4612.0, 7307.707691692326, 7440.516366436218, 7453.515521491086, 7468.4025277977635, 7473.775786726407, 4654.045920586272, 4654.25531914892, 4655.790489427984, 4656.790444429941, 4659.464161621457, 3970.543387510473, 4677.9942312403, 4702.9764851176205, 4721.409823772028, 4726.740029298421, 5584.6090773646265, 5590.630295577609, 5590.888182236333, 5597.132444471114, 5608.074667679869, 6623.039659249382, 6831.2144103427745, 7094.11323584552, 7101.680424380916, 7121.643917804089, 1429.2139323372103, 1620.7568864670159, 1635.7731701224081, 1639.0739232333694, 1639.8278180791083, 1024.1857723110145, 1026.4662375564665, 1043.4052590023614, 1044.9425281609433, 1048.2662136504478, 6433.228012638513, 6550.0, 6551.6069035857045, 6585.86828263432, 6750.054992301082, 7263.745768898063, 7406.629668516553, 7424.591647459333, 7437.702491900372, 7444.516106453063, 6625.86748265032, 6630.7679231226675, 6632.43624291931, 6636.5354425190735, 6637.601743895285, 3859.6526312632, 3912.295786758411, 3913.745606535566, 3916.2559113768293, 3917.5103112110987, 7451.441141914378, 7681.500702454323, 7715.652795624211, 7723.266289702469, 7749.7675069748, 7522.774316770506, 7547.16981132075, 7552.320291173821, 7568.508046976929, 7574.545527268381, 7672.232776722283, 7703.306702396699, 7773.222677732295, 7790.766277011699, 7802.141764405911, 7397.852042959003, 7406.851862962602, 7409.962950185214, 7410.62946852666, 7418.443616728875, 7004.824879378057, 7270.272972702687, 7270.636468176675, 7299.233580473974, 7318.853622927618, 6019.6990150493175, 6763.0, 7708.267714567107, 7727.609030374587, 7741.845163096819, 4604.815807367668, 4652.069586082865, 4654.20878450671, 4658.976705116453, 4659.627229821674, 5944.562729280974, 7333.74331898381, 7336.816579585553, 7341.70633174667, 7347.632618369167, 5992.880142397041, 6813.625250611264, 6841.965790171216, 7086.610236437045, 7097.432205423657, 7030.156381234285, 7053.1536215652395, 7075.433965282868, 7076.5846830633045, 7081.044059051962, 6934.375906168469, 6937.583744975318, 6939.306069393222, 6939.4795390346935, 6948.062011628567, 5694.943050569441, 7014.579125252501, 7031.507794454339, 7055.576665400093, 7094.0, 5953.285605727167, 6591.703373348115, 6639.269680335063, 6893.20728116273, 6921.2386637468835], "value": 5127.515030197922, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.8138761840494, 686.9622170780605, 710.1513691139091, 712.8823744082226, 767.2174382130225, 13048.543300984467, 13832.446702131916, 14602.634934126652, 14902.031367961075, 14992.700145997087, 13772.931135344334, 13872.514461993807, 13874.306284685776, 13885.861141388583, 13901.235432051233, 742.9777106686781, 754.6038329876803, 756.9016027916391, 911.307406371156, 946.6733976778, 718.5580867766338, 733.2923728601899, 740.0933856026378, 913.4382354851751, 936.7049379445486, 941.5480569326727, 969.0212884986145, 971.3297824501071, 971.9854202186938, 975.2539307429822, 901.1169054326797, 933.1927882381725, 963.5182408795603, 964.0889359555223, 964.2286171063156, 573.1088157914442, 576.3113079869557, 579.8637320229743, 583.8890610783938, 653.7385045981598, 11441.656750297505, 14694.191819449927, 14866.702665946728, 14873.553793386216, 14901.180435076067, 12416.75166496665, 12487.562935297312, 12638.431270592846, 12867.87132128676, 13042.804357934638, 9899.505024748734, 9915.90084099157, 10175.694729158064, 10190.388576685422, 10591.523381447852, 3467.5064300603517, 3471.8090505022215, 3513.0690367052753, 3555.9466608000903, 3559.0212691509782, 81449.0, 81527.5923620384, 81980.95047623858, 82157.58921205356, 82336.0, 9513.619455221851, 9600.231981441468, 9681.419114853132, 9693.176080033136, 9762.560684769202, 21016.579668406546, 21482.462938426663, 21534.353969380943, 21603.89198054031, 21614.35156945294, 7407.259274072658, 7565.205653406423, 7612.7716168515035, 7618.771436856903, 7752.728654497066, 4063.532693740258, 4077.4903137107863, 4125.834966601363, 4299.118680670455, 4353.477582690098, 3575.73182011345, 3580.4092324766643, 3631.3100510902846, 3635.0003748969243, 3651.744377893575, 655.1875674164003, 666.7133132752866, 679.49038221334, 687.4088284075691, 701.5687996487109, 486.72986492497006, 505.7774579185147, 552.4447929830575, 596.4691424632081, 611.6055144431816, 8095.2714255717265, 8129.227723366271, 8184.713535026245, 8330.875036874451, 8331.791705207419, 471.33541706194103, 474.2885671492761, 602.8442388844445, 613.6379536073649, 618.935011823761, 3369.921625079951, 3765.45400916871, 3824.0822202671716, 3902.453656488094, 3989.5412027616617, 85939.14060859436, 88355.37334699923, 88714.55642721875, 88836.0, 89326.0, 68913.3108668907, 69542.30457695358, 71211.64394177996, 72085.6395718018, 72286.63856680683, 48504.51495485, 66762.66474670479, 67059.3294067053, 67532.0, 68401.65799170973, 7534.962325188339, 7670.979667282739, 7817.37461003107, 7888.211178882066, 8104.98687664042, 46649.766751167386, 47062.529374707185, 47439.52560474489, 47450.813729657035, 48157.27764083471, 60787.69606152118, 63992.3600764005, 64160.35839641731, 64570.35429645831, 66231.00653490105, 9800.215982721507, 9989.550470229002, 10065.69802905914, 10181.083702466814, 10210.948945255226, 10642.04221620058, 10839.512221950188, 10923.344599324066, 10930.56277748881, 11043.116550675766, 10101.191904647463, 10355.792884142124, 10494.790104198026, 10530.894691053298, 10631.787364252827, 10104.393736375841, 10352.378857268588, 10463.68608941733, 10495.0, 10673.89326106729, 10252.077313041862, 10322.793544128925, 10395.94802025985, 10542.420166890895, 10792.0, 8715.782105447415, 8841.0, 8990.145936136056, 9028.458292502472, 9066.546672666473, 8348.165183481604, 8494.91505084966, 8499.830003399773, 8655.6970506032, 8812.339074569305], "value": 6625.405269194643, "unit": "score"}], "name": "dromaeo_css", "value": 7319.856383402084, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 120.0, 113.0, 113.0, 111.0, 116.0, 113.0, 114.0, 135.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 167.0, 175.0, 138.0, 171.0, 147.0, 187.0, 157.0, 121.0], "value": 153.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 187.0, 190.0, 185.0, 183.0, 182.0, 185.0, 186.0, 185.0], "value": 185.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 72.0, 151.0, 57.0, 55.0, 59.0, 66.0, 134.0, 56.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 138.0, 77.0, 81.0, 85.0, 124.0, 80.0, 77.0, 122.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 87.0, 85.0, 87.0, 129.0, 128.0, 84.0, 87.0, 84.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 92.0, 93.0, 94.0, 93.0, 92.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 84.0, 85.0, 84.0, 84.0, 84.0, 87.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 63.0, 63.0, 64.0, 63.0, 65.0, 80.0, 60.0, 58.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 66.0, 80.0, 71.0, 70.0, 67.0, 69.0, 67.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 80.0, 81.0, 80.0, 80.0, 82.0, 80.0, 82.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 128.0, 128.0, 124.0, 127.0, 128.0, 120.0, 146.0, 163.0, 170.0], "value": 136.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 164.0, 222.0, 203.0, 169.0, 165.0, 167.0, 166.0, 164.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 70.0, 68.0, 67.0, 68.0, 70.0, 67.0, 66.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1500.6000000000001, "alertThreshold": 2.0}]} 07:21:41 INFO - Return code: 0 07:21:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:21:41 ERROR - # TBPL SUCCESS # 07:21:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:21:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:21:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:21:41 INFO - 2016-05-31 07:21:41.660 screenresolution[1687:10303] starting screenresolution argv=screenresolution get 07:21:41 INFO - 2016-05-31 07:21:41.662 screenresolution[1687:10303] Display 0: 1600x1200x32@60 07:21:41 INFO - 2016-05-31 07:21:41.668 screenresolution[1688:10306] starting screenresolution argv=screenresolution list 07:21:41 INFO - Available Modes on Display 0 07:21:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:21:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:21:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:21:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:21:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:21:41 INFO - Intel Iris: 07:21:41 INFO - Chipset Model: Intel Iris 07:21:41 INFO - Type: GPU 07:21:41 INFO - Bus: Built-In 07:21:41 INFO - VRAM (Dynamic, Max): 1536 MB 07:21:41 INFO - Vendor: Intel (0x8086) 07:21:41 INFO - Device ID: 0x0a2e 07:21:41 INFO - Revision ID: 0x0009 07:21:41 INFO - Displays: 07:21:41 INFO - Display: 07:21:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:21:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:21:41 INFO - Display Serial Number: 1600x1200 60 07:21:41 INFO - Main Display: Yes 07:21:41 INFO - Mirror: Off 07:21:41 INFO - Online: Yes 07:21:41 INFO - Rotation: Supported 07:21:41 INFO - Return code: 0 07:21:41 INFO - Running post-action listener: _resource_record_post_action 07:21:41 INFO - Running post-run listener: _resource_record_post_run 07:21:42 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 32378368; Write bytes: 655258624; Read time: 325; Write time: 2215 07:21:42 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 347081216; Write bytes: 346297856; Read time: 26527; Write time: 690 07:21:42 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 31456768; Write bytes: 299003392; Read time: 311; Write time: 1493 07:21:42 INFO - Running post-run listener: _upload_blobber_files 07:21:42 INFO - Blob upload gear active. 07:21:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:21:42 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 07:21:42 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'] 07:21:42 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 07:21:43 INFO - (blobuploader) - INFO - Open directory for files ... 07:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:21:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:21:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:21:44 INFO - (blobuploader) - INFO - Done attempting. 07:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:21:46 INFO - (blobuploader) - INFO - Done attempting. 07:21:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:21:46 INFO - Return code: 0 07:21:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:21:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:21:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72c25ad455e6667f0ff73060b7328454c1938929d541a4682132df2656eb241ac8a6e89f181a6cb9543d967751ad572115fd07a7d65b2e7736ea0015d4e85520", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4911500d13c564f8219a087243757c319828cff0edf724a2dc6e92fd6a3e1fd6e5d968bb3a5670a5e7fcad21c16a649223c6325df5791a4e46332e4f897a6414"} 07:21:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:21:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:21:46 INFO - Contents: 07:21:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72c25ad455e6667f0ff73060b7328454c1938929d541a4682132df2656eb241ac8a6e89f181a6cb9543d967751ad572115fd07a7d65b2e7736ea0015d4e85520", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4911500d13c564f8219a087243757c319828cff0edf724a2dc6e92fd6a3e1fd6e5d968bb3a5670a5e7fcad21c16a649223c6325df5791a4e46332e4f897a6414"} 07:21:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:21:46 INFO - Copying logs to upload dir... 07:21:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:21:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.737054 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-31 07:21:46.200306) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 07:21:46.204136) ========= 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.exDpBR4LC7/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.HX9U0pbWjh/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/72c25ad455e6667f0ff73060b7328454c1938929d541a4682132df2656eb241ac8a6e89f181a6cb9543d967751ad572115fd07a7d65b2e7736ea0015d4e85520", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4911500d13c564f8219a087243757c319828cff0edf724a2dc6e92fd6a3e1fd6e5d968bb3a5670a5e7fcad21c16a649223c6325df5791a4e46332e4f897a6414"} build_url:https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/eiA6SFAFTTiT9qrm_tAIfA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72c25ad455e6667f0ff73060b7328454c1938929d541a4682132df2656eb241ac8a6e89f181a6cb9543d967751ad572115fd07a7d65b2e7736ea0015d4e85520", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4911500d13c564f8219a087243757c319828cff0edf724a2dc6e92fd6a3e1fd6e5d968bb3a5670a5e7fcad21c16a649223c6325df5791a4e46332e4f897a6414"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 07:21:46.233842) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 07:21:46.234144) ========= 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.exDpBR4LC7/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.HX9U0pbWjh/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005354 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 07:21:46.282326) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 07:21:46.282615) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 07:21:46.282930) ========= ========= Total master_lag: 4.15 =========