builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0190 starttime: 1461151762.27 results: success (0) buildid: 20160420024137 builduid: 90e0ea923ce741c390fe2bff0056a598 revision: 88c060c38873d2373efd49a2e58acc46c22ba532 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.266067) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.266617) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.266925) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.457875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.458179) ========= 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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-20 04:29:22-- 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% 116M=0s 2016-04-20 04:29:22 (116 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129552 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.605517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.605986) ========= 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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.028537 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:22.654660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 04:29:22.655054) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 88c060c38873d2373efd49a2e58acc46c22ba532 --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 88c060c38873d2373efd49a2e58acc46c22ba532 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-20 04:29:22,728 truncating revision to first 12 chars 2016-04-20 04:29:22,728 Setting DEBUG logging. 2016-04-20 04:29:22,728 attempt 1/10 2016-04-20 04:29:22,729 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/88c060c38873?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 04:29:23,971 unpacking tar archive at: mozilla-aurora-88c060c38873/testing/mozharness/ program finished with exit code 0 elapsedTime=1.491468 ========= master_lag: 1.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-20 04:29:25.273892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:25.274172) ========= script_repo_revision: 88c060c38873d2373efd49a2e58acc46c22ba532 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:25.274580) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:25.274853) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 04:29:25.290009) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-20 04:29:25.290435) ========= /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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:29:25 INFO - MultiFileLogger online at 20160420 04:29:25 in /builds/slave/test 04:29:25 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 04:29:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:25 INFO - {'append_to_log': False, 04:29:25 INFO - 'base_work_dir': '/builds/slave/test', 04:29:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:29:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:25 INFO - 'branch': 'Mozilla-Aurora', 04:29:25 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:25 INFO - 'config_files': ('talos/mac_config.py',), 04:29:25 INFO - 'default_actions': ('clobber', 04:29:25 INFO - 'read-buildbot-config', 04:29:25 INFO - 'download-and-extract', 04:29:25 INFO - 'populate-webroot', 04:29:25 INFO - 'create-virtualenv', 04:29:25 INFO - 'install', 04:29:25 INFO - 'run-tests'), 04:29:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:25 INFO - 'download_minidump_stackwalk': True, 04:29:25 INFO - 'download_symbols': 'ondemand', 04:29:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:25 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:25 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:25 INFO - 'installer_path': 'installer.exe', 04:29:25 INFO - 'log_level': 'info', 04:29:25 INFO - 'log_name': 'talos', 04:29:25 INFO - 'log_to_console': True, 04:29:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:29:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:29:25 INFO - 'opt_config_files': (), 04:29:25 INFO - 'pip_index': False, 04:29:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:25 INFO - 'cmd': ('bash', 04:29:25 INFO - '-c', 04:29:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:25 INFO - 'enabled': True, 04:29:25 INFO - 'halt_on_failure': False, 04:29:25 INFO - 'name': 'check_screen_resolution'},), 04:29:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:25 INFO - 'cmd': ('bash', 04:29:25 INFO - '-c', 04:29:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:25 INFO - 'enabled': True, 04:29:25 INFO - 'halt_on_failure': False, 04:29:25 INFO - 'name': 'check_screen_resolution'},), 04:29:25 INFO - 'run_cmd_checks_enabled': True, 04:29:25 INFO - 'sps_profile': False, 04:29:25 INFO - 'sps_profile_interval': 0, 04:29:25 INFO - 'suite': 'dromaeojs', 04:29:25 INFO - 'system_bits': '32', 04:29:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:25 INFO - 'title': 't-yosemite-r7-0190', 04:29:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:25 INFO - 'use_talos_json': True, 04:29:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:25 INFO - 'work_dir': 'build'} 04:29:25 INFO - ##### 04:29:25 INFO - ##### Running clobber step. 04:29:25 INFO - ##### 04:29:25 INFO - Running pre-action listener: _resource_record_pre_action 04:29:25 INFO - Running main action method: clobber 04:29:25 INFO - rmtree: /builds/slave/test/build 04:29:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:28 INFO - Running post-action listener: _resource_record_post_action 04:29:28 INFO - ##### 04:29:28 INFO - ##### Running read-buildbot-config step. 04:29:28 INFO - ##### 04:29:28 INFO - Running pre-action listener: _resource_record_pre_action 04:29:28 INFO - Running main action method: read_buildbot_config 04:29:28 INFO - Using buildbot properties: 04:29:28 INFO - { 04:29:28 INFO - "project": "", 04:29:28 INFO - "product": "firefox", 04:29:28 INFO - "script_repo_revision": "production", 04:29:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:29:28 INFO - "repository": "", 04:29:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:29:28 INFO - "buildid": "20160420024137", 04:29:28 INFO - "pgo_build": "False", 04:29:28 INFO - "basedir": "/builds/slave/test", 04:29:28 INFO - "buildnumber": 297, 04:29:28 INFO - "slavename": "t-yosemite-r7-0190", 04:29:28 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:29:28 INFO - "platform": "yosemite_r7", 04:29:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:29:28 INFO - "branch": "mozilla-aurora", 04:29:28 INFO - "repo_path": "releases/mozilla-aurora", 04:29:28 INFO - "revision": "88c060c38873d2373efd49a2e58acc46c22ba532", 04:29:28 INFO - "stage_platform": "macosx64", 04:29:28 INFO - "builduid": "90e0ea923ce741c390fe2bff0056a598", 04:29:28 INFO - "slavebuilddir": "test" 04:29:28 INFO - } 04:29:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 04:29:28 INFO - Running post-action listener: _resource_record_post_action 04:29:28 INFO - ##### 04:29:28 INFO - ##### Running download-and-extract step. 04:29:28 INFO - ##### 04:29:28 INFO - Running pre-action listener: _resource_record_pre_action 04:29:28 INFO - Running main action method: download_and_extract 04:29:28 INFO - mkdir: /builds/slave/test/build/tests 04:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:28 INFO - https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:29:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 04:29:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 04:29:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 04:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 04:29:29 INFO - Downloaded 1277 bytes. 04:29:29 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 04:29:29 INFO - Using the following test package requirements: 04:29:29 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 04:29:29 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 04:29:29 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 04:29:29 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'jsshell-mac.zip'], 04:29:29 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 04:29:29 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 04:29:29 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 04:29:29 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 04:29:29 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 04:29:29 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 04:29:29 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 04:29:29 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 04:29:29 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:29: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')]} 04:29:29 INFO - https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:29:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 04:29:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 04:29:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 04:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:29:31 INFO - Downloaded 17912057 bytes. 04:29:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:29:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:29:34 INFO - Return code: 0 04:29:34 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:34 INFO - https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:29:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 04:29:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 04:29:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 04:29:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:29:35 INFO - Downloaded 17912057 bytes. 04:29:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:29:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:29:38 INFO - Return code: 0 04:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:38 INFO - https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:29:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 04:29:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 04:29:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 04:29:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:29:40 INFO - Downloaded 10845168 bytes. 04:29:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:29:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:29:41 INFO - Return code: 0 04:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:41 INFO - https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 04:29:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 04:29:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 04:29:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 04:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 04:29:44 INFO - Downloaded 115253841 bytes. 04:29:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 04:29:44 INFO - mkdir: /builds/slave/test/properties 04:29:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:44 INFO - Contents: 04:29:44 INFO - build_url:https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 04:29:46 INFO - Running post-action listener: _resource_record_post_action 04:29:46 INFO - Running post-action listener: set_extra_try_arguments 04:29:46 INFO - ##### 04:29:46 INFO - ##### Running populate-webroot step. 04:29:46 INFO - ##### 04:29:46 INFO - Running pre-action listener: _resource_record_pre_action 04:29:46 INFO - Running main action method: populate_webroot 04:29:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:46 INFO - '--tppagecycles', 04:29:46 INFO - '7'], 04:29:46 INFO - 'tests': ['tsvgm']}}, 04:29:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:29:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:29:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'tests': ['damp', 'tps']}, 04:29:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['damp', 'tps']}, 04:29:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:29:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:46 INFO - 'other': {'tests': ['a11yr', 04:29:46 INFO - 'ts_paint', 04:29:46 INFO - 'tpaint', 04:29:46 INFO - 'sessionrestore', 04:29:46 INFO - 'sessionrestore_no_auto_restore', 04:29:46 INFO - 'tabpaint']}, 04:29:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['a11yr', 04:29:46 INFO - 'ts_paint', 04:29:46 INFO - 'tpaint', 04:29:46 INFO - 'sessionrestore', 04:29:46 INFO - 'sessionrestore_no_auto_restore', 04:29:46 INFO - 'tabpaint']}, 04:29:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['a11yr', 04:29:46 INFO - 'ts_paint', 04:29:46 INFO - 'tpaint', 04:29:46 INFO - 'sessionrestore', 04:29:46 INFO - 'sessionrestore_no_auto_restore', 04:29:46 INFO - 'tabpaint']}, 04:29:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['a11yr', 04:29:46 INFO - 'ts_paint', 04:29:46 INFO - 'tpaint', 04:29:46 INFO - 'sessionrestore', 04:29:46 INFO - 'sessionrestore_no_auto_restore', 04:29:46 INFO - 'tabpaint']}, 04:29:46 INFO - 'other_l64': {'tests': ['a11yr', 04:29:46 INFO - 'ts_paint', 04:29:46 INFO - 'tpaint', 04:29:46 INFO - 'sessionrestore', 04:29:46 INFO - 'sessionrestore_no_auto_restore', 04:29:46 INFO - 'tabpaint']}, 04:29:46 INFO - 'other_nol64': {'tests': ['a11yr', 04:29:46 INFO - 'ts_paint', 04:29:46 INFO - 'tpaint', 04:29:46 INFO - 'sessionrestore', 04:29:46 INFO - 'sessionrestore_no_auto_restore', 04:29:46 INFO - 'tabpaint']}, 04:29:46 INFO - 'svgr': {'tests': ['tsvgx', 04:29:46 INFO - 'tsvgr_opacity', 04:29:46 INFO - 'tart', 04:29:46 INFO - 'tscrollx', 04:29:46 INFO - 'cart']}, 04:29:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['tsvgx', 04:29:46 INFO - 'tsvgr_opacity', 04:29:46 INFO - 'tart', 04:29:46 INFO - 'tscrollx', 04:29:46 INFO - 'cart']}, 04:29:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'tests': ['tp5o']}, 04:29:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'talos_options': ['--e10s'], 04:29:46 INFO - 'tests': ['tp5o']}, 04:29:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'talos_options': ['--xperf_path', 04:29:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:46 INFO - 'tests': ['tp5n']}, 04:29:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:46 INFO - 'talos_options': ['--e10s', 04:29:46 INFO - '--xperf_path', 04:29:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:46 INFO - 'tests': ['tp5n']}}, 04:29:46 INFO - 'talos.zip': {'path': '', 04:29:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:46 INFO - Running post-action listener: _resource_record_post_action 04:29:46 INFO - ##### 04:29:46 INFO - ##### Running create-virtualenv step. 04:29:46 INFO - ##### 04:29:46 INFO - Running pre-action listener: _resource_record_pre_action 04:29:46 INFO - Running main action method: create_virtualenv 04:29:46 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:46 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 04:29:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:46 INFO - Using real prefix '/tools/python27' 04:29:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:47 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:49 INFO - Installing pip.................done. 04:29:49 INFO - Return code: 0 04:29:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:49 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:29:49 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 04:29:49 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 04:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:49 INFO - 'HOME': '/Users/cltbld', 04:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:49 INFO - 'LOGNAME': 'cltbld', 04:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:49 INFO - 'MOZ_NO_REMOTE': '1', 04:29:49 INFO - 'NO_EM_RESTART': '1', 04:29:49 INFO - 'PAGER': '/bin/cat', 04:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:49 INFO - 'PWD': '/builds/slave/test', 04:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:49 INFO - 'SHELL': '/bin/bash', 04:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:29:49 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:49 INFO - 'USER': 'cltbld', 04:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:49 INFO - 'XPC_FLAGS': '0x0', 04:29:49 INFO - 'XPC_SERVICE_NAME': '0', 04:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:50 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 04:29:50 INFO - Cleaning up... 04:29:50 INFO - Return code: 0 04:29:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:50 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:29:50 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 04:29:50 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 04:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:50 INFO - 'HOME': '/Users/cltbld', 04:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:50 INFO - 'LOGNAME': 'cltbld', 04:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:50 INFO - 'MOZ_NO_REMOTE': '1', 04:29:50 INFO - 'NO_EM_RESTART': '1', 04:29:50 INFO - 'PAGER': '/bin/cat', 04:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:50 INFO - 'PWD': '/builds/slave/test', 04:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:50 INFO - 'SHELL': '/bin/bash', 04:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:29:50 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:50 INFO - 'USER': 'cltbld', 04:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:50 INFO - 'XPC_FLAGS': '0x0', 04:29:50 INFO - 'XPC_SERVICE_NAME': '0', 04:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:50 INFO - Downloading/unpacking psutil>=0.7.1 04:29:50 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 04:29:50 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 04:29:50 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 04:29:50 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 04:29:50 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 04:29:50 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 04:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:53 INFO - Installing collected packages: psutil 04:29:53 INFO - Running setup.py install for psutil 04:29:53 INFO - building 'psutil._psutil_osx' extension 04:29:53 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 INFO - building 'psutil._psutil_posix' extension 04:29:54 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 04:29:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:54 INFO - ^ 04:29:54 INFO - 1 warning generated. 04:29:54 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 04:29:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:54 INFO - Successfully installed psutil 04:29:54 INFO - Cleaning up... 04:29:54 INFO - Return code: 0 04:29:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:54 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:29:54 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 04:29:54 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 04:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:54 INFO - 'HOME': '/Users/cltbld', 04:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:54 INFO - 'LOGNAME': 'cltbld', 04:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:54 INFO - 'MOZ_NO_REMOTE': '1', 04:29:54 INFO - 'NO_EM_RESTART': '1', 04:29:54 INFO - 'PAGER': '/bin/cat', 04:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:54 INFO - 'PWD': '/builds/slave/test', 04:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:54 INFO - 'SHELL': '/bin/bash', 04:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:29:54 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:54 INFO - 'USER': 'cltbld', 04:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:54 INFO - 'XPC_FLAGS': '0x0', 04:29:54 INFO - 'XPC_SERVICE_NAME': '0', 04:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:54 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 04:29:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:29:57 INFO - Installing collected packages: mozsystemmonitor 04:29:57 INFO - Running setup.py install for mozsystemmonitor 04:29:57 INFO - Successfully installed mozsystemmonitor 04:29:57 INFO - Cleaning up... 04:29:57 INFO - Return code: 0 04:29:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:57 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:29:57 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 04:29:57 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 04:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:57 INFO - 'HOME': '/Users/cltbld', 04:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:57 INFO - 'LOGNAME': 'cltbld', 04:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:57 INFO - 'MOZ_NO_REMOTE': '1', 04:29:57 INFO - 'NO_EM_RESTART': '1', 04:29:57 INFO - 'PAGER': '/bin/cat', 04:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:57 INFO - 'PWD': '/builds/slave/test', 04:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:57 INFO - 'SHELL': '/bin/bash', 04:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:29:57 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:57 INFO - 'USER': 'cltbld', 04:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:57 INFO - 'XPC_FLAGS': '0x0', 04:29:57 INFO - 'XPC_SERVICE_NAME': '0', 04:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:57 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 04:29:57 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 04:29:57 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 04:29:57 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 04:29:57 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 04:29:57 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 04:30:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:01 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 04:30:01 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 04:30:01 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 04:30:01 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 04:30:01 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 04:30:01 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 04:30:01 INFO - Downloading docopt-0.6.1.tar.gz 04:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:01 INFO - Running setup.py install for blobuploader 04:30:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:01 INFO - Running setup.py install for requests 04:30:02 INFO - Running setup.py install for docopt 04:30:02 INFO - Successfully installed blobuploader requests docopt 04:30:02 INFO - Cleaning up... 04:30:02 INFO - Return code: 0 04:30:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:02 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:30:02 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 04:30:02 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 04:30:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:30:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:02 INFO - 'HOME': '/Users/cltbld', 04:30:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:02 INFO - 'LOGNAME': 'cltbld', 04:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:02 INFO - 'MOZ_NO_REMOTE': '1', 04:30:02 INFO - 'NO_EM_RESTART': '1', 04:30:02 INFO - 'PAGER': '/bin/cat', 04:30:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:02 INFO - 'PWD': '/builds/slave/test', 04:30:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:02 INFO - 'SHELL': '/bin/bash', 04:30:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:30:02 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:30:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:02 INFO - 'USER': 'cltbld', 04:30:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:02 INFO - 'XPC_FLAGS': '0x0', 04:30:02 INFO - 'XPC_SERVICE_NAME': '0', 04:30:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-0UI6dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-kZ50dF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:02 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-6oHtfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-1Ii1M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-2TBjqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-SHbFJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-tfGMOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-FIuYjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-nj_8wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-QPGrQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-6fKP7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-UpLGyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:03 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ffJLAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:04 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-JgU7XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:04 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-aoKp5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:04 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-BLYeSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:04 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-944J3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:04 INFO - Running setup.py install for manifestparser 04:30:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:04 INFO - Running setup.py install for mozcrash 04:30:04 INFO - Running setup.py install for mozdebug 04:30:04 INFO - Running setup.py install for mozdevice 04:30:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozfile 04:30:05 INFO - Running setup.py install for mozhttpd 04:30:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozinfo 04:30:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozInstall 04:30:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:30:05 INFO - Running setup.py install for mozleak 04:30:05 INFO - Running setup.py install for mozlog 04:30:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Running setup.py install for moznetwork 04:30:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Running setup.py install for mozprocess 04:30:06 INFO - Running setup.py install for mozprofile 04:30:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Running setup.py install for mozrunner 04:30:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:30:06 INFO - Running setup.py install for mozscreenshot 04:30:06 INFO - Running setup.py install for moztest 04:30:06 INFO - Running setup.py install for mozversion 04:30:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:30:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:30:07 INFO - Cleaning up... 04:30:07 INFO - Return code: 0 04:30:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:07 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:30:07 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 04:30:07 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 04:30:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:30:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:07 INFO - 'HOME': '/Users/cltbld', 04:30:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:07 INFO - 'LOGNAME': 'cltbld', 04:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:07 INFO - 'MOZ_NO_REMOTE': '1', 04:30:07 INFO - 'NO_EM_RESTART': '1', 04:30:07 INFO - 'PAGER': '/bin/cat', 04:30:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:07 INFO - 'PWD': '/builds/slave/test', 04:30:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:07 INFO - 'SHELL': '/bin/bash', 04:30:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:30:07 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:30:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:07 INFO - 'USER': 'cltbld', 04:30:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:07 INFO - 'XPC_FLAGS': '0x0', 04:30:07 INFO - 'XPC_SERVICE_NAME': '0', 04:30:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:07 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-3lYTiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:07 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-6r2efG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:07 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Dad6y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:07 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-alZFDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:07 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ZPLNuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:07 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)) 04:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:07 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-m1CDyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-7kGOB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-CTKEGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-fhkVmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-PVEXst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-XT51pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-iMQG_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-GPw5UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-afZOeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-QQ7v0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:08 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)) 04:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:08 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-7c2zQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:09 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)) 04:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:09 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-1BF8ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:09 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)) 04:30:09 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)) 04:30:09 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)) 04:30:09 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)) 04:30:09 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)) 04:30:09 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)) 04:30:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:30:09 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 04:30:09 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 04:30:09 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 04:30:09 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 04:30:09 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 04:30:09 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 04:30:11 INFO - Downloading blessings-1.6.tar.gz 04:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:11 INFO - Installing collected packages: blessings 04:30:11 INFO - Running setup.py install for blessings 04:30:11 INFO - Successfully installed blessings 04:30:11 INFO - Cleaning up... 04:30:11 INFO - Return code: 0 04:30:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:12 INFO - Reading from file tmpfile_stdout 04:30:12 INFO - Current package versions: 04:30:12 INFO - blessings == 1.6 04:30:12 INFO - blobuploader == 1.2.4 04:30:12 INFO - docopt == 0.6.1 04:30:12 INFO - manifestparser == 1.1 04:30:12 INFO - mozInstall == 1.12 04:30:12 INFO - mozcrash == 0.17 04:30:12 INFO - mozdebug == 0.1 04:30:12 INFO - mozdevice == 0.48 04:30:12 INFO - mozfile == 1.2 04:30:12 INFO - mozhttpd == 0.7 04:30:12 INFO - mozinfo == 0.9 04:30:12 INFO - mozleak == 0.1 04:30:12 INFO - mozlog == 3.1 04:30:12 INFO - moznetwork == 0.27 04:30:12 INFO - mozprocess == 0.22 04:30:12 INFO - mozprofile == 0.28 04:30:12 INFO - mozrunner == 6.11 04:30:12 INFO - mozscreenshot == 0.1 04:30:12 INFO - mozsystemmonitor == 0.0 04:30:12 INFO - moztest == 0.7 04:30:12 INFO - mozversion == 1.4 04:30:12 INFO - psutil == 3.1.1 04:30:12 INFO - requests == 1.2.3 04:30:12 INFO - wsgiref == 0.1.2 04:30:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:12 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')]} 04:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 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')]} 04:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:30:12 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 04:30:12 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 04:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:12 INFO - 'HOME': '/Users/cltbld', 04:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:12 INFO - 'LOGNAME': 'cltbld', 04:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:12 INFO - 'MOZ_NO_REMOTE': '1', 04:30:12 INFO - 'NO_EM_RESTART': '1', 04:30:12 INFO - 'PAGER': '/bin/cat', 04:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:12 INFO - 'PWD': '/builds/slave/test', 04:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:12 INFO - 'SHELL': '/bin/bash', 04:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:30:12 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:12 INFO - 'USER': 'cltbld', 04:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:12 INFO - 'XPC_FLAGS': '0x0', 04:30:12 INFO - 'XPC_SERVICE_NAME': '0', 04:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 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)) 04:30:12 INFO - Cleaning up... 04:30:12 INFO - Return code: 0 04:30:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30:12 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')]} 04:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 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')]} 04:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 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 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:30:12 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 04:30:12 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 04:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:12 INFO - 'HOME': '/Users/cltbld', 04:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:12 INFO - 'LOGNAME': 'cltbld', 04:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:12 INFO - 'MOZ_NO_REMOTE': '1', 04:30:12 INFO - 'NO_EM_RESTART': '1', 04:30:12 INFO - 'PAGER': '/bin/cat', 04:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:12 INFO - 'PWD': '/builds/slave/test', 04:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:12 INFO - 'SHELL': '/bin/bash', 04:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:30:12 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:12 INFO - 'USER': 'cltbld', 04:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:12 INFO - 'XPC_FLAGS': '0x0', 04:30:12 INFO - 'XPC_SERVICE_NAME': '0', 04:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:13 INFO - Downloading/unpacking jsonschema 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:16 INFO - Downloading/unpacking functools32 (from jsonschema) 04:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:16 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:16 INFO - warning: no files found matching '*.txt' 04:30:16 INFO - no previously-included directories found matching 'build' 04:30:16 INFO - no previously-included directories found matching 'dist' 04:30:16 INFO - no previously-included directories found matching '.git*' 04:30:16 INFO - Installing collected packages: jsonschema, functools32 04:30:16 INFO - Running setup.py install for functools32 04:30:16 INFO - warning: no files found matching '*.txt' 04:30:16 INFO - no previously-included directories found matching 'build' 04:30:16 INFO - no previously-included directories found matching 'dist' 04:30:16 INFO - no previously-included directories found matching '.git*' 04:30:16 INFO - Successfully installed jsonschema functools32 04:30:16 INFO - Cleaning up... 04:30:16 INFO - Return code: 0 04:30:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1010e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb20ccfbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, '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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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 04:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:17 INFO - 'HOME': '/Users/cltbld', 04:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:17 INFO - 'LOGNAME': 'cltbld', 04:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:17 INFO - 'MOZ_NO_REMOTE': '1', 04:30:17 INFO - 'NO_EM_RESTART': '1', 04:30:17 INFO - 'PAGER': '/bin/cat', 04:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:17 INFO - 'PWD': '/builds/slave/test', 04:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:17 INFO - 'SHELL': '/bin/bash', 04:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:30:17 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:17 INFO - 'USER': 'cltbld', 04:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:17 INFO - 'XPC_FLAGS': '0x0', 04:30:17 INFO - 'XPC_SERVICE_NAME': '0', 04:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:17 INFO - Downloading/unpacking flake8 04:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:30:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:30:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:30:21 INFO - no previously-included directories found matching 'docs/_build' 04:30:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:30:21 INFO - Running setup.py install for pep8 04:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:30:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:30:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:30:21 INFO - no previously-included directories found matching 'docs/_build' 04:30:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:30:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:30:21 INFO - Cleaning up... 04:30:21 INFO - Return code: 0 04:30:21 INFO - Running post-action listener: _resource_record_post_action 04:30:21 INFO - Running post-action listener: _start_resource_monitoring 04:30:21 INFO - Starting resource monitoring. 04:30:21 INFO - ##### 04:30:21 INFO - ##### Running install step. 04:30:21 INFO - ##### 04:30:21 INFO - Running pre-action listener: _resource_record_pre_action 04:30:21 INFO - Running main action method: install 04:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:21 INFO - Reading from file tmpfile_stdout 04:30:21 INFO - Detecting whether we're running mozinstall >=1.0... 04:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:30:21 INFO - Reading from file tmpfile_stdout 04:30:21 INFO - Output received: 04:30:21 INFO - Usage: mozinstall [options] installer 04:30:21 INFO - Options: 04:30:21 INFO - -h, --help show this help message and exit 04:30:21 INFO - -d DEST, --destination=DEST 04:30:21 INFO - Directory to install application into. [default: 04:30:21 INFO - "/builds/slave/test"] 04:30:21 INFO - --app=APP Application being installed. [default: firefox] 04:30:21 INFO - mkdir: /builds/slave/test/build/application 04:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:30:53 INFO - Reading from file tmpfile_stdout 04:30:53 INFO - Output received: 04:30:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:30:53 INFO - Running post-action listener: _resource_record_post_action 04:30:53 INFO - ##### 04:30:53 INFO - ##### Running run-tests step. 04:30:53 INFO - ##### 04:30:53 INFO - Running pre-action listener: _resource_record_pre_action 04:30:53 INFO - Running main action method: run_tests 04:30:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:53 INFO - 2016-04-20 04:30:53.611 screenresolution[1638:7413] starting screenresolution argv=screenresolution get 04:30:53 INFO - 2016-04-20 04:30:53.613 screenresolution[1638:7413] Display 0: 1600x1200x32@60 04:30:53 INFO - 2016-04-20 04:30:53.619 screenresolution[1639:7415] starting screenresolution argv=screenresolution list 04:30:53 INFO - Available Modes on Display 0 04:30:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:53 INFO - Intel Iris: 04:30:53 INFO - Chipset Model: Intel Iris 04:30:53 INFO - Type: GPU 04:30:53 INFO - Bus: Built-In 04:30:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:53 INFO - Vendor: Intel (0x8086) 04:30:53 INFO - Device ID: 0x0a2e 04:30:53 INFO - Revision ID: 0x0009 04:30:53 INFO - Displays: 04:30:53 INFO - Display: 04:30:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:53 INFO - Display Serial Number: 1600x1200 60 04:30:53 INFO - Main Display: Yes 04:30:53 INFO - Mirror: Off 04:30:53 INFO - Online: Yes 04:30:53 INFO - Rotation: Supported 04:30:53 INFO - Return code: 0 04:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:53 INFO - Python 2.7.3 04:30:53 INFO - Return code: 0 04:30:53 INFO - grabbing minidump binary from tooltool 04:30: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')]} 04:30:53 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 0x100f8bd30>, '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 0x100defdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:53 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 04:30:53 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 04:30:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:53 INFO - Return code: 0 04:30:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:30:55 INFO - Return code: 0 04:30:55 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/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--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 04:30:55 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/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0190 --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 04:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRCiM6nJXt/Render', 04:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:55 INFO - 'HOME': '/Users/cltbld', 04:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:55 INFO - 'LOGNAME': 'cltbld', 04:30:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:55 INFO - 'MOZ_NO_REMOTE': '1', 04:30:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:55 INFO - 'NO_EM_RESTART': '1', 04:30:55 INFO - 'PAGER': '/bin/cat', 04:30:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:55 INFO - 'PWD': '/builds/slave/test', 04:30:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:55 INFO - 'SHELL': '/bin/bash', 04:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZeVW7wEsVh/Listeners', 04:30:55 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 04:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:55 INFO - 'USER': 'cltbld', 04:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:55 INFO - 'XPC_FLAGS': '0x0', 04:30:55 INFO - 'XPC_SERVICE_NAME': '0', 04:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:55 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/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--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 04:30:55 INFO - mozversion application_buildid: 20160420024137 04:30:55 INFO - mozversion application_changeset: 88c060c38873d2373efd49a2e58acc46c22ba532 04:30:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:30:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:55 INFO - mozversion application_name: Firefox 04:30:55 INFO - mozversion application_remotingname: firefox-dev 04:30:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:30:55 INFO - mozversion application_vendor: Mozilla 04:30:55 INFO - mozversion application_version: 47.0a2 04:30:55 INFO - mozversion platform_buildid: 20160420024137 04:30:55 INFO - mozversion platform_changeset: 88c060c38873d2373efd49a2e58acc46c22ba532 04:30:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:30:55 INFO - mozversion platform_version: 47.0a2 04:30:55 INFO - using testdate: 1461151855 04:30:55 INFO - actual date: 1461151855 04:30:55 INFO - starting webserver on 'localhost:49270' 04:30:55 INFO - SUITE-START | Running 2 tests 04:30:55 INFO - TEST-START | dromaeo_css 04:30:55 INFO - operating with platform_type : mac_ 04:30:55 INFO - Initialising browser for dromaeo_css test... 04:30:55 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpt1a9Z4/profile http://localhost:49270/getInfo.html) 04:30:57 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:30:57 INFO - PROCESS | 1657 | colorDepth:24 04:30:57 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:30:57 INFO - PROCESS | 1657 | __metrics 04:30:57 INFO - PROCESS | 1657 | 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" 04:30:57 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:30:58 INFO - TEST-INFO | 1657: exit 0 04:30:58 INFO - Browser initialized. 04:30:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:58 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpt1a9Z4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:10 INFO - PROCESS | 1659 | RSS: Main: 207630336 04:31:10 INFO - PROCESS | 1659 | 04:32:01 INFO - PROCESS | 1659 | 1461151921292 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] 04:34:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:34:12 INFO - PROCESS | 1659 | RSS: Main: 320606208 04:34:12 INFO - PROCESS | 1659 | 04:37:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:37:15 INFO - PROCESS | 1659 | RSS: Main: 334921728 04:37:15 INFO - PROCESS | 1659 | 04:40:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:40:18 INFO - PROCESS | 1659 | RSS: Main: 345874432 04:40:18 INFO - PROCESS | 1659 | 04:43:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 04:43:21 INFO - PROCESS | 1659 | RSS: Main: 303325184 04:43:21 INFO - PROCESS | 1659 | 04:46:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 04:46:24 INFO - PROCESS | 1659 | RSS: Main: 305647616 04:46:24 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 04:49:27 INFO - PROCESS | 1659 | RSS: Main: 350064640 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | __start_tp_report 04:49:27 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 04:49:27 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 04:49:27 INFO - PROCESS | 1659 | |i|pagename|runs| 04:49:27 INFO - PROCESS | 1659 | |0;dojo.html;3411.4712219605963;3485.3377858206923;3506.4039113350736;3510.666486683764;3565.5364802575677;5731.426857314266;5750.568707346944;5756.13657951307;5767.221425107609;5773.249477567915;5717.857053573663;5756.366799652035;5768.192453056565;5769.884602307951;5776.133579963008;5102.36220472441;5138.5375316221625;5146.433892271847;5179.041877252702;5272.947270527303;5110;5128.512791284822;5131.717755523445;5137.2037334213255;5141.15170996785;5180.3006594109875;5224.425313215544;5233.685978841282;5238.8690282742855;5271.103912334912;4634.55971682693;5165.38015438148;5166.095933211711;5183.714895680736;5243.292155558989;5116.258142569344;5190.480951904817;5202.08963431398;5221.321228240343;5247.344081989751;5639.887202255932;5649.265595472562;5707.229524014247;5716.857078573069;5744.310682718059;6142.038385241494;6248.468880145193;6290.87418251637;6304.085907543426;6307.148534947815;6115.9694201529155;6207.099970504251;6247.718852651642;6262.561620686553;6267.091271765614;5801.448862358154;5804.2744656917885;5827.067669172912;5851.970740146272;5882.6764527950945;6147.108669243024;6236.688165591702;6238.844028899314;6251.093591429218;6266.749330026839;6044.063170208625;6130.856783744145;6134.2332208473945;6151.231096112986;6152.046432802923;5524.696141712245;5982.312034116046;6176.042713379434;6191.628502289877;6198.690065496798;6026.9095963560585;6078.787242446493;6118.868009418272;6144.846378840565;6162.26052873658;6130.673316708229;6135.0797380392405;6138.048602466625;6157.815265542041;6161.383861613803;4812.663113582085;4828.082664293773;4830.106430310404;4865.927011094836;4868.707877527359;4369.563043695605;4381.496127945264;4392.05570802279;4402.119576084796;4488.102379524108;5545.473180047889;5558.666480011212;5590;5613;5618.438156184349;6159.3224745277985;6170.413810687977;6181.721822517998;6193.597416167935;6199.411055949678;5547.5007249346945;5595.21666966625;5617.494425501724;5619.522340601013;5708.486236238676;5363.088274993261;5368.033753924253;5372.623916325898;5385.057614917374;5393.029254734185;6221.96889015552;6252.374762523711;6256.749730010749;6266.655333956676;6273.65494897785;6154.446099851035;6156.891759483336;6162.969185154225;6215.036669316263;6237.96881015592;6263.123162757127;6267.874642507033;6269;6284.4344009039405;6290.874182516415;6199.442050215503;6220.875582488235;6247.12540244357;6257.7184026718;6284.685765711604;6075.513958883191;6145.709401025834;6155.261368635793;6221.96889015552;6238.095476156022;6022.668753218615;6058.848528786815;6058.878822423439;6060.484858787053;6083.482903953213;5896.321922978741;6055.333913269625;6071.969640151771;6091.116788065794;6098.0852872069545;5866.67733274674;5872;5912.320083190488;6011.819645410645;6021.855034588102;5571.415001424792;5577.525910297508;5595.272614560081;5599.328080630187;5707.429257074259;5508.504234618904;5511.255980442589;5516.751746171512;5522.751476183501;5571.972140139435 04:49:27 INFO - PROCESS | 1659 | |1;ext.html;487.84145152825295;586.0974099886175;598.3298705449897;668.6593380272554;669.4075742967473;30344.544831827512;40685.982850428714;41090.9727256818;41130;41297.58702412971;43262.35106473405;43747;43787.56212437883;43793.562064379345;43795.56204437955;9407.106324899141;9612.134907858292;9614.663486777965;9615.375000624972;9620.326577139604;9503.952480237624;9674.516274186297;9674.806503869919;9681.903180968171;9717.902820971805;9627.422354658744;9863.013698630151;9866.703998880044;9877.111060004598;9881.16010139139;9711.75720606987;9747.561359738793;9845.261605379625;9849.458279794608;9863.506824658738;9507.714768556885;9653.082957119133;9661.323707340489;9662.855057174149;9689.176420004309;42112;42431.36352954708;42443.151136977394;42506.78746606278;42529;3486.3550243205086;3488.476728490721;3489.371913055674;3689.5019172411917;3910.843566257346;29569.96505122332;29578;29974.400511989857;30172.849135754182;30398.93603723859;2752.0367871244903;2759.9724002759917;2785.48191235777;2829.6887342392524;2832.122042166914;466769;469983.6500817508;472846;473012.63493682654;473995;67963;68194;68241.31758682449;68280;68573.65713171402;73199.63400182965;73556.89664655035;77770;80176.59911700404;80341;17850.375236866646;17990.37033703814;18224.179911903997;18322.45032648996;18381.17284722164;11541.826872596917;13701.520446784314;13787.655308617164;13807;13809.102408343426;22426.215082472034;22745.431364216027;22755.544889102126;22795.974181161557;22827.31518054428;8197.754067377988;8872.423292485968;9323.533823308942;9345.439273643604;9354.65067036028;901.9954900225457;986.4032260482448;1009.1170226052204;1042.95828166874;1092.9289596176222;29701.85149074284;30339.544906826417;30620;30710.846445767627;30827.229319267197;1179.5432640688744;1180.4392913366255;1195.6592371174174;1237.832892559511;1244.9066320025854;13172.736545269041;15217.543473695807;15511;15538.145402002772;15968.600784980235;479461;540588;545232;545359;550221;62491.687541562;63121.68439157867;63242.0513692295;63246;63272;1466.5820241231197;1518.9392424303126;1553.5882991007393;1586.6588683433117;1656.98428859477;24545;29332.12003639894;29488.705112949454;29636;30361.240968975068;48112.75943620259;48660;49146.508534914195;49193.52419427423;49321.753391232814;331194;331665;332621;334771;337453.3127334348;29627.555586666647;33845.323093537496;34129.65870341265;34888.47667284942;35778.821105894305;35931.64068359283;43213.351799723656;43554.56445435505;43712.12575748531;44535;36175;36252.63747362493;36504.45243321406;36569.634303656625;36906.44640330451;36098.61436026704;36182.819085904404;36425.81787091154;36648.45027324539;36995.63004369922;36400;36633.717819876176;37005;37708;38183.809080954416;32235.838820805748;33370.165745856546;33454.66545334516;33696.989090327326;33844.83077584596;24530;24887.50224995526;25182.370440739127;25183.61497581712;25438.74561254438 04:49:27 INFO - PROCESS | 1659 | |2;jquery.html;354.48422545196735;374.50939269556875;384.5558380071017;385.45458176679983;391.6905644540813;20068.69896951545;20552.38342849715;20900.954952252403;21030.57938841222;21517.56964860702;20351.69472457914;20674.586508269826;21015.2644657437;21026;21223.257185998493;5861.85345366365;6052.818415447521;6062.9090563641585;6083.17877086592;6099.146119543268;4115.73247738896;4298.96824762058;5735.713214339287;5785.363610002896;6016.7292471838655;6018.458338749511;6021.638701677914;6030.608010479326;6044.486218671418;6047.244094488189;5833.300003999505;5915.970420147914;5941.108833674962;6024.759009639587;6030.065339872327;5726.914096288559;5728.8854222915725;5737.110747834093;5739.110437882135;5744.827655170351;13666;17565.736513952303;20712.792872071237;25277.862496187532;25451.87274063618;2038.8878611676353;2335.1943579465055;2362.3149286706835;2497.475530138673;2804.1026871400954;9762.707118786448;9820;9860.30977831552;10212;10308.175345972304;1764.1267572551542;1777.7333399990105;1836.7520384748022;1839.1539891649857;1880.0881572437356;102565.4871725644;105289.94710052955;105300;107892;111262;9921;11053;11250.437478126061;11490.942545287387;11610.24533405326;14181.574552763434;14400.279986000867;14401.351939162763;14439.35022923971;14441.205733684748;8779.648814047367;8800.075992020873;8805.559722013873;8841.60212790413;8877.822443551093;8948.821023579492;8962.775930601787;8979.910200897908;9045.276377889753;9071.002189759252;4501.909961800811;4520.570545798144;4523.185826551186;4523.253663145544;4575.725456472623;1532.0424734540911;1761.9377493251845;1795.2370242646823;1907.7138429235727;2001.319551352518;938.1790932933683;978.7797745507346;980.9509524523745;1006.8338724110587;1012.3015119567475;19437.611247775247;19846.404607861787;19926.60146797056;19945.902975336496;19999.400017999484;926.346925417579;976.8730065091635;999.5002498750624;1011.2011510906301;1036.528379587286;7540.811479712941;10817.18871084656;10853.620123295646;10864.185186111074;10946.835797463045;556322.2183889055;562074;566556;615172;689768;125721;129163;131450.4495060676;131644;132843.33578332048;1040.6887321974366;1092.9289596176222;1102.1953973599157;1102.795982743195;1105.9778804423865;17716.734248986533;17770;17822.46532604024;18312.084395780425;18495.815041849946;50877.745611271705;51926.480735193676;52338.47661523336;52787;53055.734721326146;70349;70781;71116.93324600032;73724.52550949059;73740;7774.30031297186;7921.643526041226;7968.083670377749;8022.8395432089865;8309.210624990616;8891.19979201875;11972.58095966637;12190.634280971584;12999.740005199654;13031.543895963596;10828.24202305831;10988.560457581607;11011.61459348919;11621.593244236412;11623.709407264885;10844.891551084389;10909.18181136402;11371.54513819438;11454.255473394203;11734.589289374831;10249.487525623837;10378;10411.791764164524;10945;11356.205065645325;8086.676532938852;8131.79670508242;8174.427790054639;8323.95838020806;8514.74455766328;10276.126529245097;10952.726181845199;11848.585299514476;11854.94072529632;11858.525658973544 04:49:27 INFO - PROCESS | 1659 | |3;mootools.html;1478.1648368671706;1603.0942517477624;1624.122973594259;1945.7275981362611;2057.7310625518635;8291.378146639003;8882.200601945831;9024.774380640487;9025.18773310402;9033.72898813035;8776.254018408443;9018.14327638875;9049;9052.954735226314;9053.95473022634;2257.7177852768405;2511.3972646564785;2513.40934880303;2553.6552565403617;2557.514072326261;2560.4239046214548;2606.7653911147995;2609.386794103384;2610.543154947886;2614.594737815632;2483.9751602484016;2509.3475696318906;2513.761192686701;2514.107491840398;2517.1567524879188;2512.309114993382;2530.5318516074494;2569.9357516062246;2570.370259286487;2571.614257861317;2539.987300063506;2566.384067823709;2578.071894118116;2586.069015154562;2591;8846.469211847245;8913.420627659181;8916.73249802507;8921.420107693044;8963.058878817761;5520.365158006841;5710.65736055838;5720.542356611461;5734.225879506255;5750.108733146369;3579.4093974494176;3644.2711457708565;3741.382671859167;3752.887413377603;3754.042719106638;1066.664000839733;1074.2641290715885;1085.5549224817867;1100.0374672161859;1121.9846039334416;11953.163278570504;12140.575079872251;12154.5745898894;12155.817662734979;12182.086343524272;4866.562009419169;4887.608991280689;4988.451270360258;4997.775100120431;5004.899902001939;10510.316829406063;10529.631462898915;10531.736706582244;10545.420001899816;10571.788564228673;5552.305961754781;5599.776008959678;5603.075492543685;5619.606627536115;5621.381648018714;4552.635789136861;4710.646701497401;4895.657303988686;5049.8737531562;5051.646384753032;3596.1549035976523;4060.7381273563324;4107.424960505532;4107.630313271819;4165.479315085614;1178.7878181927335;1361.7140400515805;1384.197165643924;1391.0679844504207;1391.3391139208795;1131.7000994736402;1398.4336343780785;1448.0153495622867;1452.832924213729;1457.358762144653;8147.266745992889;8293.95853020731;8296.626651800683;8346.958265208757;8362.749117526484;1246.9750604987848;1251.6495381293253;1252.9686757830946;1256.723520825417;1271.053065466215;6355.968220158869;6385.457236134887;6441.3880681335195;6459.224893012869;6468.902966455507;10797.62208322705;11155.995960363607;11288.435578221057;11307.038901693448;11333.773324533464;10650.520726567169;11042.392668403316;11056.557737690564;11068.335899846035;11104.944475277573;8613.397062205586;8694.08712085222;8743;8752.606132723915;8758.12418758119;8104.554249516334;8142.7557173284895;8143.104258531675;8238.83522329562;8239.299659528768;9014.86477702848;9355.111264429868;9524.571394287135;9871.753206169617;9999.35004224723;8719.12808719123;9307.11582399671;9492.335536512377;9809.019098090133;9814.06766357195;6649.335066493312;7301.233370496021;7424.925750742639;7545.622718864144;7566.697332106654;8107.22981316774;8109.3917956152345;8165.591720414074;8176.182381761776;8201.13888041771;7321.5607063574225;7411.888821667787;7476.289752473506;7556.8488630228185;7641.197674244347;7314.853702926018;7330.816729581803;7365.7790266292095;7432.814179645552;7432.851342973218;7930.325922296669;7940.325072368682;7977.521348719096;7985.72049978248;7993.92006079955;5878.794242201502;7166.677499512431;7215.963920180366;7216.134063912364;7223.566586004857;7225.060742103493;7300.890486642811;7301.780946571611;7304.744333948286;7305.415566754753 04:49:27 INFO - PROCESS | 1659 | |4;prototype.html;336.6700633379288;345.79598037158087;347.5586005772667;348.5083841159836;353.468030613926;6585.934140658592;6670.166229221348;6681.899771503426;6687.531872768908;6705.195376554808;5297.258383826267;6602.47180225582;6606.372394622503;6608.966955165218;6616.106825578546;3954.604539546037;4698.248280275139;4803.5676789088975;4940.184869496528;4940.827071052514;4887.7311747853855;4914.410270767513;4917.2869933859565;4924.679895806761;4934.383202099738;4875.658703890729;4879.365682461293;4885.60915126789;4896.510348965093;4900.828471003516;4628.629709623239;4725.669203155763;4863.586595139417;4878.43897951733;4929.704217746911;4248.9787551062045;4250.724782565218;4263.829446822124;4264.402983582301;4270.871873843789;6349.936500634981;6353.904691429632;6357.745690172434;6364.204474440695;6378.808635740936;5815.9709201453725;5816.651000939914;5818.243628328332;5821.970890145522;5823.79616713413;6701.832454188636;7043.9647801761175;7051.788446346618;7055.717771289143;7065.399441047466;4591.701539399928;4613.215753321943;4615.630749540029;4615.676902616819;4618.630509559227;7376.520526165782;7482;7489.625518724097;7491.438142139307;7494.737684181027;4703.835365762182;4716.929246061243;4727.125481785881;4727.527247275314;4731.124741922756;4774.427068751772;4774.498677638798;4778.450478194983;4785.95214047862;4787.640926930424;5527.309086364205;5739.081746920556;5741.655500669973;5750.913736293959;5759.884802303931;7034.40207582351;7049.929500704928;7060.364567188978;7062.3996960258955;7085.291470852977;1612.7258366077795;1664.25108701085;1667.2330727865076;1667.374734474572;1668.1575804218792;992.2012779712256;1043.3531210649294;1051.952662130206;1052.6263176572359;1083.3878858445112;6388.041793730978;6397.872042559123;6401.007843784221;6465.1595292611655;6472.967635161888;7189.065421495172;7190.316919892602;7193.820154496179;7218.783436496913;7244.782656520313;6330.0821380899515;6440.903386449207;6454.9677251614385;6467.51493637979;6470.708818103197;2853.5434330507433;2902.811317264371;3878.5261600591593;3881.4177873318663;3956.4263181838473;6823.590584564942;7109.075820143348;7287;7585.848283034308;7594.6202689866395;7503;7526.849463010709;7536.284053014955;7539.32146106853;7542.773716788505;7585.810354741176;7587.089549254015;7596.202398748273;7601.923980760122;7624;5897.469227769349;6996.965015174892;7222;7279.980802687734;7294.598797066213;7121.750738724119;7163.964180179066;7171.318724721144;7172.892406613801;7181.497295189287;7480.663370148465;7537.623118843926;7554.508956917783;7563.092428908566;7587.62061896914;4724.196886529367;4735.076660051394;4738.691985020962;4745.027269409832;4749.572538471554;6963.7562685305775;7219.891701624375;7259.455540834564;7262.818429539092;7263.745768898063;6952.5480843745;6959.4432445405255;6987.056747339044;6990.335918087773;7007.019017337475;6931;6969.860602788017;6972.26791186918;6985.0220969065385;6990.685419156252;6826.829329266808;6840.931590684029;6841.281665425058;6847.589144651337;6847.691853866489;6657.3342665733035;6974.349747096274;6980.092587963735;6985.755498557526;7016.22821489646;6606.6036037837885;6784.660766961533;6817.147856517935;6849.863002739818;6874.518783685294 04:49:27 INFO - PROCESS | 1659 | |5;yui.html;714.9571025738456;727.3235890621721;763.736510903738;774.8179177893194;780.5979920341025;15046.699066018673;15287.464938727147;15339.616509587238;15345.462908798194;15370.69258614827;14209.502667406643;14396.784048239284;14400.423983040666;14400.71198576028;14408;778.8286576953076;796.6016991504248;940.7130825098342;972.5526257921365;973.6251543155901;962.7689354554891;971.0920289529278;973.6932866147141;975.6975337645317;976.5068640336656;1025.52825700178;1029.727122312584;1032.2309879140014;1035.0425856083132;1037.3516552154904;825;1007.8185926533221;1027.121810851726;1030.1398332392462;1032.4785983078534;772.8029352515073;777.1839568453158;777.2577188784753;782.8434313137395;783.6160281462099;12513.43689533964;14822.925885370612;15319.540413787605;15329.923350383287;15343;12853.421596028202;13093.018023648265;13171.407286672124;13199.274039927801;13580.456781728719;10147.238957078249;10246.948765256126;10428.530716117793;10576.10103141234;10985.066269367033;3510.2452972610754;3524.1718196223865;3571;3575.463680447928;3660.5790334111653;79359.60320198363;79402;79885;82297;82525.58737206335;9215.907840921505;9331;9512.1439070484;9517.286203534763;9913.553890074963;23122.3063308101;23145.18991835278;23178.65232021521;23188.53622927532;23222.53554928892;7699.884501732478;7705.267999540035;7721.266479684421;7878.290953814069;7930.6431210595665;3627.764195327295;4249.660027197817;4261.169072030924;4261.424707664488;4548.363229147922;3687.6865466435115;3718.2563487302646;3722.1439069014277;3729.5524537055726;3735.2716220336774;601.6480358989952;617.1853153836981;637.528229110455;660.9471242300605;669.1334721535526;537.6719502828008;557.1948534367799;574.9223854779635;610.8656095658948;611.4924612571555;8016.158303378178;8051.798705032304;8254.0507841599;8289.378296627778;8293.377996650275;549.8570371703386;584.7982446056104;593.7209511529575;609.2932198649634;620.0389396435507;3882.2429626222615;3922.86269980555;3979.820908059144;4019.457373254632;4132.8140233689555;71675.64162179155;91956;93887;97494;98360;76648.61675691698;77703.61148194222;77765;79176.20823791689;81019;55368.72315638396;71208;71479.64260178666;72526;74197;7982.9600851995365;8015.318697910743;8073.19268073188;8096.579557260383;8097.716579919675;47904.04191916212;48277.27584086312;49051.75474122607;49845;50198.7490062562;64484;68763.3123668777;69689.65155174394;70579.64710176416;70999.64500177465;10454.11140053104;10685.626003089854;10712.678619641423;10865.18511111654;11113.333200008025;11156.49795759209;11218.326900385677;11463.968242857849;11528.308301501937;12018.939905300418;10365.689029329133;10584.312019718695;10723.517441714985;10742.033217010507;10876.945615271872;10439.53022114022;10476.94761526218;10497.632582859564;10618.73453163677;10624.628138015132;10317;10479.68560943173;10516.102844357552;10659.946700266759;10726.51730672106;8809.526166272473;8872.866906996529;9174.174324310845;9264.907350926675;9301.930278018115;7899.526028438312;8997.546214623802;9053.230475409664;9089.31830112731;9358.490566037735 04:49:27 INFO - PROCESS | 1659 | __end_tp_report 04:49:27 INFO - PROCESS | 1659 | __start_cc_report 04:49:27 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1030 04:49:27 INFO - PROCESS | 1659 | __end_cc_report 04:49:27 INFO - PROCESS | 1659 | __startTimestamp1461152967652__endTimestamp 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | ------- Summary: start ------- 04:49:27 INFO - PROCESS | 1659 | Number of tests: 6 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5688.94 Median:5804.27 stddev:617.54 (10.6%) stddev-sans-first:593.03 04:49:27 INFO - PROCESS | 1659 | Values: 3411.5 3485.3 3506.4 3510.7 3565.5 5731.4 5750.6 5756.1 5767.2 5773.2 5717.9 5756.4 5768.2 5769.9 5776.1 5102.4 5138.5 5146.4 5179.0 5272.9 5110.0 5128.5 5131.7 5137.2 5141.2 5180.3 5224.4 5233.7 5238.9 5271.1 4634.6 5165.4 5166.1 5183.7 5243.3 5116.3 5190.5 5202.1 5221.3 5247.3 5639.9 5649.3 5707.2 5716.9 5744.3 6142.0 6248.5 6290.9 6304.1 6307.1 6116.0 6207.1 6247.7 6262.6 6267.1 5801.4 5804.3 5827.1 5852.0 5882.7 6147.1 6236.7 6238.8 6251.1 6266.7 6044.1 6130.9 6134.2 6151.2 6152.0 5524.7 5982.3 6176.0 6191.6 6198.7 6026.9 6078.8 6118.9 6144.8 6162.3 6130.7 6135.1 6138.0 6157.8 6161.4 4812.7 4828.1 4830.1 4865.9 4868.7 4369.6 4381.5 4392.1 4402.1 4488.1 5545.5 5558.7 5590.0 5613.0 5618.4 6159.3 6170.4 6181.7 6193.6 6199.4 5547.5 5595.2 5617.5 5619.5 5708.5 5363.1 5368.0 5372.6 5385.1 5393.0 6222.0 6252.4 6256.7 6266.7 6273.7 6154.4 6156.9 6163.0 6215.0 6238.0 6263.1 6267.9 6269.0 6284.4 6290.9 6199.4 6220.9 6247.1 6257.7 6284.7 6075.5 6145.7 6155.3 6222.0 6238.1 6022.7 6058.8 6058.9 6060.5 6083.5 5896.3 6055.3 6072.0 6091.1 6098.1 5866.7 5872.0 5912.3 6011.8 6021.9 5571.4 5577.5 5595.3 5599.3 5707.4 5508.5 5511.3 5516.8 5522.8 5572.0 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:60838.93 Median:37000.32 stddev:120457.86 (325.6%) stddev-sans-first:120710.55 04:49:27 INFO - PROCESS | 1659 | Values: 487.8 586.1 598.3 668.7 669.4 30344.5 40686.0 41091.0 41130.0 41297.6 43262.4 43747.0 43787.6 43793.6 43795.6 9407.1 9612.1 9614.7 9615.4 9620.3 9504.0 9674.5 9674.8 9681.9 9717.9 9627.4 9863.0 9866.7 9877.1 9881.2 9711.8 9747.6 9845.3 9849.5 9863.5 9507.7 9653.1 9661.3 9662.9 9689.2 42112.0 42431.4 42443.2 42506.8 42529.0 3486.4 3488.5 3489.4 3689.5 3910.8 29570.0 29578.0 29974.4 30172.8 30398.9 2752.0 2760.0 2785.5 2829.7 2832.1 466769.0 469983.7 472846.0 473012.6 473995.0 67963.0 68194.0 68241.3 68280.0 68573.7 73199.6 73556.9 77770.0 80176.6 80341.0 17850.4 17990.4 18224.2 18322.5 18381.2 11541.8 13701.5 13787.7 13807.0 13809.1 22426.2 22745.4 22755.5 22796.0 22827.3 8197.8 8872.4 9323.5 9345.4 9354.7 902.0 986.4 1009.1 1043.0 1092.9 29701.9 30339.5 30620.0 30710.8 30827.2 1179.5 1180.4 1195.7 1237.8 1244.9 13172.7 15217.5 15511.0 15538.1 15968.6 479461.0 540588.0 545232.0 545359.0 550221.0 62491.7 63121.7 63242.1 63246.0 63272.0 1466.6 1518.9 1553.6 1586.7 1657.0 24545.0 29332.1 29488.7 29636.0 30361.2 48112.8 48660.0 49146.5 49193.5 49321.8 331194.0 331665.0 332621.0 334771.0 337453.3 29627.6 33845.3 34129.7 34888.5 35778.8 35931.6 43213.4 43554.6 43712.1 44535.0 36175.0 36252.6 36504.5 36569.6 36906.4 36098.6 36182.8 36425.8 36648.5 36995.6 36400.0 36633.7 37005.0 37708.0 38183.8 32235.8 33370.2 33454.7 33697.0 33844.8 24530.0 24887.5 25182.4 25183.6 25438.7 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34382.38 Median:21370.41 stddev:99924.64 (467.6%) stddev-sans-first:100172.28 04:49:27 INFO - PROCESS | 1659 | Values: 354.5 374.5 384.6 385.5 391.7 20068.7 20552.4 20901.0 21030.6 21517.6 20351.7 20674.6 21015.3 21026.0 21223.3 5861.9 6052.8 6062.9 6083.2 6099.1 4115.7 4299.0 5735.7 5785.4 6016.7 6018.5 6021.6 6030.6 6044.5 6047.2 5833.3 5916.0 5941.1 6024.8 6030.1 5726.9 5728.9 5737.1 5739.1 5744.8 13666.0 17565.7 20712.8 25277.9 25451.9 2038.9 2335.2 2362.3 2497.5 2804.1 9762.7 9820.0 9860.3 10212.0 10308.2 1764.1 1777.7 1836.8 1839.2 1880.1 102565.5 105289.9 105300.0 107892.0 111262.0 9921.0 11053.0 11250.4 11490.9 11610.2 14181.6 14400.3 14401.4 14439.4 14441.2 8779.6 8800.1 8805.6 8841.6 8877.8 8948.8 8962.8 8979.9 9045.3 9071.0 4501.9 4520.6 4523.2 4523.3 4575.7 1532.0 1761.9 1795.2 1907.7 2001.3 938.2 978.8 981.0 1006.8 1012.3 19437.6 19846.4 19926.6 19945.9 19999.4 926.3 976.9 999.5 1011.2 1036.5 7540.8 10817.2 10853.6 10864.2 10946.8 556322.2 562074.0 566556.0 615172.0 689768.0 125721.0 129163.0 131450.4 131644.0 132843.3 1040.7 1092.9 1102.2 1102.8 1106.0 17716.7 17770.0 17822.5 18312.1 18495.8 50877.7 51926.5 52338.5 52787.0 53055.7 70349.0 70781.0 71116.9 73724.5 73740.0 7774.3 7921.6 7968.1 8022.8 8309.2 8891.2 11972.6 12190.6 12999.7 13031.5 10828.2 10988.6 11011.6 11621.6 11623.7 10844.9 10909.2 11371.5 11454.3 11734.6 10249.5 10378.0 10411.8 10945.0 11356.2 8086.7 8131.8 8174.4 8324.0 8514.7 10276.1 10952.7 11848.6 11854.9 11858.5 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6193.19 Median:5536.34 stddev:3228.73 (58.3%) stddev-sans-first:3218.33 04:49:27 INFO - PROCESS | 1659 | Values: 1478.2 1603.1 1624.1 1945.7 2057.7 8291.4 8882.2 9024.8 9025.2 9033.7 8776.3 9018.1 9049.0 9053.0 9054.0 2257.7 2511.4 2513.4 2553.7 2557.5 2560.4 2606.8 2609.4 2610.5 2614.6 2484.0 2509.3 2513.8 2514.1 2517.2 2512.3 2530.5 2569.9 2570.4 2571.6 2540.0 2566.4 2578.1 2586.1 2591.0 8846.5 8913.4 8916.7 8921.4 8963.1 5520.4 5710.7 5720.5 5734.2 5750.1 3579.4 3644.3 3741.4 3752.9 3754.0 1066.7 1074.3 1085.6 1100.0 1122.0 11953.2 12140.6 12154.6 12155.8 12182.1 4866.6 4887.6 4988.5 4997.8 5004.9 10510.3 10529.6 10531.7 10545.4 10571.8 5552.3 5599.8 5603.1 5619.6 5621.4 4552.6 4710.6 4895.7 5049.9 5051.6 3596.2 4060.7 4107.4 4107.6 4165.5 1178.8 1361.7 1384.2 1391.1 1391.3 1131.7 1398.4 1448.0 1452.8 1457.4 8147.3 8294.0 8296.6 8347.0 8362.7 1247.0 1251.6 1253.0 1256.7 1271.1 6356.0 6385.5 6441.4 6459.2 6468.9 10797.6 11156.0 11288.4 11307.0 11333.8 10650.5 11042.4 11056.6 11068.3 11104.9 8613.4 8694.1 8743.0 8752.6 8758.1 8104.6 8142.8 8143.1 8238.8 8239.3 9014.9 9355.1 9524.6 9871.8 9999.4 8719.1 9307.1 9492.3 9809.0 9814.1 6649.3 7301.2 7424.9 7545.6 7566.7 8107.2 8109.4 8165.6 8176.2 8201.1 7321.6 7411.9 7476.3 7556.8 7641.2 7314.9 7330.8 7365.8 7432.8 7432.9 7930.3 7940.3 7977.5 7985.7 7993.9 5878.8 7166.7 7216.0 7216.1 7223.6 7225.1 7300.9 7301.8 7304.7 7305.4 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:5754.06 Median:6529.45 stddev:1826.55 (28.0%) stddev-sans-first:1785.84 04:49:27 INFO - PROCESS | 1659 | Values: 336.7 345.8 347.6 348.5 353.5 6585.9 6670.2 6681.9 6687.5 6705.2 5297.3 6602.5 6606.4 6609.0 6616.1 3954.6 4698.2 4803.6 4940.2 4940.8 4887.7 4914.4 4917.3 4924.7 4934.4 4875.7 4879.4 4885.6 4896.5 4900.8 4628.6 4725.7 4863.6 4878.4 4929.7 4249.0 4250.7 4263.8 4264.4 4270.9 6349.9 6353.9 6357.7 6364.2 6378.8 5816.0 5816.7 5818.2 5822.0 5823.8 6701.8 7044.0 7051.8 7055.7 7065.4 4591.7 4613.2 4615.6 4615.7 4618.6 7376.5 7482.0 7489.6 7491.4 7494.7 4703.8 4716.9 4727.1 4727.5 4731.1 4774.4 4774.5 4778.5 4786.0 4787.6 5527.3 5739.1 5741.7 5750.9 5759.9 7034.4 7049.9 7060.4 7062.4 7085.3 1612.7 1664.3 1667.2 1667.4 1668.2 992.2 1043.4 1052.0 1052.6 1083.4 6388.0 6397.9 6401.0 6465.2 6473.0 7189.1 7190.3 7193.8 7218.8 7244.8 6330.1 6440.9 6455.0 6467.5 6470.7 2853.5 2902.8 3878.5 3881.4 3956.4 6823.6 7109.1 7287.0 7585.8 7594.6 7503.0 7526.8 7536.3 7539.3 7542.8 7585.8 7587.1 7596.2 7601.9 7624.0 5897.5 6997.0 7222.0 7280.0 7294.6 7121.8 7164.0 7171.3 7172.9 7181.5 7480.7 7537.6 7554.5 7563.1 7587.6 4724.2 4735.1 4738.7 4745.0 4749.6 6963.8 7219.9 7259.5 7262.8 7263.7 6952.5 6959.4 6987.1 6990.3 7007.0 6931.0 6969.9 6972.3 6985.0 6990.7 6826.8 6840.9 6841.3 6847.6 6847.7 6657.3 6974.3 6980.1 6985.8 7016.2 6606.6 6784.7 6817.1 6849.9 6874.5 04:49:27 INFO - PROCESS | 1659 | 04:49:27 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18222.77 Median:27959.29 stddev:25657.96 (91.8%) stddev-sans-first:25696.26 04:49:27 INFO - PROCESS | 1659 | Values: 715.0 727.3 763.7 774.8 780.6 15046.7 15287.5 15339.6 15345.5 15370.7 14209.5 14396.8 14400.4 14400.7 14408.0 778.8 796.6 940.7 972.6 973.6 962.8 971.1 973.7 975.7 976.5 1025.5 1029.7 1032.2 1035.0 1037.4 825.0 1007.8 1027.1 1030.1 1032.5 772.8 777.2 777.3 782.8 783.6 12513.4 14822.9 15319.5 15329.9 15343.0 12853.4 13093.0 13171.4 13199.3 13580.5 10147.2 10246.9 10428.5 10576.1 10985.1 3510.2 3524.2 3571.0 3575.5 3660.6 79359.6 79402.0 79885.0 82297.0 82525.6 9215.9 9331.0 9512.1 9517.3 9913.6 23122.3 23145.2 23178.7 23188.5 23222.5 7699.9 7705.3 7721.3 7878.3 7930.6 3627.8 4249.7 4261.2 4261.4 4548.4 3687.7 3718.3 3722.1 3729.6 3735.3 601.6 617.2 637.5 660.9 669.1 537.7 557.2 574.9 610.9 611.5 8016.2 8051.8 8254.1 8289.4 8293.4 549.9 584.8 593.7 609.3 620.0 3882.2 3922.9 3979.8 4019.5 4132.8 71675.6 91956.0 93887.0 97494.0 98360.0 76648.6 77703.6 77765.0 79176.2 81019.0 55368.7 71208.0 71479.6 72526.0 74197.0 7983.0 8015.3 8073.2 8096.6 8097.7 47904.0 48277.3 49051.8 49845.0 50198.7 64484.0 68763.3 69689.7 70579.6 70999.6 10454.1 10685.6 10712.7 10865.2 11113.3 11156.5 11218.3 11464.0 11528.3 12018.9 10365.7 10584.3 10723.5 10742.0 10876.9 10439.5 10476.9 10497.6 10618.7 10624.6 10317.0 10479.7 10516.1 10659.9 10726.5 8809.5 8872.9 9174.2 9264.9 9301.9 7899.5 8997.5 9053.2 9089.3 9358.5 04:49:27 INFO - PROCESS | 1659 | -------- Summary: end -------- 04:49:27 INFO - PROCESS | 1659 | 04:49:28 INFO - TEST-INFO | 1659: exit 0 04:49:28 INFO - TEST-OK | dromaeo_css | took 1112794ms 04:49:28 INFO - TEST-START | kraken 04:49:28 INFO - operating with platform_type : mac_ 04:49:28 INFO - Initialising browser for kraken test... 04:49:28 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpFpTQAR/profile http://localhost:49270/getInfo.html) 04:49:30 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 04:49:30 INFO - PROCESS | 1668 | colorDepth:24 04:49:30 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 04:49:30 INFO - PROCESS | 1668 | __metrics 04:49:30 INFO - PROCESS | 1668 | 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" 04:49:30 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:30 INFO - TEST-INFO | 1668: exit 0 04:49:30 INFO - Browser initialized. 04:49:30 INFO - Running cycle 1/1 for kraken test... 04:49:31 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpFpTQAR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:42 INFO - PROCESS | 1670 | RSS: Main: 207736832 04:49:42 INFO - PROCESS | 1670 | 04:49:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:46 INFO - PROCESS | 1670 | RSS: Main: 263585792 04:49:46 INFO - PROCESS | 1670 | 04:49:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 04:49:49 INFO - PROCESS | 1670 | RSS: Main: 273690624 04:49:49 INFO - PROCESS | 1670 | 04:49:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 04:49:53 INFO - PROCESS | 1670 | RSS: Main: 264138752 04:49:53 INFO - PROCESS | 1670 | 04:49:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 04:49:55 INFO - PROCESS | 1670 | RSS: Main: 266469376 04:49:55 INFO - PROCESS | 1670 | 04:49:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:58 INFO - PROCESS | 1670 | RSS: Main: 265842688 04:49:58 INFO - PROCESS | 1670 | 04:50:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:01 INFO - PROCESS | 1670 | RSS: Main: 265994240 04:50:01 INFO - PROCESS | 1670 | 04:50:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:05 INFO - PROCESS | 1670 | RSS: Main: 266100736 04:50:05 INFO - PROCESS | 1670 | 04:50:07 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 04:50:08 INFO - PROCESS | 1670 | RSS: Main: 267022336 04:50:08 INFO - PROCESS | 1670 | 04:50:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:10 INFO - PROCESS | 1670 | RSS: Main: 300285952 04:50:10 INFO - PROCESS | 1670 | 04:50:12 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:13 INFO - PROCESS | 1670 | RSS: Main: 301035520 04:50:13 INFO - PROCESS | 1670 | 04:50:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:15 INFO - PROCESS | 1670 | RSS: Main: 306229248 04:50:15 INFO - PROCESS | 1670 | 04:50:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:18 INFO - PROCESS | 1670 | RSS: Main: 308047872 04:50:18 INFO - PROCESS | 1670 | 04:50:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:21 INFO - PROCESS | 1670 | RSS: Main: 308092928 04:50:21 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 04:50:23 INFO - PROCESS | 1670 | RSS: Main: 370294784 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | __start_tp_report 04:50:23 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 04:50:23 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 04:50:23 INFO - PROCESS | 1670 | |i|pagename|runs| 04:50:23 INFO - PROCESS | 1670 | |0;ai-astar;113;108;112;111;112;110;120;111;111;113 04:50:23 INFO - PROCESS | 1670 | |1;audio-beat-detection;154;122;140;182;143;138;137;188;151;114 04:50:23 INFO - PROCESS | 1670 | |2;audio-dft;185;184;216;261;189;186;185;186;185;184 04:50:23 INFO - PROCESS | 1670 | |3;audio-fft;74;73;74;116;55;58;57;57;122;56 04:50:23 INFO - PROCESS | 1670 | |4;audio-oscillator;91;95;130;80;62;75;137;111;114;153 04:50:23 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;86;84;86;85;85;83;87;83;86;82 04:50:23 INFO - PROCESS | 1670 | |6;imaging-darkroom;92;92;102;147;93;93;94;93;93;92 04:50:23 INFO - PROCESS | 1670 | |7;imaging-desaturate;88;85;87;83;88;87;95;134;87;85 04:50:23 INFO - PROCESS | 1670 | |8;json-parse-financial;55;59;58;58;56;58;57;67;56;53 04:50:23 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;63;59;90;104;73;72;66;67;67;69 04:50:23 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;80;82;80;82;80;75;76;79;81;87 04:50:23 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;117;132;119;120;130;116;132;127;125;110 04:50:23 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;132;122;129;133;133;123;135;132;131;130 04:50:23 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;67;65;66;66;67;64;66;65;65;82 04:50:23 INFO - PROCESS | 1670 | __end_tp_report 04:50:23 INFO - PROCESS | 1670 | __start_cc_report 04:50:23 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1205 04:50:23 INFO - PROCESS | 1670 | __end_cc_report 04:50:23 INFO - PROCESS | 1670 | __startTimestamp1461153023829__endTimestamp 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | ------- Summary: start ------- 04:50:23 INFO - PROCESS | 1670 | Number of tests: 14 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:3.14 (2.8%) stddev-sans-first:3.32 04:50:23 INFO - PROCESS | 1670 | Values: 113.0 108.0 112.0 111.0 112.0 110.0 120.0 111.0 111.0 113.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:146.90 Median:147.00 stddev:23.41 (15.9%) stddev-sans-first:24.69 04:50:23 INFO - PROCESS | 1670 | Values: 154.0 122.0 140.0 182.0 143.0 138.0 137.0 188.0 151.0 114.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:196.10 Median:186.00 stddev:24.78 (13.3%) stddev-sans-first:25.95 04:50:23 INFO - PROCESS | 1670 | Values: 185.0 184.0 216.0 261.0 189.0 186.0 185.0 186.0 185.0 184.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:74.20 Median:57.50 stddev:24.92 (43.3%) stddev-sans-first:26.43 04:50:23 INFO - PROCESS | 1670 | Values: 74.0 73.0 74.0 116.0 55.0 58.0 57.0 57.0 122.0 56.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:68.50 stddev:29.32 (42.8%) stddev-sans-first:30.68 04:50:23 INFO - PROCESS | 1670 | Values: 91.0 95.0 130.0 80.0 62.0 75.0 137.0 111.0 114.0 153.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:85.50 stddev:1.64 (1.9%) stddev-sans-first:1.67 04:50:23 INFO - PROCESS | 1670 | Values: 86.0 84.0 86.0 85.0 85.0 83.0 87.0 83.0 86.0 82.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:93.00 stddev:17.09 (18.4%) stddev-sans-first:17.93 04:50:23 INFO - PROCESS | 1670 | Values: 92.0 92.0 102.0 147.0 93.0 93.0 94.0 93.0 93.0 92.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:87.00 stddev:15.13 (17.4%) stddev-sans-first:15.98 04:50:23 INFO - PROCESS | 1670 | Values: 88.0 85.0 87.0 83.0 88.0 87.0 95.0 134.0 87.0 85.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.00 stddev:3.71 (6.4%) stddev-sans-first:3.81 04:50:23 INFO - PROCESS | 1670 | Values: 55.0 59.0 58.0 58.0 56.0 58.0 57.0 67.0 56.0 53.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:68.00 stddev:13.68 (20.1%) stddev-sans-first:14.02 04:50:23 INFO - PROCESS | 1670 | Values: 63.0 59.0 90.0 104.0 73.0 72.0 66.0 67.0 67.0 69.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.50 stddev:3.33 (4.1%) stddev-sans-first:3.53 04:50:23 INFO - PROCESS | 1670 | Values: 80.0 82.0 80.0 82.0 80.0 75.0 76.0 79.0 81.0 87.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:122.80 Median:126.00 stddev:7.52 (6.0%) stddev-sans-first:7.68 04:50:23 INFO - PROCESS | 1670 | Values: 117.0 132.0 119.0 120.0 130.0 116.0 132.0 127.0 125.0 110.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.00 Median:132.00 stddev:4.29 (3.3%) stddev-sans-first:4.49 04:50:23 INFO - PROCESS | 1670 | Values: 132.0 122.0 129.0 133.0 133.0 123.0 135.0 132.0 131.0 130.0 04:50:23 INFO - PROCESS | 1670 | 04:50:23 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:66.00 stddev:5.25 (8.0%) stddev-sans-first:5.57 04:50:23 INFO - PROCESS | 1670 | Values: 67.0 65.0 66.0 66.0 67.0 64.0 66.0 65.0 65.0 82.0 04:50:23 INFO - PROCESS | 1670 | -------- Summary: end -------- 04:50:23 INFO - PROCESS | 1670 | 04:50:24 INFO - TEST-INFO | 1670: exit 0 04:50:24 INFO - TEST-OK | kraken | took 56488ms 04:50:24 INFO - SUITE-END | took 1169s 04:50:24 INFO - Completed test suite (00:19:29) 04:50:24 INFO - javascript benchmark 04:50:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3411.4712219605963, 3485.3377858206923, 3506.4039113350736, 3510.666486683764, 3565.5364802575677, 5731.426857314266, 5750.568707346944, 5756.13657951307, 5767.221425107609, 5773.249477567915, 5717.857053573663, 5756.366799652035, 5768.192453056565, 5769.884602307951, 5776.133579963008, 5102.36220472441, 5138.5375316221625, 5146.433892271847, 5179.041877252702, 5272.947270527303, 5110.0, 5128.512791284822, 5131.717755523445, 5137.2037334213255, 5141.15170996785, 5180.3006594109875, 5224.425313215544, 5233.685978841282, 5238.8690282742855, 5271.103912334912, 4634.55971682693, 5165.38015438148, 5166.095933211711, 5183.714895680736, 5243.292155558989, 5116.258142569344, 5190.480951904817, 5202.08963431398, 5221.321228240343, 5247.344081989751, 5639.887202255932, 5649.265595472562, 5707.229524014247, 5716.857078573069, 5744.310682718059, 6142.038385241494, 6248.468880145193, 6290.87418251637, 6304.085907543426, 6307.148534947815, 6115.9694201529155, 6207.099970504251, 6247.718852651642, 6262.561620686553, 6267.091271765614, 5801.448862358154, 5804.2744656917885, 5827.067669172912, 5851.970740146272, 5882.6764527950945, 6147.108669243024, 6236.688165591702, 6238.844028899314, 6251.093591429218, 6266.749330026839, 6044.063170208625, 6130.856783744145, 6134.2332208473945, 6151.231096112986, 6152.046432802923, 5524.696141712245, 5982.312034116046, 6176.042713379434, 6191.628502289877, 6198.690065496798, 6026.9095963560585, 6078.787242446493, 6118.868009418272, 6144.846378840565, 6162.26052873658, 6130.673316708229, 6135.0797380392405, 6138.048602466625, 6157.815265542041, 6161.383861613803, 4812.663113582085, 4828.082664293773, 4830.106430310404, 4865.927011094836, 4868.707877527359, 4369.563043695605, 4381.496127945264, 4392.05570802279, 4402.119576084796, 4488.102379524108, 5545.473180047889, 5558.666480011212, 5590.0, 5613.0, 5618.438156184349, 6159.3224745277985, 6170.413810687977, 6181.721822517998, 6193.597416167935, 6199.411055949678, 5547.5007249346945, 5595.21666966625, 5617.494425501724, 5619.522340601013, 5708.486236238676, 5363.088274993261, 5368.033753924253, 5372.623916325898, 5385.057614917374, 5393.029254734185, 6221.96889015552, 6252.374762523711, 6256.749730010749, 6266.655333956676, 6273.65494897785, 6154.446099851035, 6156.891759483336, 6162.969185154225, 6215.036669316263, 6237.96881015592, 6263.123162757127, 6267.874642507033, 6269.0, 6284.4344009039405, 6290.874182516415, 6199.442050215503, 6220.875582488235, 6247.12540244357, 6257.7184026718, 6284.685765711604, 6075.513958883191, 6145.709401025834, 6155.261368635793, 6221.96889015552, 6238.095476156022, 6022.668753218615, 6058.848528786815, 6058.878822423439, 6060.484858787053, 6083.482903953213, 5896.321922978741, 6055.333913269625, 6071.969640151771, 6091.116788065794, 6098.0852872069545, 5866.67733274674, 5872.0, 5912.320083190488, 6011.819645410645, 6021.855034588102, 5571.415001424792, 5577.525910297508, 5595.272614560081, 5599.328080630187, 5707.429257074259, 5508.504234618904, 5511.255980442589, 5516.751746171512, 5522.751476183501, 5571.972140139435], "unit": "score", "name": "dojo.html", "value": 5650.779460485861}, {"lowerIsBetter": false, "replicates": [487.84145152825295, 586.0974099886175, 598.3298705449897, 668.6593380272554, 669.4075742967473, 30344.544831827512, 40685.982850428714, 41090.9727256818, 41130.0, 41297.58702412971, 43262.35106473405, 43747.0, 43787.56212437883, 43793.562064379345, 43795.56204437955, 9407.106324899141, 9612.134907858292, 9614.663486777965, 9615.375000624972, 9620.326577139604, 9503.952480237624, 9674.516274186297, 9674.806503869919, 9681.903180968171, 9717.902820971805, 9627.422354658744, 9863.013698630151, 9866.703998880044, 9877.111060004598, 9881.16010139139, 9711.75720606987, 9747.561359738793, 9845.261605379625, 9849.458279794608, 9863.506824658738, 9507.714768556885, 9653.082957119133, 9661.323707340489, 9662.855057174149, 9689.176420004309, 42112.0, 42431.36352954708, 42443.151136977394, 42506.78746606278, 42529.0, 3486.3550243205086, 3488.476728490721, 3489.371913055674, 3689.5019172411917, 3910.843566257346, 29569.96505122332, 29578.0, 29974.400511989857, 30172.849135754182, 30398.93603723859, 2752.0367871244903, 2759.9724002759917, 2785.48191235777, 2829.6887342392524, 2832.122042166914, 466769.0, 469983.6500817508, 472846.0, 473012.63493682654, 473995.0, 67963.0, 68194.0, 68241.31758682449, 68280.0, 68573.65713171402, 73199.63400182965, 73556.89664655035, 77770.0, 80176.59911700404, 80341.0, 17850.375236866646, 17990.37033703814, 18224.179911903997, 18322.45032648996, 18381.17284722164, 11541.826872596917, 13701.520446784314, 13787.655308617164, 13807.0, 13809.102408343426, 22426.215082472034, 22745.431364216027, 22755.544889102126, 22795.974181161557, 22827.31518054428, 8197.754067377988, 8872.423292485968, 9323.533823308942, 9345.439273643604, 9354.65067036028, 901.9954900225457, 986.4032260482448, 1009.1170226052204, 1042.95828166874, 1092.9289596176222, 29701.85149074284, 30339.544906826417, 30620.0, 30710.846445767627, 30827.229319267197, 1179.5432640688744, 1180.4392913366255, 1195.6592371174174, 1237.832892559511, 1244.9066320025854, 13172.736545269041, 15217.543473695807, 15511.0, 15538.145402002772, 15968.600784980235, 479461.0, 540588.0, 545232.0, 545359.0, 550221.0, 62491.687541562, 63121.68439157867, 63242.0513692295, 63246.0, 63272.0, 1466.5820241231197, 1518.9392424303126, 1553.5882991007393, 1586.6588683433117, 1656.98428859477, 24545.0, 29332.12003639894, 29488.705112949454, 29636.0, 30361.240968975068, 48112.75943620259, 48660.0, 49146.508534914195, 49193.52419427423, 49321.753391232814, 331194.0, 331665.0, 332621.0, 334771.0, 337453.3127334348, 29627.555586666647, 33845.323093537496, 34129.65870341265, 34888.47667284942, 35778.821105894305, 35931.64068359283, 43213.351799723656, 43554.56445435505, 43712.12575748531, 44535.0, 36175.0, 36252.63747362493, 36504.45243321406, 36569.634303656625, 36906.44640330451, 36098.61436026704, 36182.819085904404, 36425.81787091154, 36648.45027324539, 36995.63004369922, 36400.0, 36633.717819876176, 37005.0, 37708.0, 38183.809080954416, 32235.838820805748, 33370.165745856546, 33454.66545334516, 33696.989090327326, 33844.83077584596, 24530.0, 24887.50224995526, 25182.370440739127, 25183.61497581712, 25438.74561254438], "unit": "score", "name": "ext.html", "value": 20266.602349503504}, {"lowerIsBetter": false, "replicates": [354.48422545196735, 374.50939269556875, 384.5558380071017, 385.45458176679983, 391.6905644540813, 20068.69896951545, 20552.38342849715, 20900.954952252403, 21030.57938841222, 21517.56964860702, 20351.69472457914, 20674.586508269826, 21015.2644657437, 21026.0, 21223.257185998493, 5861.85345366365, 6052.818415447521, 6062.9090563641585, 6083.17877086592, 6099.146119543268, 4115.73247738896, 4298.96824762058, 5735.713214339287, 5785.363610002896, 6016.7292471838655, 6018.458338749511, 6021.638701677914, 6030.608010479326, 6044.486218671418, 6047.244094488189, 5833.300003999505, 5915.970420147914, 5941.108833674962, 6024.759009639587, 6030.065339872327, 5726.914096288559, 5728.8854222915725, 5737.110747834093, 5739.110437882135, 5744.827655170351, 13666.0, 17565.736513952303, 20712.792872071237, 25277.862496187532, 25451.87274063618, 2038.8878611676353, 2335.1943579465055, 2362.3149286706835, 2497.475530138673, 2804.1026871400954, 9762.707118786448, 9820.0, 9860.30977831552, 10212.0, 10308.175345972304, 1764.1267572551542, 1777.7333399990105, 1836.7520384748022, 1839.1539891649857, 1880.0881572437356, 102565.4871725644, 105289.94710052955, 105300.0, 107892.0, 111262.0, 9921.0, 11053.0, 11250.437478126061, 11490.942545287387, 11610.24533405326, 14181.574552763434, 14400.279986000867, 14401.351939162763, 14439.35022923971, 14441.205733684748, 8779.648814047367, 8800.075992020873, 8805.559722013873, 8841.60212790413, 8877.822443551093, 8948.821023579492, 8962.775930601787, 8979.910200897908, 9045.276377889753, 9071.002189759252, 4501.909961800811, 4520.570545798144, 4523.185826551186, 4523.253663145544, 4575.725456472623, 1532.0424734540911, 1761.9377493251845, 1795.2370242646823, 1907.7138429235727, 2001.319551352518, 938.1790932933683, 978.7797745507346, 980.9509524523745, 1006.8338724110587, 1012.3015119567475, 19437.611247775247, 19846.404607861787, 19926.60146797056, 19945.902975336496, 19999.400017999484, 926.346925417579, 976.8730065091635, 999.5002498750624, 1011.2011510906301, 1036.528379587286, 7540.811479712941, 10817.18871084656, 10853.620123295646, 10864.185186111074, 10946.835797463045, 556322.2183889055, 562074.0, 566556.0, 615172.0, 689768.0, 125721.0, 129163.0, 131450.4495060676, 131644.0, 132843.33578332048, 1040.6887321974366, 1092.9289596176222, 1102.1953973599157, 1102.795982743195, 1105.9778804423865, 17716.734248986533, 17770.0, 17822.46532604024, 18312.084395780425, 18495.815041849946, 50877.745611271705, 51926.480735193676, 52338.47661523336, 52787.0, 53055.734721326146, 70349.0, 70781.0, 71116.93324600032, 73724.52550949059, 73740.0, 7774.30031297186, 7921.643526041226, 7968.083670377749, 8022.8395432089865, 8309.210624990616, 8891.19979201875, 11972.58095966637, 12190.634280971584, 12999.740005199654, 13031.543895963596, 10828.24202305831, 10988.560457581607, 11011.61459348919, 11621.593244236412, 11623.709407264885, 10844.891551084389, 10909.18181136402, 11371.54513819438, 11454.255473394203, 11734.589289374831, 10249.487525623837, 10378.0, 10411.791764164524, 10945.0, 11356.205065645325, 8086.676532938852, 8131.79670508242, 8174.427790054639, 8323.95838020806, 8514.74455766328, 10276.126529245097, 10952.726181845199, 11848.585299514476, 11854.94072529632, 11858.525658973544], "unit": "score", "name": "jquery.html", "value": 9471.045811273072}, {"lowerIsBetter": false, "replicates": [1478.1648368671706, 1603.0942517477624, 1624.122973594259, 1945.7275981362611, 2057.7310625518635, 8291.378146639003, 8882.200601945831, 9024.774380640487, 9025.18773310402, 9033.72898813035, 8776.254018408443, 9018.14327638875, 9049.0, 9052.954735226314, 9053.95473022634, 2257.7177852768405, 2511.3972646564785, 2513.40934880303, 2553.6552565403617, 2557.514072326261, 2560.4239046214548, 2606.7653911147995, 2609.386794103384, 2610.543154947886, 2614.594737815632, 2483.9751602484016, 2509.3475696318906, 2513.761192686701, 2514.107491840398, 2517.1567524879188, 2512.309114993382, 2530.5318516074494, 2569.9357516062246, 2570.370259286487, 2571.614257861317, 2539.987300063506, 2566.384067823709, 2578.071894118116, 2586.069015154562, 2591.0, 8846.469211847245, 8913.420627659181, 8916.73249802507, 8921.420107693044, 8963.058878817761, 5520.365158006841, 5710.65736055838, 5720.542356611461, 5734.225879506255, 5750.108733146369, 3579.4093974494176, 3644.2711457708565, 3741.382671859167, 3752.887413377603, 3754.042719106638, 1066.664000839733, 1074.2641290715885, 1085.5549224817867, 1100.0374672161859, 1121.9846039334416, 11953.163278570504, 12140.575079872251, 12154.5745898894, 12155.817662734979, 12182.086343524272, 4866.562009419169, 4887.608991280689, 4988.451270360258, 4997.775100120431, 5004.899902001939, 10510.316829406063, 10529.631462898915, 10531.736706582244, 10545.420001899816, 10571.788564228673, 5552.305961754781, 5599.776008959678, 5603.075492543685, 5619.606627536115, 5621.381648018714, 4552.635789136861, 4710.646701497401, 4895.657303988686, 5049.8737531562, 5051.646384753032, 3596.1549035976523, 4060.7381273563324, 4107.424960505532, 4107.630313271819, 4165.479315085614, 1178.7878181927335, 1361.7140400515805, 1384.197165643924, 1391.0679844504207, 1391.3391139208795, 1131.7000994736402, 1398.4336343780785, 1448.0153495622867, 1452.832924213729, 1457.358762144653, 8147.266745992889, 8293.95853020731, 8296.626651800683, 8346.958265208757, 8362.749117526484, 1246.9750604987848, 1251.6495381293253, 1252.9686757830946, 1256.723520825417, 1271.053065466215, 6355.968220158869, 6385.457236134887, 6441.3880681335195, 6459.224893012869, 6468.902966455507, 10797.62208322705, 11155.995960363607, 11288.435578221057, 11307.038901693448, 11333.773324533464, 10650.520726567169, 11042.392668403316, 11056.557737690564, 11068.335899846035, 11104.944475277573, 8613.397062205586, 8694.08712085222, 8743.0, 8752.606132723915, 8758.12418758119, 8104.554249516334, 8142.7557173284895, 8143.104258531675, 8238.83522329562, 8239.299659528768, 9014.86477702848, 9355.111264429868, 9524.571394287135, 9871.753206169617, 9999.35004224723, 8719.12808719123, 9307.11582399671, 9492.335536512377, 9809.019098090133, 9814.06766357195, 6649.335066493312, 7301.233370496021, 7424.925750742639, 7545.622718864144, 7566.697332106654, 8107.22981316774, 8109.3917956152345, 8165.591720414074, 8176.182381761776, 8201.13888041771, 7321.5607063574225, 7411.888821667787, 7476.289752473506, 7556.8488630228185, 7641.197674244347, 7314.853702926018, 7330.816729581803, 7365.7790266292095, 7432.814179645552, 7432.851342973218, 7930.325922296669, 7940.325072368682, 7977.521348719096, 7985.72049978248, 7993.92006079955, 5878.794242201502, 7166.677499512431, 7215.963920180366, 7216.134063912364, 7223.566586004857, 7225.060742103493, 7300.890486642811, 7301.780946571611, 7304.744333948286, 7305.415566754753], "unit": "score", "name": "mootools.html", "value": 5084.563292781023}, {"lowerIsBetter": false, "replicates": [336.6700633379288, 345.79598037158087, 347.5586005772667, 348.5083841159836, 353.468030613926, 6585.934140658592, 6670.166229221348, 6681.899771503426, 6687.531872768908, 6705.195376554808, 5297.258383826267, 6602.47180225582, 6606.372394622503, 6608.966955165218, 6616.106825578546, 3954.604539546037, 4698.248280275139, 4803.5676789088975, 4940.184869496528, 4940.827071052514, 4887.7311747853855, 4914.410270767513, 4917.2869933859565, 4924.679895806761, 4934.383202099738, 4875.658703890729, 4879.365682461293, 4885.60915126789, 4896.510348965093, 4900.828471003516, 4628.629709623239, 4725.669203155763, 4863.586595139417, 4878.43897951733, 4929.704217746911, 4248.9787551062045, 4250.724782565218, 4263.829446822124, 4264.402983582301, 4270.871873843789, 6349.936500634981, 6353.904691429632, 6357.745690172434, 6364.204474440695, 6378.808635740936, 5815.9709201453725, 5816.651000939914, 5818.243628328332, 5821.970890145522, 5823.79616713413, 6701.832454188636, 7043.9647801761175, 7051.788446346618, 7055.717771289143, 7065.399441047466, 4591.701539399928, 4613.215753321943, 4615.630749540029, 4615.676902616819, 4618.630509559227, 7376.520526165782, 7482.0, 7489.625518724097, 7491.438142139307, 7494.737684181027, 4703.835365762182, 4716.929246061243, 4727.125481785881, 4727.527247275314, 4731.124741922756, 4774.427068751772, 4774.498677638798, 4778.450478194983, 4785.95214047862, 4787.640926930424, 5527.309086364205, 5739.081746920556, 5741.655500669973, 5750.913736293959, 5759.884802303931, 7034.40207582351, 7049.929500704928, 7060.364567188978, 7062.3996960258955, 7085.291470852977, 1612.7258366077795, 1664.25108701085, 1667.2330727865076, 1667.374734474572, 1668.1575804218792, 992.2012779712256, 1043.3531210649294, 1051.952662130206, 1052.6263176572359, 1083.3878858445112, 6388.041793730978, 6397.872042559123, 6401.007843784221, 6465.1595292611655, 6472.967635161888, 7189.065421495172, 7190.316919892602, 7193.820154496179, 7218.783436496913, 7244.782656520313, 6330.0821380899515, 6440.903386449207, 6454.9677251614385, 6467.51493637979, 6470.708818103197, 2853.5434330507433, 2902.811317264371, 3878.5261600591593, 3881.4177873318663, 3956.4263181838473, 6823.590584564942, 7109.075820143348, 7287.0, 7585.848283034308, 7594.6202689866395, 7503.0, 7526.849463010709, 7536.284053014955, 7539.32146106853, 7542.773716788505, 7585.810354741176, 7587.089549254015, 7596.202398748273, 7601.923980760122, 7624.0, 5897.469227769349, 6996.965015174892, 7222.0, 7279.980802687734, 7294.598797066213, 7121.750738724119, 7163.964180179066, 7171.318724721144, 7172.892406613801, 7181.497295189287, 7480.663370148465, 7537.623118843926, 7554.508956917783, 7563.092428908566, 7587.62061896914, 4724.196886529367, 4735.076660051394, 4738.691985020962, 4745.027269409832, 4749.572538471554, 6963.7562685305775, 7219.891701624375, 7259.455540834564, 7262.818429539092, 7263.745768898063, 6952.5480843745, 6959.4432445405255, 6987.056747339044, 6990.335918087773, 7007.019017337475, 6931.0, 6969.860602788017, 6972.26791186918, 6985.0220969065385, 6990.685419156252, 6826.829329266808, 6840.931590684029, 6841.281665425058, 6847.589144651337, 6847.691853866489, 6657.3342665733035, 6974.349747096274, 6980.092587963735, 6985.755498557526, 7016.22821489646, 6606.6036037837885, 6784.660766961533, 6817.147856517935, 6849.863002739818, 6874.518783685294], "unit": "score", "name": "prototype.html", "value": 5145.599009555264}, {"lowerIsBetter": false, "replicates": [714.9571025738456, 727.3235890621721, 763.736510903738, 774.8179177893194, 780.5979920341025, 15046.699066018673, 15287.464938727147, 15339.616509587238, 15345.462908798194, 15370.69258614827, 14209.502667406643, 14396.784048239284, 14400.423983040666, 14400.71198576028, 14408.0, 778.8286576953076, 796.6016991504248, 940.7130825098342, 972.5526257921365, 973.6251543155901, 962.7689354554891, 971.0920289529278, 973.6932866147141, 975.6975337645317, 976.5068640336656, 1025.52825700178, 1029.727122312584, 1032.2309879140014, 1035.0425856083132, 1037.3516552154904, 825.0, 1007.8185926533221, 1027.121810851726, 1030.1398332392462, 1032.4785983078534, 772.8029352515073, 777.1839568453158, 777.2577188784753, 782.8434313137395, 783.6160281462099, 12513.43689533964, 14822.925885370612, 15319.540413787605, 15329.923350383287, 15343.0, 12853.421596028202, 13093.018023648265, 13171.407286672124, 13199.274039927801, 13580.456781728719, 10147.238957078249, 10246.948765256126, 10428.530716117793, 10576.10103141234, 10985.066269367033, 3510.2452972610754, 3524.1718196223865, 3571.0, 3575.463680447928, 3660.5790334111653, 79359.60320198363, 79402.0, 79885.0, 82297.0, 82525.58737206335, 9215.907840921505, 9331.0, 9512.1439070484, 9517.286203534763, 9913.553890074963, 23122.3063308101, 23145.18991835278, 23178.65232021521, 23188.53622927532, 23222.53554928892, 7699.884501732478, 7705.267999540035, 7721.266479684421, 7878.290953814069, 7930.6431210595665, 3627.764195327295, 4249.660027197817, 4261.169072030924, 4261.424707664488, 4548.363229147922, 3687.6865466435115, 3718.2563487302646, 3722.1439069014277, 3729.5524537055726, 3735.2716220336774, 601.6480358989952, 617.1853153836981, 637.528229110455, 660.9471242300605, 669.1334721535526, 537.6719502828008, 557.1948534367799, 574.9223854779635, 610.8656095658948, 611.4924612571555, 8016.158303378178, 8051.798705032304, 8254.0507841599, 8289.378296627778, 8293.377996650275, 549.8570371703386, 584.7982446056104, 593.7209511529575, 609.2932198649634, 620.0389396435507, 3882.2429626222615, 3922.86269980555, 3979.820908059144, 4019.457373254632, 4132.8140233689555, 71675.64162179155, 91956.0, 93887.0, 97494.0, 98360.0, 76648.61675691698, 77703.61148194222, 77765.0, 79176.20823791689, 81019.0, 55368.72315638396, 71208.0, 71479.64260178666, 72526.0, 74197.0, 7982.9600851995365, 8015.318697910743, 8073.19268073188, 8096.579557260383, 8097.716579919675, 47904.04191916212, 48277.27584086312, 49051.75474122607, 49845.0, 50198.7490062562, 64484.0, 68763.3123668777, 69689.65155174394, 70579.64710176416, 70999.64500177465, 10454.11140053104, 10685.626003089854, 10712.678619641423, 10865.18511111654, 11113.333200008025, 11156.49795759209, 11218.326900385677, 11463.968242857849, 11528.308301501937, 12018.939905300418, 10365.689029329133, 10584.312019718695, 10723.517441714985, 10742.033217010507, 10876.945615271872, 10439.53022114022, 10476.94761526218, 10497.632582859564, 10618.73453163677, 10624.628138015132, 10317.0, 10479.68560943173, 10516.102844357552, 10659.946700266759, 10726.51730672106, 8809.526166272473, 8872.866906996529, 9174.174324310845, 9264.907350926675, 9301.930278018115, 7899.526028438312, 8997.546214623802, 9053.230475409664, 9089.31830112731, 9358.490566037735], "unit": "score", "name": "yui.html", "value": 6944.816121443612}], "name": "dromaeo_css", "value": 7628.610047196412}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 108.0, 112.0, 111.0, 112.0, 110.0, 120.0, 111.0, 111.0, 113.0], "unit": "score", "name": "ai-astar", "value": 112.1}, {"lowerIsBetter": true, "replicates": [154.0, 122.0, 140.0, 182.0, 143.0, 138.0, 137.0, 188.0, 151.0, 114.0], "unit": "score", "name": "audio-beat-detection", "value": 146.9}, {"lowerIsBetter": true, "replicates": [185.0, 184.0, 216.0, 261.0, 189.0, 186.0, 185.0, 186.0, 185.0, 184.0], "unit": "score", "name": "audio-dft", "value": 196.1}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 74.0, 116.0, 55.0, 58.0, 57.0, 57.0, 122.0, 56.0], "unit": "score", "name": "audio-fft", "value": 74.2}, {"lowerIsBetter": true, "replicates": [91.0, 95.0, 130.0, 80.0, 62.0, 75.0, 137.0, 111.0, 114.0, 153.0], "unit": "score", "name": "audio-oscillator", "value": 104.8}, {"lowerIsBetter": true, "replicates": [86.0, 84.0, 86.0, 85.0, 85.0, 83.0, 87.0, 83.0, 86.0, 82.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 84.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 102.0, 147.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 99.1}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 87.0, 83.0, 88.0, 87.0, 95.0, 134.0, 87.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 91.9}, {"lowerIsBetter": true, "replicates": [55.0, 59.0, 58.0, 58.0, 56.0, 58.0, 57.0, 67.0, 56.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 57.7}, {"lowerIsBetter": true, "replicates": [63.0, 59.0, 90.0, 104.0, 73.0, 72.0, 66.0, 67.0, 67.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 73.0}, {"lowerIsBetter": true, "replicates": [80.0, 82.0, 80.0, 82.0, 80.0, 75.0, 76.0, 79.0, 81.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.2}, {"lowerIsBetter": true, "replicates": [117.0, 132.0, 119.0, 120.0, 130.0, 116.0, 132.0, 127.0, 125.0, 110.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.8}, {"lowerIsBetter": true, "replicates": [132.0, 122.0, 129.0, 133.0, 133.0, 123.0, 135.0, 132.0, 131.0, 130.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 130.0}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 66.0, 66.0, 67.0, 64.0, 66.0, 65.0, 65.0, 82.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.3}], "name": "kraken", "value": 1440.8}]} 04:50:25 INFO - Return code: 0 04:50:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:50:25 INFO - # TBPL SUCCESS # 04:50:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:25 INFO - 2016-04-20 04:50:25.100 screenresolution[1674:9535] starting screenresolution argv=screenresolution get 04:50:25 INFO - 2016-04-20 04:50:25.103 screenresolution[1674:9535] Display 0: 1600x1200x32@60 04:50:25 INFO - 2016-04-20 04:50:25.109 screenresolution[1675:9538] starting screenresolution argv=screenresolution list 04:50:25 INFO - Available Modes on Display 0 04:50:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:25 INFO - Intel Iris: 04:50:25 INFO - Chipset Model: Intel Iris 04:50:25 INFO - Type: GPU 04:50:25 INFO - Bus: Built-In 04:50:25 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:25 INFO - Vendor: Intel (0x8086) 04:50:25 INFO - Device ID: 0x0a2e 04:50:25 INFO - Revision ID: 0x0009 04:50:25 INFO - Displays: 04:50:25 INFO - Display: 04:50:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:25 INFO - Display Serial Number: 1600x1200 60 04:50:25 INFO - Main Display: Yes 04:50:25 INFO - Mirror: Off 04:50:25 INFO - Online: Yes 04:50:25 INFO - Rotation: Supported 04:50:25 INFO - Return code: 0 04:50:25 INFO - Running post-action listener: _resource_record_post_action 04:50:25 INFO - Running post-run listener: _resource_record_post_run 04:50:26 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 37299712; Write bytes: 663683584; Read time: 393; Write time: 1996 04:50:26 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 360706560; Write bytes: 355337728; Read time: 27929; Write time: 773 04:50:26 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 35476992; Write bytes: 298216448; Read time: 371; Write time: 1191 04:50:26 INFO - Running post-run listener: _upload_blobber_files 04:50:26 INFO - Blob upload gear active. 04:50:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:26 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 04:50:26 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'] 04:50:26 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 04:50:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:27 INFO - (blobuploader) - INFO - Done attempting. 04:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:28 INFO - (blobuploader) - INFO - Done attempting. 04:50:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:28 INFO - Return code: 0 04:50:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1442dda239e3e279bf7811e53f4bc9afbcc930b04e74e8cdec130c299ed65a4b50f39c790fc28bf20dfb5c9f9c9ab1d2b0007ee6f81ab0d90521978044ec0b75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9066379224c760231350c028e09aa8b7fc5f825c9b5bff0bc3264acfdb4678674c0b293886f2a2fe11fd63c4f249591060668413a3b472880579d15bcfddae4"} 04:50:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:28 INFO - Contents: 04:50:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1442dda239e3e279bf7811e53f4bc9afbcc930b04e74e8cdec130c299ed65a4b50f39c790fc28bf20dfb5c9f9c9ab1d2b0007ee6f81ab0d90521978044ec0b75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9066379224c760231350c028e09aa8b7fc5f825c9b5bff0bc3264acfdb4678674c0b293886f2a2fe11fd63c4f249591060668413a3b472880579d15bcfddae4"} 04:50:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:28 INFO - Copying logs to upload dir... 04:50:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.444722 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-20 04:50:28.861493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-04-20 04:50:28.865064) ========= 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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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/1442dda239e3e279bf7811e53f4bc9afbcc930b04e74e8cdec130c299ed65a4b50f39c790fc28bf20dfb5c9f9c9ab1d2b0007ee6f81ab0d90521978044ec0b75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9066379224c760231350c028e09aa8b7fc5f825c9b5bff0bc3264acfdb4678674c0b293886f2a2fe11fd63c4f249591060668413a3b472880579d15bcfddae4"} build_url:https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/K8qrMMqOT6WFhBknzbpsow/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1442dda239e3e279bf7811e53f4bc9afbcc930b04e74e8cdec130c299ed65a4b50f39c790fc28bf20dfb5c9f9c9ab1d2b0007ee6f81ab0d90521978044ec0b75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9066379224c760231350c028e09aa8b7fc5f825c9b5bff0bc3264acfdb4678674c0b293886f2a2fe11fd63c4f249591060668413a3b472880579d15bcfddae4"}' ========= master_lag: 9.20 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-04-20 04:50:38.079783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 04:50:38.080105) ========= 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.TRCiM6nJXt/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.ZeVW7wEsVh/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.005180 ========= master_lag: 1.37 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 04:50:39.454503) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 04:50:39.454864) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 04:50:39.820051) ========= ========= Total master_lag: 11.86 =========