builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1474540509.62 results: success (0) buildid: 20160922004007 builduid: 55c35e7f67a14d469c38eaefece672e7 revision: e77a60c374edda1097cbcded033e4de8a7c4ef00 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.619609) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.620911) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.621464) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.643232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.643538) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 03:35:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (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% 114M=0s 2016-09-22 03:35:10 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090596 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.756350) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.756732) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020427 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:09.820562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 03:35:09.820944) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e77a60c374edda1097cbcded033e4de8a7c4ef00 --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 e77a60c374edda1097cbcded033e4de8a7c4ef00 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 03:35:10,206 truncating revision to first 12 chars 2016-09-22 03:35:10,207 Setting DEBUG logging. 2016-09-22 03:35:10,207 attempt 1/10 2016-09-22 03:35:10,207 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e77a60c374ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 03:35:11,607 unpacking tar archive at: mozilla-aurora-e77a60c374ed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.730203 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-22 03:35:11.569263) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:11.570082) ========= script_repo_revision: e77a60c374edda1097cbcded033e4de8a7c4ef00 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:11.571142) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:11.571833) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 03:35:11.583394) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-22 03:35:11.583770) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_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/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:35:12 INFO - MultiFileLogger online at 20160922 03:35:12 in /builds/slave/test 03:35:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:35:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:12 INFO - {'append_to_log': False, 03:35:12 INFO - 'base_work_dir': '/builds/slave/test', 03:35:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:35:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:12 INFO - 'branch': 'Mozilla-Aurora', 03:35:12 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:12 INFO - 'config_files': ('talos/linux_config.py',), 03:35:12 INFO - 'default_actions': ('clobber', 03:35:12 INFO - 'read-buildbot-config', 03:35:12 INFO - 'download-and-extract', 03:35:12 INFO - 'populate-webroot', 03:35:12 INFO - 'create-virtualenv', 03:35:12 INFO - 'install', 03:35:12 INFO - 'run-tests'), 03:35:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:12 INFO - 'download_minidump_stackwalk': True, 03:35:12 INFO - 'download_symbols': 'ondemand', 03:35:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:12 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:12 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:12 INFO - 'installer_path': 'installer.exe', 03:35:12 INFO - 'log_level': 'info', 03:35:12 INFO - 'log_name': 'talos', 03:35:12 INFO - 'log_to_console': True, 03:35:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:35:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:35:12 INFO - 'opt_config_files': (), 03:35:12 INFO - 'pip_index': False, 03:35:12 INFO - 'sps_profile': False, 03:35:12 INFO - 'sps_profile_interval': 0, 03:35:12 INFO - 'suite': 'dromaeojs', 03:35:12 INFO - 'system_bits': '32', 03:35:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:12 INFO - 'title': 'talos-linux64-ix-011', 03:35:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:12 INFO - 'use_talos_json': True, 03:35:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:12 INFO - 'work_dir': 'build'} 03:35:12 INFO - [mozharness: 2016-09-22 10:35:12.067638Z] Running clobber step. 03:35:12 INFO - Running pre-action listener: _resource_record_pre_action 03:35:12 INFO - Running main action method: clobber 03:35:12 INFO - rmtree: /builds/slave/test/build 03:35:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:13 INFO - Running post-action listener: _resource_record_post_action 03:35:13 INFO - [mozharness: 2016-09-22 10:35:13.038707Z] Finished clobber step (success) 03:35:13 INFO - [mozharness: 2016-09-22 10:35:13.038806Z] Running read-buildbot-config step. 03:35:13 INFO - Running pre-action listener: _resource_record_pre_action 03:35:13 INFO - Running main action method: read_buildbot_config 03:35:13 INFO - Using buildbot properties: 03:35:13 INFO - { 03:35:13 INFO - "project": "", 03:35:13 INFO - "product": "firefox", 03:35:13 INFO - "script_repo_revision": "production", 03:35:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:35:13 INFO - "repository": "", 03:35:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:35:13 INFO - "buildid": "20160922004007", 03:35:13 INFO - "stage_platform": "linux64-pgo", 03:35:13 INFO - "basedir": "/builds/slave/test", 03:35:13 INFO - "nightly_build": "True", 03:35:13 INFO - "buildnumber": 778, 03:35:13 INFO - "slavename": "talos-linux64-ix-011", 03:35:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:35:13 INFO - "platform": "ubuntu64_hw", 03:35:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:35:13 INFO - "branch": "mozilla-aurora", 03:35:13 INFO - "repo_path": "releases/mozilla-aurora", 03:35:13 INFO - "revision": "e77a60c374edda1097cbcded033e4de8a7c4ef00", 03:35:13 INFO - "pgo_build": "True", 03:35:13 INFO - "builduid": "55c35e7f67a14d469c38eaefece672e7", 03:35:13 INFO - "slavebuilddir": "test" 03:35:13 INFO - } 03:35:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:35:13 INFO - Running post-action listener: _resource_record_post_action 03:35:13 INFO - [mozharness: 2016-09-22 10:35:13.041330Z] Finished read-buildbot-config step (success) 03:35:13 INFO - [mozharness: 2016-09-22 10:35:13.041421Z] Running download-and-extract step. 03:35:13 INFO - Running pre-action listener: _resource_record_pre_action 03:35:13 INFO - Running main action method: download_and_extract 03:35:13 INFO - mkdir: /builds/slave/test/build/tests 03:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:13 INFO - trying https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:35:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:35:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:35:13 INFO - Downloaded 1361 bytes. 03:35:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:35:13 INFO - Using the following test package requirements: 03:35:13 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:35:13 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:35:13 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:35:13 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'jsshell-linux-x86_64.zip'], 03:35:13 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:35:13 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:35:13 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:35:13 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:35:13 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:35:13 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:35:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:35:13 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:35:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:35:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:35:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:35:14 INFO - Expected file size: 21894193 03:35:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:35:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:35:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:35:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:35:19 INFO - Expected file size: 21894193 03:35:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:35:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:35:24 INFO - Expected file size: 14414059 03:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:25 INFO - trying https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:35:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:35:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:35:27 INFO - Downloaded 63954852 bytes. 03:35:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:35:27 INFO - mkdir: /builds/slave/test/properties 03:35:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:35:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:35:27 INFO - Contents: 03:35:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:35:28 INFO - Running post-action listener: _resource_record_post_action 03:35:28 INFO - Running post-action listener: set_extra_try_arguments 03:35:28 INFO - [mozharness: 2016-09-22 10:35:28.185035Z] Finished download-and-extract step (success) 03:35:28 INFO - [mozharness: 2016-09-22 10:35:28.185301Z] Running populate-webroot step. 03:35:28 INFO - Running pre-action listener: _resource_record_pre_action 03:35:28 INFO - Running main action method: populate_webroot 03:35:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:35:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:35:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:35:28 INFO - '--tppagecycles', 03:35:28 INFO - '7'], 03:35:28 INFO - 'tests': ['tsvgm']}}, 03:35:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:35:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:35:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:35:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:35:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:35:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:35:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['damp', 'tps']}, 03:35:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'tests': ['damp', 'tps']}, 03:35:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['dromaeo_dom']}, 03:35:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:35:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['basic_compositor_video']}, 03:35:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:35:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['a11yr', 03:35:28 INFO - 'ts_paint', 03:35:28 INFO - 'tpaint', 03:35:28 INFO - 'sessionrestore', 03:35:28 INFO - 'sessionrestore_no_auto_restore', 03:35:28 INFO - 'tabpaint']}, 03:35:28 INFO - 'other-e10s': {'tests': ['a11yr', 03:35:28 INFO - 'ts_paint', 03:35:28 INFO - 'tpaint', 03:35:28 INFO - 'sessionrestore', 03:35:28 INFO - 'sessionrestore_no_auto_restore', 03:35:28 INFO - 'tabpaint']}, 03:35:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:35:28 INFO - 'ts_paint', 03:35:28 INFO - 'tpaint', 03:35:28 INFO - 'sessionrestore', 03:35:28 INFO - 'sessionrestore_no_auto_restore', 03:35:28 INFO - 'tabpaint']}, 03:35:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:35:28 INFO - 'ts_paint', 03:35:28 INFO - 'tpaint', 03:35:28 INFO - 'sessionrestore', 03:35:28 INFO - 'sessionrestore_no_auto_restore', 03:35:28 INFO - 'tabpaint']}, 03:35:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['a11yr', 03:35:28 INFO - 'ts_paint', 03:35:28 INFO - 'tpaint', 03:35:28 INFO - 'sessionrestore', 03:35:28 INFO - 'sessionrestore_no_auto_restore', 03:35:28 INFO - 'tabpaint']}, 03:35:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['a11yr', 03:35:28 INFO - 'ts_paint', 03:35:28 INFO - 'tpaint', 03:35:28 INFO - 'sessionrestore', 03:35:28 INFO - 'sessionrestore_no_auto_restore', 03:35:28 INFO - 'tabpaint']}, 03:35:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['tsvgx', 03:35:28 INFO - 'tsvgr_opacity', 03:35:28 INFO - 'tart', 03:35:28 INFO - 'tscrollx', 03:35:28 INFO - 'cart']}, 03:35:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:35:28 INFO - 'tsvgr_opacity', 03:35:28 INFO - 'tart', 03:35:28 INFO - 'tscrollx', 03:35:28 INFO - 'cart']}, 03:35:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'talos_options': ['--disable-e10s'], 03:35:28 INFO - 'tests': ['tp5o']}, 03:35:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'tests': ['tp5o']}, 03:35:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'talos_options': ['--disable-e10s', 03:35:28 INFO - '--xperf_path', 03:35:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:35:28 INFO - 'tests': ['tp5n']}, 03:35:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:28 INFO - 'talos_options': ['--xperf_path', 03:35:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:35:28 INFO - 'tests': ['tp5n']}}, 03:35:28 INFO - 'talos.zip': {'path': '', 03:35:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:35:28 INFO - Running post-action listener: _resource_record_post_action 03:35:28 INFO - [mozharness: 2016-09-22 10:35:28.206256Z] Finished populate-webroot step (success) 03:35:28 INFO - [mozharness: 2016-09-22 10:35:28.206344Z] Running create-virtualenv step. 03:35:28 INFO - Running pre-action listener: _resource_record_pre_action 03:35:28 INFO - Running main action method: create_virtualenv 03:35:28 INFO - Creating virtualenv /builds/slave/test/build/venv 03:35:28 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 03:35:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:35:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:35:28 INFO - Using real prefix '/usr' 03:35:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:35:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:35:30 INFO - Installing pip.................done. 03:35:30 INFO - Return code: 0 03:35:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:35:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:30 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 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:30 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 03:35:30 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 03:35:30 INFO - Using env: {'DISPLAY': ':0', 03:35:30 INFO - 'HOME': '/home/cltbld', 03:35:30 INFO - 'LANG': 'en_US.UTF-8', 03:35:30 INFO - 'LANGUAGE': 'en_US:en', 03:35:30 INFO - 'LOGNAME': 'cltbld', 03:35:30 INFO - 'MAIL': '/var/mail/cltbld', 03:35:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:30 INFO - 'MOZ_NO_REMOTE': '1', 03:35:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:30 INFO - 'NO_EM_RESTART': '1', 03:35:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:30 INFO - 'PWD': '/builds/slave/test', 03:35:30 INFO - 'SHELL': '/bin/bash', 03:35:30 INFO - 'SHLVL': '1', 03:35:30 INFO - 'TERM': 'linux', 03:35:30 INFO - 'TMOUT': '86400', 03:35:30 INFO - 'USER': 'cltbld', 03:35:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:30 INFO - '_': '/tools/buildbot/bin/python'} 03:35:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:30 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 03:35:30 INFO - Cleaning up... 03:35:31 INFO - Return code: 0 03:35:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:31 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:31 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>=3.1.1'] in /builds/slave/test/build 03:35:31 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>=3.1.1 03:35:31 INFO - Using env: {'DISPLAY': ':0', 03:35:31 INFO - 'HOME': '/home/cltbld', 03:35:31 INFO - 'LANG': 'en_US.UTF-8', 03:35:31 INFO - 'LANGUAGE': 'en_US:en', 03:35:31 INFO - 'LOGNAME': 'cltbld', 03:35:31 INFO - 'MAIL': '/var/mail/cltbld', 03:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:31 INFO - 'MOZ_NO_REMOTE': '1', 03:35:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:31 INFO - 'NO_EM_RESTART': '1', 03:35:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:31 INFO - 'PWD': '/builds/slave/test', 03:35:31 INFO - 'SHELL': '/bin/bash', 03:35:31 INFO - 'SHLVL': '1', 03:35:31 INFO - 'TERM': 'linux', 03:35:31 INFO - 'TMOUT': '86400', 03:35:31 INFO - 'USER': 'cltbld', 03:35:31 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:31 INFO - '_': '/tools/buildbot/bin/python'} 03:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:31 INFO - Downloading/unpacking psutil>=3.1.1 03:35:31 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 03:35:31 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 03:35:31 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 03:35:31 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 03:35:31 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 03:35:31 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 03:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:35:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:35:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:35:33 INFO - Installing collected packages: psutil 03:35:33 INFO - Running setup.py install for psutil 03:35:33 INFO - building 'psutil._psutil_linux' extension 03:35:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:35:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:35:33 INFO - building 'psutil._psutil_posix' extension 03:35:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:35:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:35:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:35:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:35:33 INFO - Successfully installed psutil 03:35:33 INFO - Cleaning up... 03:35:33 INFO - Return code: 0 03:35:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:33 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:33 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.3'] in /builds/slave/test/build 03:35:33 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.3 03:35:33 INFO - Using env: {'DISPLAY': ':0', 03:35:33 INFO - 'HOME': '/home/cltbld', 03:35:33 INFO - 'LANG': 'en_US.UTF-8', 03:35:33 INFO - 'LANGUAGE': 'en_US:en', 03:35:33 INFO - 'LOGNAME': 'cltbld', 03:35:33 INFO - 'MAIL': '/var/mail/cltbld', 03:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:33 INFO - 'MOZ_NO_REMOTE': '1', 03:35:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:33 INFO - 'NO_EM_RESTART': '1', 03:35:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:33 INFO - 'PWD': '/builds/slave/test', 03:35:33 INFO - 'SHELL': '/bin/bash', 03:35:33 INFO - 'SHLVL': '1', 03:35:33 INFO - 'TERM': 'linux', 03:35:33 INFO - 'TMOUT': '86400', 03:35:33 INFO - 'USER': 'cltbld', 03:35:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:33 INFO - '_': '/tools/buildbot/bin/python'} 03:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:35:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:35:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:35:35 INFO - Installing collected packages: mozsystemmonitor 03:35:35 INFO - Running setup.py install for mozsystemmonitor 03:35:36 INFO - Successfully installed mozsystemmonitor 03:35:36 INFO - Cleaning up... 03:35:36 INFO - Return code: 0 03:35:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:36 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:36 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==2.5.1'] in /builds/slave/test/build 03:35:36 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==2.5.1 03:35:36 INFO - Using env: {'DISPLAY': ':0', 03:35:36 INFO - 'HOME': '/home/cltbld', 03:35:36 INFO - 'LANG': 'en_US.UTF-8', 03:35:36 INFO - 'LANGUAGE': 'en_US:en', 03:35:36 INFO - 'LOGNAME': 'cltbld', 03:35:36 INFO - 'MAIL': '/var/mail/cltbld', 03:35:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:36 INFO - 'MOZ_NO_REMOTE': '1', 03:35:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:36 INFO - 'NO_EM_RESTART': '1', 03:35:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:36 INFO - 'PWD': '/builds/slave/test', 03:35:36 INFO - 'SHELL': '/bin/bash', 03:35:36 INFO - 'SHLVL': '1', 03:35:36 INFO - 'TERM': 'linux', 03:35:36 INFO - 'TMOUT': '86400', 03:35:36 INFO - 'USER': 'cltbld', 03:35:36 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:36 INFO - '_': '/tools/buildbot/bin/python'} 03:35:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:36 INFO - Downloading/unpacking jsonschema==2.5.1 03:35:36 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 03:35:36 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 03:35:36 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 03:35:36 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 03:35:36 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 03:35:36 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 03:35:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:35:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:38 INFO - Downloading functools32-3.2.3-2.tar.gz 03:35:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:35:38 INFO - warning: no files found matching '*.txt' 03:35:38 INFO - no previously-included directories found matching 'build' 03:35:38 INFO - no previously-included directories found matching 'dist' 03:35:38 INFO - no previously-included directories found matching '.git*' 03:35:38 INFO - Installing collected packages: jsonschema, functools32 03:35:38 INFO - Running setup.py install for functools32 03:35:38 INFO - warning: no files found matching '*.txt' 03:35:38 INFO - no previously-included directories found matching 'build' 03:35:38 INFO - no previously-included directories found matching 'dist' 03:35:38 INFO - no previously-included directories found matching '.git*' 03:35:38 INFO - Successfully installed jsonschema functools32 03:35:38 INFO - Cleaning up... 03:35:38 INFO - Return code: 0 03:35:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:35:38 INFO - Using env: {'DISPLAY': ':0', 03:35:38 INFO - 'HOME': '/home/cltbld', 03:35:38 INFO - 'LANG': 'en_US.UTF-8', 03:35:38 INFO - 'LANGUAGE': 'en_US:en', 03:35:38 INFO - 'LOGNAME': 'cltbld', 03:35:38 INFO - 'MAIL': '/var/mail/cltbld', 03:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:38 INFO - 'MOZ_NO_REMOTE': '1', 03:35:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:38 INFO - 'NO_EM_RESTART': '1', 03:35:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:38 INFO - 'PWD': '/builds/slave/test', 03:35:38 INFO - 'SHELL': '/bin/bash', 03:35:38 INFO - 'SHLVL': '1', 03:35:38 INFO - 'TERM': 'linux', 03:35:38 INFO - 'TMOUT': '86400', 03:35:38 INFO - 'USER': 'cltbld', 03:35:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:38 INFO - '_': '/tools/buildbot/bin/python'} 03:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:35:38 INFO - Cleaning up... 03:35:38 INFO - Return code: 0 03:35:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:35:38 INFO - Using env: {'DISPLAY': ':0', 03:35:38 INFO - 'HOME': '/home/cltbld', 03:35:38 INFO - 'LANG': 'en_US.UTF-8', 03:35:38 INFO - 'LANGUAGE': 'en_US:en', 03:35:38 INFO - 'LOGNAME': 'cltbld', 03:35:38 INFO - 'MAIL': '/var/mail/cltbld', 03:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:38 INFO - 'MOZ_NO_REMOTE': '1', 03:35:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:38 INFO - 'NO_EM_RESTART': '1', 03:35:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:38 INFO - 'PWD': '/builds/slave/test', 03:35:38 INFO - 'SHELL': '/bin/bash', 03:35:38 INFO - 'SHLVL': '1', 03:35:38 INFO - 'TERM': 'linux', 03:35:38 INFO - 'TMOUT': '86400', 03:35:38 INFO - 'USER': 'cltbld', 03:35:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:38 INFO - '_': '/tools/buildbot/bin/python'} 03:35:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:39 INFO - Downloading/unpacking blobuploader==1.2.4 03:35:39 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 03:35:39 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 03:35:39 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 03:35:39 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 03:35:39 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 03:35:39 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 03:35:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:35:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:35:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:35:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:41 INFO - Downloading docopt-0.6.1.tar.gz 03:35:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:35:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:35:41 INFO - Running setup.py install for blobuploader 03:35:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:35:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:35:41 INFO - Running setup.py install for requests 03:35:42 INFO - Running setup.py install for docopt 03:35:42 INFO - Successfully installed blobuploader requests docopt 03:35:42 INFO - Cleaning up... 03:35:42 INFO - Return code: 0 03:35:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:42 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 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:42 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 03:35:42 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 03:35:42 INFO - Using env: {'DISPLAY': ':0', 03:35:42 INFO - 'HOME': '/home/cltbld', 03:35:42 INFO - 'LANG': 'en_US.UTF-8', 03:35:42 INFO - 'LANGUAGE': 'en_US:en', 03:35:42 INFO - 'LOGNAME': 'cltbld', 03:35:42 INFO - 'MAIL': '/var/mail/cltbld', 03:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:42 INFO - 'MOZ_NO_REMOTE': '1', 03:35:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:42 INFO - 'NO_EM_RESTART': '1', 03:35:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:42 INFO - 'PWD': '/builds/slave/test', 03:35:42 INFO - 'SHELL': '/bin/bash', 03:35:42 INFO - 'SHLVL': '1', 03:35:42 INFO - 'TERM': 'linux', 03:35:42 INFO - 'TMOUT': '86400', 03:35:42 INFO - 'USER': 'cltbld', 03:35:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:42 INFO - '_': '/tools/buildbot/bin/python'} 03:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:35:42 INFO - Running setup.py (path:/tmp/pip-wClW_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:35:42 INFO - Running setup.py (path:/tmp/pip-tK4nJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:35:42 INFO - Running setup.py (path:/tmp/pip-f1onGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:35:42 INFO - Running setup.py (path:/tmp/pip-NXDDCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:35:42 INFO - Running setup.py (path:/tmp/pip-mMAb2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:35:42 INFO - Running setup.py (path:/tmp/pip-AsNukW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:35:43 INFO - Running setup.py (path:/tmp/pip-qK44eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:35:43 INFO - Running setup.py (path:/tmp/pip-iH86cG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:35:43 INFO - Running setup.py (path:/tmp/pip-VNxerF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:35:43 INFO - Running setup.py (path:/tmp/pip-2iaXLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:35:43 INFO - Running setup.py (path:/tmp/pip-ydc0ZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:35:43 INFO - Running setup.py (path:/tmp/pip-v24aMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:35:43 INFO - Running setup.py (path:/tmp/pip-5vCqWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:35:43 INFO - Running setup.py (path:/tmp/pip-68L6kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:43 INFO - Running setup.py (path:/tmp/pip-bhz5jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:35:43 INFO - Running setup.py (path:/tmp/pip-uvA76C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:35:43 INFO - Running setup.py (path:/tmp/pip-oJteKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:35:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:35:43 INFO - Running setup.py install for manifestparser 03:35:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:35:43 INFO - Running setup.py install for mozcrash 03:35:43 INFO - Running setup.py install for mozdebug 03:35:44 INFO - Running setup.py install for mozdevice 03:35:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Running setup.py install for mozfile 03:35:44 INFO - Running setup.py install for mozhttpd 03:35:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Running setup.py install for mozinfo 03:35:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Running setup.py install for mozInstall 03:35:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Running setup.py install for mozleak 03:35:44 INFO - Running setup.py install for mozlog 03:35:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Running setup.py install for moznetwork 03:35:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:35:44 INFO - Running setup.py install for mozprocess 03:35:45 INFO - Running setup.py install for mozprofile 03:35:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:35:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:35:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:35:45 INFO - Running setup.py install for mozrunner 03:35:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:35:45 INFO - Running setup.py install for mozscreenshot 03:35:45 INFO - Running setup.py install for moztest 03:35:45 INFO - Running setup.py install for mozversion 03:35:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:35:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:35:45 INFO - Cleaning up... 03:35:45 INFO - Return code: 0 03:35:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:45 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 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:45 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 03:35:45 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 03:35:45 INFO - Using env: {'DISPLAY': ':0', 03:35:45 INFO - 'HOME': '/home/cltbld', 03:35:45 INFO - 'LANG': 'en_US.UTF-8', 03:35:45 INFO - 'LANGUAGE': 'en_US:en', 03:35:45 INFO - 'LOGNAME': 'cltbld', 03:35:45 INFO - 'MAIL': '/var/mail/cltbld', 03:35:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:45 INFO - 'MOZ_NO_REMOTE': '1', 03:35:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:45 INFO - 'NO_EM_RESTART': '1', 03:35:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:45 INFO - 'PWD': '/builds/slave/test', 03:35:45 INFO - 'SHELL': '/bin/bash', 03:35:45 INFO - 'SHLVL': '1', 03:35:45 INFO - 'TERM': 'linux', 03:35:45 INFO - 'TMOUT': '86400', 03:35:45 INFO - 'USER': 'cltbld', 03:35:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:45 INFO - '_': '/tools/buildbot/bin/python'} 03:35:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:35:45 INFO - Running setup.py (path:/tmp/pip-jR8Dw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:35:45 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)) 03:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:35:45 INFO - Running setup.py (path:/tmp/pip-FmeaKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:35:45 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)) 03:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:35:45 INFO - Running setup.py (path:/tmp/pip-UJBo1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:35:46 INFO - Running setup.py (path:/tmp/pip-tbmvRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:35:46 INFO - Running setup.py (path:/tmp/pip-bnKOKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:35:46 INFO - Running setup.py (path:/tmp/pip-a1gQas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:35:46 INFO - Running setup.py (path:/tmp/pip-eRpjVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:35:46 INFO - Running setup.py (path:/tmp/pip-8pimvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:35:46 INFO - Running setup.py (path:/tmp/pip-ZF1Mgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:35:46 INFO - Running setup.py (path:/tmp/pip-g7PJh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:35:46 INFO - Running setup.py (path:/tmp/pip-CAvOFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:35:46 INFO - Running setup.py (path:/tmp/pip-ikVYqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:35:46 INFO - Running setup.py (path:/tmp/pip-OYR7Qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:35:46 INFO - Running setup.py (path:/tmp/pip-3wrtc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:46 INFO - Running setup.py (path:/tmp/pip-sHT1Oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:35:46 INFO - Running setup.py (path:/tmp/pip-GboNyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:35:46 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)) 03:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:35:46 INFO - Running setup.py (path:/tmp/pip-cBUzk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:35:47 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)) 03:35:47 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)) 03:35:47 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)) 03:35:47 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)) 03:35:47 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)) 03:35:47 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)) 03:35:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:48 INFO - Downloading blessings-1.6.tar.gz 03:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:35:49 INFO - Installing collected packages: blessings 03:35:49 INFO - Running setup.py install for blessings 03:35:49 INFO - Successfully installed blessings 03:35:49 INFO - Cleaning up... 03:35:49 INFO - Return code: 0 03:35:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:35:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:35:49 INFO - Reading from file tmpfile_stdout 03:35:49 INFO - Current package versions: 03:35:49 INFO - argparse == 1.2.1 03:35:49 INFO - blessings == 1.6 03:35:49 INFO - blobuploader == 1.2.4 03:35:49 INFO - docopt == 0.6.1 03:35:49 INFO - functools32 == 3.2.3-2 03:35:49 INFO - jsonschema == 2.5.1 03:35:49 INFO - manifestparser == 1.1 03:35:49 INFO - mozInstall == 1.12 03:35:49 INFO - mozcrash == 0.17 03:35:49 INFO - mozdebug == 0.1 03:35:49 INFO - mozdevice == 0.48 03:35:49 INFO - mozfile == 1.2 03:35:49 INFO - mozhttpd == 0.7 03:35:49 INFO - mozinfo == 0.9 03:35:49 INFO - mozleak == 0.1 03:35:49 INFO - mozlog == 3.4 03:35:49 INFO - moznetwork == 0.27 03:35:49 INFO - mozprocess == 0.23 03:35:49 INFO - mozprofile == 0.28 03:35:49 INFO - mozrunner == 6.12 03:35:49 INFO - mozscreenshot == 0.1 03:35:49 INFO - mozsystemmonitor == 0.3 03:35:49 INFO - moztest == 0.7 03:35:49 INFO - mozversion == 1.4 03:35:49 INFO - psutil == 3.1.1 03:35:49 INFO - requests == 1.2.3 03:35:49 INFO - wsgiref == 0.1.2 03:35:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35:49 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 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35:49 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 03:35:49 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 03:35:49 INFO - Using env: {'DISPLAY': ':0', 03:35:49 INFO - 'HOME': '/home/cltbld', 03:35:49 INFO - 'LANG': 'en_US.UTF-8', 03:35:49 INFO - 'LANGUAGE': 'en_US:en', 03:35:49 INFO - 'LOGNAME': 'cltbld', 03:35:49 INFO - 'MAIL': '/var/mail/cltbld', 03:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:49 INFO - 'MOZ_NO_REMOTE': '1', 03:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:49 INFO - 'NO_EM_RESTART': '1', 03:35:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:49 INFO - 'PWD': '/builds/slave/test', 03:35:49 INFO - 'SHELL': '/bin/bash', 03:35:49 INFO - 'SHLVL': '1', 03:35:49 INFO - 'TERM': 'linux', 03:35:49 INFO - 'TMOUT': '86400', 03:35:49 INFO - 'USER': 'cltbld', 03:35:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:49 INFO - '_': '/tools/buildbot/bin/python'} 03:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 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)) 03:35:49 INFO - Cleaning up... 03:35:49 INFO - Return code: 0 03:35:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:35: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f626fd7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f6ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:35: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', 'jsonschema'] in /builds/slave/test/build 03:35: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 jsonschema 03:35:49 INFO - Using env: {'DISPLAY': ':0', 03:35:49 INFO - 'HOME': '/home/cltbld', 03:35:49 INFO - 'LANG': 'en_US.UTF-8', 03:35:49 INFO - 'LANGUAGE': 'en_US:en', 03:35:49 INFO - 'LOGNAME': 'cltbld', 03:35:49 INFO - 'MAIL': '/var/mail/cltbld', 03:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:49 INFO - 'MOZ_NO_REMOTE': '1', 03:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:49 INFO - 'NO_EM_RESTART': '1', 03:35:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:49 INFO - 'PWD': '/builds/slave/test', 03:35:49 INFO - 'SHELL': '/bin/bash', 03:35:49 INFO - 'SHLVL': '1', 03:35:49 INFO - 'TERM': 'linux', 03:35:49 INFO - 'TMOUT': '86400', 03:35:49 INFO - 'USER': 'cltbld', 03:35:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:49 INFO - '_': '/tools/buildbot/bin/python'} 03:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:35:49 INFO - Cleaning up... 03:35:49 INFO - Return code: 0 03:35:49 INFO - Running post-action listener: _resource_record_post_action 03:35:49 INFO - Running post-action listener: _start_resource_monitoring 03:35:49 INFO - Starting resource monitoring. 03:35:49 INFO - [mozharness: 2016-09-22 10:35:49.898671Z] Finished create-virtualenv step (success) 03:35:49 INFO - [mozharness: 2016-09-22 10:35:49.899129Z] Running install step. 03:35:49 INFO - Running pre-action listener: _resource_record_pre_action 03:35:49 INFO - Running main action method: install 03:35:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:35:50 INFO - Reading from file tmpfile_stdout 03:35:50 INFO - Detecting whether we're running mozinstall >=1.0... 03:35:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:35:50 INFO - Reading from file tmpfile_stdout 03:35:50 INFO - Output received: 03:35:50 INFO - Usage: mozinstall [options] installer 03:35:50 INFO - Options: 03:35:50 INFO - -h, --help show this help message and exit 03:35:50 INFO - -d DEST, --destination=DEST 03:35:50 INFO - Directory to install application into. [default: 03:35:50 INFO - "/builds/slave/test"] 03:35:50 INFO - --app=APP Application being installed. [default: firefox] 03:35:50 INFO - mkdir: /builds/slave/test/build/application 03:35:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:36:06 INFO - Reading from file tmpfile_stdout 03:36:06 INFO - Output received: 03:36:06 INFO - /builds/slave/test/build/application/firefox/firefox 03:36:06 INFO - Running post-action listener: _resource_record_post_action 03:36:06 INFO - [mozharness: 2016-09-22 10:36:06.153292Z] Finished install step (success) 03:36:06 INFO - [mozharness: 2016-09-22 10:36:06.153545Z] Running run-tests step. 03:36:06 INFO - Running pre-action listener: _resource_record_pre_action 03:36:06 INFO - Running main action method: run_tests 03:36:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:36:06 INFO - Python 2.7.3 03:36:06 INFO - Return code: 0 03:36:06 INFO - grabbing minidump binary from tooltool 03:36:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:06 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f3f60>, '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 0x24f4d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24f51d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:36:06 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:36:06 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/linux64/releng.manifest -o -c /builds/tooltool_cache 03:36:06 INFO - Calling ['/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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:36:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:36:06 INFO - Return code: 0 03:36:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:36:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:36:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:36:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:36:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:36:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 03:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 03:36:06 INFO - Using env: {'DISPLAY': ':0', 03:36:06 INFO - 'HOME': '/home/cltbld', 03:36:06 INFO - 'LANG': 'en_US.UTF-8', 03:36:06 INFO - 'LANGUAGE': 'en_US:en', 03:36:06 INFO - 'LOGNAME': 'cltbld', 03:36:06 INFO - 'MAIL': '/var/mail/cltbld', 03:36:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:36:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:06 INFO - 'MOZ_NO_REMOTE': '1', 03:36:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:36:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:36:06 INFO - 'NO_EM_RESTART': '1', 03:36:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:06 INFO - 'PWD': '/builds/slave/test', 03:36:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:36:06 INFO - 'SHELL': '/bin/bash', 03:36:06 INFO - 'SHLVL': '1', 03:36:06 INFO - 'TERM': 'linux', 03:36:06 INFO - 'TMOUT': '86400', 03:36:06 INFO - 'USER': 'cltbld', 03:36:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000', 03:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:06 INFO - '_': '/tools/buildbot/bin/python'} 03:36:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 03:36:06 INFO - mozversion application_buildid: 20160922004007 03:36:06 INFO - mozversion application_changeset: e77a60c374edda1097cbcded033e4de8a7c4ef00 03:36:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:36:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:36:06 INFO - mozversion application_name: Firefox 03:36:06 INFO - mozversion application_remotingname: firefox-dev 03:36:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:36:06 INFO - mozversion application_vendor: Mozilla 03:36:06 INFO - mozversion application_version: 51.0a2 03:36:06 INFO - mozversion platform_buildid: 20160922004007 03:36:06 INFO - mozversion platform_changeset: e77a60c374edda1097cbcded033e4de8a7c4ef00 03:36:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:36:06 INFO - mozversion platform_version: 51.0a2 03:36:06 INFO - using testdate: 1474540566 03:36:06 INFO - actual date: 1474540566 03:36:06 INFO - starting webserver on 'localhost:60557' 03:36:06 INFO - SUITE-START | Running 2 tests 03:36:06 INFO - TEST-START | dromaeo_css 03:36:06 INFO - operating with platform_type : linux_ 03:36:06 INFO - Initialising browser for dromaeo_css test... 03:36:06 INFO - TEST-INFO | started process 3928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7MRr1/profile http://localhost:60557/getInfo.html) 03:36:11 INFO - PROCESS | 3928 | __metrics Screen width/height:1600/1200 03:36:11 INFO - PROCESS | 3928 | colorDepth:24 03:36:11 INFO - PROCESS | 3928 | Browser inner width/height: 1024/697 03:36:11 INFO - PROCESS | 3928 | __metrics 03:36:11 INFO - PROCESS | 3928 | 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" 03:36:11 INFO - PROCESS | 3928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:36:12 INFO - PROCESS | 3928 | console.error: PushService: 03:36:12 INFO - PROCESS | 3928 | stateChangeProcessEnqueue: Error transitioning state 03:36:12 INFO - PROCESS | 3928 | UnknownError 03:36:12 INFO - TEST-INFO | 3928: exit 0 03:36:12 INFO - Browser initialized. 03:36:12 INFO - Running cycle 1/1 for dromaeo_css test... 03:36:12 INFO - TEST-INFO | started process 4020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI7MRr1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:24 INFO - PROCESS | 4020 | RSS: Main: 147517440 03:36:24 INFO - PROCESS | 4020 | 03:37:15 INFO - PROCESS | 4020 | 1474540635786 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] 03:37:33 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.03274 seconds. 03:37:37 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01625 seconds. 03:37:41 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.16688 seconds. 03:37:53 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 03:37:56 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0661 seconds. 03:38:00 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 03:38:06 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.03409 seconds. 03:38:19 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.03297 seconds. 03:38:25 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0332 seconds. 03:38:26 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00083 seconds. 03:38:28 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0007 seconds. 03:38:47 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 03:38:56 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01627 seconds. 03:38:58 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 03:39:11 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 03:39:16 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.91714 seconds. 03:39:25 INFO - PROCESS | 4020 | Cycle 1(1): loaded http://localhost:60557/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60557/tests/dromaeo/cssquery-ext.html) 03:39:26 INFO - PROCESS | 4020 | RSS: Main: 242241536 03:39:26 INFO - PROCESS | 4020 | 03:39:30 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.02381 seconds. 03:39:43 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 03:40:30 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00053 seconds. 03:40:39 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01688 seconds. 03:40:44 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 03:41:30 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 03:41:52 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 03:41:54 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.03319 seconds. 03:41:57 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01586 seconds. 03:42:29 INFO - PROCESS | 4020 | Cycle 1(1): loaded http://localhost:60557/tests/dromaeo/cssquery-ext.html (next: http://localhost:60557/tests/dromaeo/cssquery-jquery.html) 03:42:29 INFO - PROCESS | 4020 | RSS: Main: 224059392 03:42:29 INFO - PROCESS | 4020 | 03:42:44 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00077 seconds. 03:43:18 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 03:44:09 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01586 seconds. 03:44:12 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 03:44:33 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 03:44:40 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:45:31 INFO - PROCESS | 4020 | Cycle 1(1): loaded http://localhost:60557/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60557/tests/dromaeo/cssquery-mootools.html) 03:45:32 INFO - PROCESS | 4020 | RSS: Main: 207560704 03:45:32 INFO - PROCESS | 4020 | 03:45:34 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01621 seconds. 03:45:42 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00034 seconds. 03:45:44 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 03:46:53 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00082 seconds. 03:47:21 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00054 seconds. 03:47:23 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00081 seconds. 03:47:25 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 03:47:39 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 03:47:49 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 03:48:34 INFO - PROCESS | 4020 | Cycle 1(1): loaded http://localhost:60557/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60557/tests/dromaeo/cssquery-prototype.html) 03:48:34 INFO - PROCESS | 4020 | RSS: Main: 173129728 03:48:34 INFO - PROCESS | 4020 | 03:48:41 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 03:48:43 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 03:48:51 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01707 seconds. 03:48:53 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.06705 seconds. 03:48:55 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 03:50:00 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 03:50:04 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 03:50:08 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01685 seconds. 03:50:18 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00014 seconds. 03:50:26 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 03:50:28 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0338 seconds. 03:50:34 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 03:50:41 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 03:50:47 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0008 seconds. 03:50:53 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 03:50:55 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 03:51:15 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 03:51:19 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 03:51:24 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00075 seconds. 03:51:25 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 03:51:33 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 03:51:37 INFO - PROCESS | 4020 | Cycle 1(1): loaded http://localhost:60557/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60557/tests/dromaeo/cssquery-yui.html) 03:51:38 INFO - PROCESS | 4020 | RSS: Main: 175747072 03:51:38 INFO - PROCESS | 4020 | 03:51:41 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01656 seconds. 03:51:43 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.04991 seconds. 03:52:00 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01601 seconds. 03:52:26 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 03:53:01 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00073 seconds. 03:53:03 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 03:53:21 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 03:53:29 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 03:53:54 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01631 seconds. 03:54:08 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 03:54:30 INFO - PROCESS | 4020 | [GFX1-]: Refresh driver waiting for the compositor for 1.01637 seconds. 03:54:40 INFO - PROCESS | 4020 | Cycle 1(1): loaded http://localhost:60557/tests/dromaeo/cssquery-yui.html (next: http://localhost:60557/tests/dromaeo/cssquery-dojo.html) 03:54:41 INFO - PROCESS | 4020 | RSS: Main: 267825152 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | __start_tp_report 03:54:41 INFO - PROCESS | 4020 | _x_x_mozilla_page_load 03:54:41 INFO - PROCESS | 4020 | _x_x_mozilla_page_load_details 03:54:41 INFO - PROCESS | 4020 | |i|pagename|runs| 03:54:41 INFO - PROCESS | 4020 | |0;dojo.html;3080.1991482214626;3124.6562878083414;3130.0922732407603;3161.588993430854;3165.4776961801304;5513.61404701671;5574.916376254359;5616.045272303708;5622.718864056791;5628.746706398211;5577.721113944307;5646.491815736583;5663.66018038918;5669;5692.544596432286;5045.823396181126;5104.591632669378;5137.280780690707;5173.379194496666;5181.922271165936;5071.492850714936;5132.692038477703;5183.688978661274;5193.221016847466;5194.039102766001;5010.448850626428;5028.974855125719;5038.143515602356;5061.316722242505;5070.771815268304;5121.436641969381;5133.717645529513;5146.588272938156;5199;5230.555402790767;5034.974825125851;5097.413797413308;5164.406093299281;5182.896342073175;5207.9479205207845;5413;5479.753411096471;5483.75323110457;5526.171074338841;5540.944590554083;5809.651420914759;5821.53427725781;5828.88342233153;5931.4958228550195;5931.733072011727;5957.1362152487645;5962.731677074542;5970;5977.402259774031;5977.7608895644125;5498.037843377393;5511.641743286674;5514.80698175562;5518.862028449281;5554.222408862763;6048.334683184846;6063.363346848606;6079.331273559905;6084.148219249309;6085.5435842312;5842.853928651818;5843.503302219274;5845.912311315334;5846.269216347957;5849.912251316234;5730.656160630376;5767.596268261265;5873.177755114288;5912.497437717842;5915.112733090071;5935.2877654681715;5940.673262970492;5940.940590594125;5949.0481522956115;5971.790987315423;5886.941130588725;5944.138099975479;5944.346121926585;5945.732442040118;5973.133895585116;4481.484629267611;4525.389072475239;4535.614472769786;4552.317152427162;4561.589456948825;4055.1241888008703;4080.714349995472;4104.343305071234;4109.835606575703;4139.461869956946;5781;5786.71066446676;5794.681292528867;5800.738966746422;5804.4775970162045;5774.3070831499645;5788.0449725795615;5881.323647780475;6047.425494578008;6052.061930400795;5591.7483713233005;5661.433856614306;5683.261176047087;5699.886002279931;5712.857178570486;5154.072266991902;5163.457836927144;5171.586273098218;5174.6377753557645;5200.063988482034;6014.9699251504335;6047.9395206047375;6096.8780624388155;6112.052631842072;6131.202943617302;5917.526597872246;5929.703514824155;5962.5528085394635;6017.247844019497;6062.818115456544;6183.501691891366;6208.875822483616;6212.751489940532;6223.751049957951;6231.688415579293;5684.8294551163535;5900.970495147497;5924.703764811656;5924.8815023700145;5954.702264886651;5766.135079738073;5975.462208401264;5995.790147344822;6013.5189184865985;6117.877642447215;5869.090291004731;5917.644941303535;5932.406759324033;5932.525397968066;5939.851503712442;5925.140854575976;5931.881362372815;5934.19888315072;5938.792142275;5940.257467816523;5572.860678482908;5674.06377947634;5779.653220806765;5798.420157984167;5827.621204621686;5960.314563825215;5970.104484327386;5973.402659733992;5979.521638268841;5984.521238301013;5633.267675202197;5678.517326027169;5806.854828629318;5812.738426770721;5822.650640961556 03:54:41 INFO - PROCESS | 4020 | |1;ext.html;587.8736071744577;610.6275172144991;620.2464006232427;643.7296335539073;650.4763665249475;33877.153071173234;45141;46032.53967460324;46066.769666151624;46135.76932115339;47677.28484072733;48086;48128.27807582889;48141;48161;8565.48607083574;9252.352335336529;9407.38851974623;9409.247260219166;9409.670661526849;8477.109903460141;10985.956334148243;11261.718457038558;11270.09839212861;11556.133290003241;11443.656690299264;11522.481488333004;11523.827142592867;11524.596639117633;11529.827052594175;11239.831402528887;11250.437478126061;11293.604723834625;11324.320540767498;11331.490082946288;11086;11145.052670523015;11146.331220126818;11168.329900206014;11169.944150279196;44495.555044449466;44827.55172448266;44879;44904.3264351035;45039.774801125786;3804.7596641985037;3900.161465284949;4314.482262128565;4366.497852746943;4420.668449866273;34550.30899382023;34594.65405345965;34652.306953861036;34783.30433391343;35153;4746.240601503743;4776.211925032366;4826.32431459596;4831.130396528623;4891;368900;370585;370612;372639;375128;60916.69541652352;61203.38796611977;61217.69391153016;61280;61346.693266534276;40457.1908561833;41309;41422.58577414187;43681.56318436775;43709.78145109254;18883.905580472285;19114.2354305829;19127.808721912603;19132.713009304873;19133;13620.523281685093;13908.443662253396;14422.206778627067;14657.56027319182;14887.255637218095;19740.605187896163;20189.394318170478;20455.18179272842;20514.28200013022;20556.28053018137;7256.963715181496;7361.447891408059;7418.221086785918;7527.967448788796;7574.128975167816;974.7660561465339;1031.169908223872;1039.3400190878695;1086.9021788039115;1107.9723006924892;33922.66077339245;34210;34519.654803451645;34633.65366346304;34833.6517426726;1153.3425947210178;1158.472894832849;1173.2198088271205;1188.0733028237987;1213.0295763389254;15250.46623368199;16602.58493537671;16666;18109.7283540747;18498.722519162224;611737;622341;623140;623532;623649;62574.68712656407;63059.369406306265;63087.6845615769;63214.68392658007;63286.367136328045;1309.8362704661918;1327.9402426890813;1383.7370899529258;1423.5871597236892;1459.5767227504118;30487.847560762053;30728;30741.69258307478;31370.215744605655;31440.52839207368;53663.19505207503;54231.45768542264;54303.72848135892;54376.45623543714;54380.728096359264;451275;454268.286585676;454600;454903;455348;30820;31637.20906977344;31639;31646.683533165295;33270;51037;51716;51882.481175187764;51996.74001629968;52083.958320832615;43127.35308970305;44072.55927440685;44132.33801492916;44895.32657010213;45065;38231;38341.80829095837;39106.217875641756;39327;40551.797241013606;42983.570164297955;43352.56647433485;43407;43424.78287608668;43448.78275608602;32589.18527036843;34312.828435857664;34859.651403486656;34861.30277394387;34917.12707182341;26048.609270861332;26169.214923552325;26192.8690356547;26561.867190663925;26947.326316842235 03:54:41 INFO - PROCESS | 4020 | |2;jquery.html;397.393974189361;421.98945026374355;430.2062694328963;432.50910216903804;439.26861775144386;15131.31909064092;19310;19985.100670469827;20151.09320080596;20461.590768184626;17773.55566110848;18602;18732.34436794709;18816;18845.52886177846;5722.084466485363;6002.8499287517725;6004.039353703409;6008.218931538894;6010.098485227207;4255.553166917475;4471.150481408538;4502.8874278142985;5903.26209223847;6535.640539770311;6608.933910660904;6614.7023383947835;6617.602943823386;6631.403173714365;6633;6383.648899310536;6554.9344506555035;6560.540762146652;6576.80269591913;6598.076269322299;5710.600257981943;5805.564582656318;5889.676067816268;5897.085951677497;5898.115282707586;24016.15943441971;24292.1497747578;24415.145469908646;24689.87655061731;24968.250952471455;2473.6165894286414;2525.330787341356;3041.9087427377212;3092.443360195164;3169.969759828065;15152.772708409382;15306.464273750365;15341.309641066178;15377.846221537866;16113.516594502184;1969.3698016634683;1988.2643421933976;2074.5643414882893;2083.4374718825984;2141.4111119442277;87169;88215.55892220562;88852.55573722154;88895;89134.55432722795;10825.242233043611;11923.674883397827;11940.6268279149;12047.096467764954;12070.698232544257;15308.770368444482;16166.515004549883;16400.75398869018;16411.5897102571;17154.914225429042;8678.956605216934;8781.868271975925;8899.644014239488;8928.687495937611;9003.774905627411;8531.402801803813;8534.786630334167;8583.527905965107;8692.652293908299;8773.254273386707;4964.553190212898;5114.360704911886;5117.590592752571;5138.486151384906;5182.97408512955;2071.513194399315;2084.291340944086;2086.3428020173596;2091.8431117666237;2111.609352269835;453.99319010214873;463.59203900567957;473.2428115015947;474.3927772451268;1050.3225419604312;11851.46668399924;13071.542496012595;13135.605931822061;13222.074454788073;13240.801387979189;493.4054464370425;498.3372115086926;499.8250612285744;502.4799332690721;505.6814207049609;6763.661816909135;7029.648517574203;7032.261612530713;7201;7226.458015648847;516418;524194;528233;532909;538641;92729.53635231916;98181.01818981861;98694;103294;103347;510.90037442698315;519.3067255214331;520.6511637203083;521.4602886013034;523.4059342646124;9761.46311952821;11678.824817627572;12119.697007574881;12330.81503777462;12503.624891253277;41026.58973410228;41734.58265417307;42404;44003.559964399945;46349.53650463587;61250.69374653098;61539.38460615336;61760.38239617726;62154.68922655358;62969.68515157578;9003.234725048444;9095.636174552943;9137.451752894847;9175.587098580447;9418.199453046567;10327.380357178292;13458.730825383633;13666.043376963516;13749;13954.23251721165;11312.94343528277;11614.12894032934;11882.762344752884;12125.514979401078;12456.813147802972;5723.7424315905055;10123.64567240143;10962.451877406258;11570.826437603275;11669.416529173677;11492.310461372344;11551.018163455865;11954.880451195377;11962.76074478523;12423.751524969632;8945.284377249674;9184.862227066465;9234.907650923406;9315.534223288943;9318.534073296443;8781.91218087811;11767.823482647596;11945.820812687643;11945.820812687643;12088.15382923222 03:54:41 INFO - PROCESS | 4020 | |3;mootools.html;1524.8551387618177;1560.4538411555955;1697.422876222085;1779.421687951416;1835.7705286839146;8979.775505612362;9684.903150968488;9689.32174747768;9694.951525242364;9697.27270454716;8469.745907622766;8688.044315125348;8748.825023499527;8983.371164018521;9433.764155896106;2443.499082688049;2475.8762061896923;2477.987610061947;2478.033566908907;2480.702315722107;2406.9398265043337;2457.447074408253;2457.8893949772305;2458.4591389894285;2460.151247819508;2425.5391475619663;2439;2445.645381419693;2451;2451.84063035903;2407.1935901473116;2413.5896897527464;2418.262429958862;2424.660547523348;2439.0365805506904;2362.2558893948353;2417.2627348658652;2419.395151212197;2420.2497225859865;2422.47916697909;9410.529473526365;9553.331266811327;9572.138507534286;9575.23398128148;9577.377470464398;5213.687178769248;5348.759305831247;5353.544948679367;5360.9463905360835;5367.43641917597;3377.257003459235;3511.631278715749;3540.451230059345;3561.626029266916;3588.3540962626716;1194.796884529632;1221.3832014832542;1222.0651201830606;1248;1254.8243245945575;13295.800562991562;13364.39860206293;13395.531156409575;13484.528041518499;13485.932570337183;4545.545445455428;4688.038952014829;4828.106800241966;4855.271709243571;4912.656114072052;11247.381394023407;11322;11333.829992550118;11350.31898086101;11362.545498180145;5636.577256705764;5638.210650508932;5640.407757185519;5649.661020338793;5650;4571.1543364477675;4720.197566413718;4728;4897.363342765489;5184.429712731597;4236.491621005499;4285.421468101828;4418.8453404130705;4418.889527761831;4464.281251718112;1397.3641992893208;1426.3296250762126;1435.067206315903;1438.978415323771;1449.811524501808;1015.3146626027402;1018.221060888421;1021.9642312519026;1036.7978244242452;1067.5036108209565;5809.970950145223;5984.308522577063;6041.426064523863;6050.092486127116;6147.66187859663;491.1674711364203;491.51585688097197;498.8478514053176;503.1597232621531;509.48796459557906;4161.771102589326;4184.267753143188;4195.244855925963;4197.769122698281;4250.51119121305;10448.641676581996;10792.946035269773;10807.67576972692;10814.945925270324;10828.72928176786;10710.678679639623;10718;10729.9463502682;10739.570417183382;10742.89257107419;8192.836143277102;8207;8207.179282071864;8560.828783424296;8561.101084386175;6239.625622462667;6283.7800676976085;6369.331220221995;6576.901346479903;6594.736210551524;10658.62694805678;10747.731306717395;10990.340579565252;11048.944755276494;11128.165387596124;10722.981316774894;11052.889471105185;11259.774804504028;11324;11371.43142842838;8092.878606820785;8265.380096492667;8317.750467485985;8351.457155284887;8358.122397148212;9145.542722863964;9184.357095003554;9273.72178834636;9275.67535136294;9281.72154835356;8072.152423995396;8084.353251739964;8144.144864789112;8144.674213031412;8200.343972482067;7515.962420187864;7550.395968322631;7755.68977240904;7789.298963093349;7795.103563090317;7513;7545.471816972759;7551.509151905108;7636.274553917369;7639.732609358646;5287.920681189708;5321.5476684482255;5486.1222204447095;5555.333359996826;5567.387587365468;5420.53925416344;5533.225348451139;5583.274174357308;5626.127950167731;5761.251037365116 03:54:41 INFO - PROCESS | 4020 | |4;prototype.html;354.42406090103583;355.443730561671;356.6415752169069;356.8857965451057;361.8516408272609;5454.581808729729;5510.448955104493;5515.862103447411;5516.365617953927;5517.282753242083;5443.176535860492;5443.836684899449;5449.264349312852;5467.0705979983395;5481.561475081994;3842;4076.5923407659143;4137.379393091045;4234.788260586974;4313.676474264427;4285.164392943378;4292.819474644482;4298;4312.22379971605;4313.935290970638;4253.8085786139545;4272.914541709164;4301.784910754465;4308.2029824482415;4314.590113939171;4121.29937910556;4125.64931980782;4202.810873510699;4296.785160741966;4311.180875633635;3787.3561494545993;3787.5833658297565;3811.5045044144085;3815.275097731422;3838.846446142151;5221.582273418117;5239;5241.479970808461;5293.206019097128;5326.748214169667;4724.456687480915;4873.853784386474;4911.533404326583;4941.752912354404;4947.6289278304275;5549.361823390322;5561.888762224733;5565.998120338338;5577.274954255961;5579.944200557983;4203.558626344251;4207.326827707552;4213.641840443566;4222.9788851055855;4228.4080228768;5630.239917611111;5859.53123750099;5862.179294898718;5929.636183677779;5954.19618351517;4182.728122672017;4202.705810593229;4213.5154457237195;4217.177650358212;4223.303154979455;4264.805854360722;4274;4277.615014648696;4280.400743895857;4298.570142985676;4813.253945638432;4929.260610908393;4937.876553086202;4951.925721114186;4965.577925876269;5752.683602401824;5754.424557544212;5756.942430575725;5769.336526299446;5788.305403351625;1469.9853001469849;1472.6686495538631;1480.326451464581;1484.6511069898565;1486.717523670499;901.7069452427987;953.1802649721234;958.1855422890627;961.245422343457;968.0851595242569;5035.320231768665;5057.291979122926;5075.441701412841;5082.796688132434;5086.77109530072;5279.392869819943;5282.31329927107;5288.286081379041;5291.417944026154;5319.86700332487;4950.504949505021;4961.50384961501;4986.576141028053;5263.973680131575;5686.34607020198;3116.750659947199;3120.2667373167287;3123.2660324823646;3130.624325081005;3139.6860313968878;6080.543959203077;6088.178095956989;6096.603720758137;6097;6114.370820547208;5733.655980641091;5733.913991290134;6042.365551617105;6048.850718363585;6058.242719660043;5955.196048533479;5982.162497250389;6133.754649813957;6142.999999999911;6156.630602163795;5908.497777688945;5914.911276330945;5921.792737254175;5944.405559444021;5954.702264886651;5562.2490963721;5817.738201780845;5866.413358664099;5874.295084589877;5882.176495290577;6132;6140.785072522441;6142.662153581596;6147.7848275311935;6157.076438534255;4099.221147981874;4285.485741711014;4312.784360782011;4315.762633055087;4328.653707703439;5843.743595127068;5863.912041319299;5891.852703682443;5898.793542226002;5914.34942156371;5765.4811067004175;5797.188393624811;5797.681127538026;5831.620944638585;5835.183074369676;5615.382307946041;5698.6010979232715;5742.827715168552;5755.251817263729;5760.884782304247;5688.208214412447;5695.202671626059;5698.943010569841;5719.2279042328755;5722.370539240597;5555.666660000251;5787.971060144672;5791.102379131242;5795.580082879722;5796.739146738322;5614.915776263441;5618.943810562006;5652.491275785199;5654.445027617438;5654.671152279293 03:54:41 INFO - PROCESS | 4020 | |5;yui.html;781.160252728317;815.7104227999064;818.4884447220487;868.2186032570687;930.7440453875179;15520.379184832607;17685.55786105348;17699.203535840887;17715;17723.73414398782;15625.21873906303;15845.366185352601;16020.279087441064;16090.919545402257;16185.271662775174;859.9054104048565;874.899386570543;875.9211670949613;876.6405773632812;876.6449587916904;717.196739651591;734.2620666230431;861.995690021549;875.7285241575132;883.1433509495779;927.8886533615976;929.8791157149561;930.9441433513977;930.9674161404353;931.0782325497776;925.6945208081385;927.7309580221728;927.7866090799087;928.2527565309923;928.9210417114554;557.8967890940189;568.8208214412447;646.6475770704977;659.8053574195624;664.3788058165583;13375.197488150645;13870.861291387158;13871.653208669763;13877.097988630807;13891;13418.932905335509;13903.235322057282;13980.58058258254;14389;14490.637734056732;12992.350382480838;13003;13194.713376428654;13221.272829994346;13462.730745385135;4700.905981880377;4750.786214620351;4871.36672232611;4981.103401387749;4997.175466048134;85088.57455712743;85936;86499.56750216335;86627;87549;12171.391430428443;12269;12583.245005299712;12744.745105097845;12892.419841107172;24135.275941721775;24297;24516.38709032253;24539.50920981606;24588.262352129466;8840.381173317935;8961.910380896108;9011.909880901107;9149.54252287383;9235.676751313806;5021.698698078127;5145.382554093458;5190.8183213587345;5266.210068489757;5388.757505912244;3468.7745296555645;3593.874214402483;3596.6223546527763;3596.9280614387567;3609.7473176877365;583.9474447299764;591.8697886464971;595.8599729063667;596.582392325374;620.2154274842328;548.4926443040172;587.5152998776027;603.3513972479602;629.1443636654146;713.6039498078616;9992.400455972665;10026.899731002597;10202.183825293958;10360.119389851796;10542.947285263677;616.7748771698298;659.9571027883172;725.836686745478;736.9447291453163;738.8780851159606;4968.826091086867;4973.726445045485;4981.128302547112;5063.265826455144;5100.872478188075;77505;82175.5891220552;95982.52008739911;134301.65698342893;140207.29896350452;67145.6642716793;97752;114441;116726.41636791761;117519.41240293915;64464;64477.677611611645;88819.55590222008;102492;109083.45458272817;9302.488363140092;9556.187724043533;9892.455914924749;9930.404175749478;9999.450030248407;52780.47219527756;54582;54636.453635463135;54840.17739733987;55031.17453238124;81515;82158;83259.5837020811;83828.58085709777;84222.15777842143;14137.858621413654;14218.715625687635;14581.708365832412;14593.489227876973;14823.925880370529;14545.272736363351;15955.559984057301;16286.918565407097;16642.50072497827;17116.144192790063;14690;14747.409487364092;15143.697126057197;15659.686806264039;15896.205189740698;14943.551693449213;15153.54539363821;15773.44792932242;15824;16055.919720401791;14117.647058823612;14421.855781442051;14847.554573362817;14955.102693838404;15796.68406631884;11871.940640296743;12232.327222002876;12406.62780116598;12968.870311296767;13122.015848811569;10946.56213751473;12689.36553172356;12811.935940320238;13156.736865262832;13209.405576748877 03:54:41 INFO - PROCESS | 4020 | __end_tp_report 03:54:41 INFO - PROCESS | 4020 | __start_cc_report 03:54:41 INFO - PROCESS | 4020 | _x_x_mozilla_cycle_collect,951 03:54:41 INFO - PROCESS | 4020 | __end_cc_report 03:54:41 INFO - PROCESS | 4020 | __startTimestamp1474541681113__endTimestamp 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | ------- Summary: start ------- 03:54:41 INFO - PROCESS | 4020 | Number of tests: 6 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | [#0] dojo.html Cycles:165 Average:5548.26 Median:5788.04 stddev:632.08 (10.9%) stddev-sans-first:603.64 03:54:41 INFO - PROCESS | 4020 | Values: 3080.2 3124.7 3130.1 3161.6 3165.5 5513.6 5574.9 5616.0 5622.7 5628.7 5577.7 5646.5 5663.7 5669.0 5692.5 5045.8 5104.6 5137.3 5173.4 5181.9 5071.5 5132.7 5183.7 5193.2 5194.0 5010.4 5029.0 5038.1 5061.3 5070.8 5121.4 5133.7 5146.6 5199.0 5230.6 5035.0 5097.4 5164.4 5182.9 5207.9 5413.0 5479.8 5483.8 5526.2 5540.9 5809.7 5821.5 5828.9 5931.5 5931.7 5957.1 5962.7 5970.0 5977.4 5977.8 5498.0 5511.6 5514.8 5518.9 5554.2 6048.3 6063.4 6079.3 6084.1 6085.5 5842.9 5843.5 5845.9 5846.3 5849.9 5730.7 5767.6 5873.2 5912.5 5915.1 5935.3 5940.7 5940.9 5949.0 5971.8 5886.9 5944.1 5944.3 5945.7 5973.1 4481.5 4525.4 4535.6 4552.3 4561.6 4055.1 4080.7 4104.3 4109.8 4139.5 5781.0 5786.7 5794.7 5800.7 5804.5 5774.3 5788.0 5881.3 6047.4 6052.1 5591.7 5661.4 5683.3 5699.9 5712.9 5154.1 5163.5 5171.6 5174.6 5200.1 6015.0 6047.9 6096.9 6112.1 6131.2 5917.5 5929.7 5962.6 6017.2 6062.8 6183.5 6208.9 6212.8 6223.8 6231.7 5684.8 5901.0 5924.7 5924.9 5954.7 5766.1 5975.5 5995.8 6013.5 6117.9 5869.1 5917.6 5932.4 5932.5 5939.9 5925.1 5931.9 5934.2 5938.8 5940.3 5572.9 5674.1 5779.7 5798.4 5827.6 5960.3 5970.1 5973.4 5979.5 5984.5 5633.3 5678.5 5806.9 5812.7 5822.7 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | [#1] ext.html Cycles:180 Average:64434.32 Median:38286.40 stddev:131089.92 (342.4%) stddev-sans-first:131370.02 03:54:41 INFO - PROCESS | 4020 | Values: 587.9 610.6 620.2 643.7 650.5 33877.2 45141.0 46032.5 46066.8 46135.8 47677.3 48086.0 48128.3 48141.0 48161.0 8565.5 9252.4 9407.4 9409.2 9409.7 8477.1 10986.0 11261.7 11270.1 11556.1 11443.7 11522.5 11523.8 11524.6 11529.8 11239.8 11250.4 11293.6 11324.3 11331.5 11086.0 11145.1 11146.3 11168.3 11169.9 44495.6 44827.6 44879.0 44904.3 45039.8 3804.8 3900.2 4314.5 4366.5 4420.7 34550.3 34594.7 34652.3 34783.3 35153.0 4746.2 4776.2 4826.3 4831.1 4891.0 368900.0 370585.0 370612.0 372639.0 375128.0 60916.7 61203.4 61217.7 61280.0 61346.7 40457.2 41309.0 41422.6 43681.6 43709.8 18883.9 19114.2 19127.8 19132.7 19133.0 13620.5 13908.4 14422.2 14657.6 14887.3 19740.6 20189.4 20455.2 20514.3 20556.3 7257.0 7361.4 7418.2 7528.0 7574.1 974.8 1031.2 1039.3 1086.9 1108.0 33922.7 34210.0 34519.7 34633.7 34833.7 1153.3 1158.5 1173.2 1188.1 1213.0 15250.5 16602.6 16666.0 18109.7 18498.7 611737.0 622341.0 623140.0 623532.0 623649.0 62574.7 63059.4 63087.7 63214.7 63286.4 1309.8 1327.9 1383.7 1423.6 1459.6 30487.8 30728.0 30741.7 31370.2 31440.5 53663.2 54231.5 54303.7 54376.5 54380.7 451275.0 454268.3 454600.0 454903.0 455348.0 30820.0 31637.2 31639.0 31646.7 33270.0 51037.0 51716.0 51882.5 51996.7 52084.0 43127.4 44072.6 44132.3 44895.3 45065.0 38231.0 38341.8 39106.2 39327.0 40551.8 42983.6 43352.6 43407.0 43424.8 43448.8 32589.2 34312.8 34859.7 34861.3 34917.1 26048.6 26169.2 26192.9 26561.9 26947.3 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | [#2] jquery.html Cycles:180 Average:30448.91 Median:4487.02 stddev:87241.93 (1944.3%) stddev-sans-first:87457.48 03:54:41 INFO - PROCESS | 4020 | Values: 397.4 422.0 430.2 432.5 439.3 15131.3 19310.0 19985.1 20151.1 20461.6 17773.6 18602.0 18732.3 18816.0 18845.5 5722.1 6002.8 6004.0 6008.2 6010.1 4255.6 4471.2 4502.9 5903.3 6535.6 6608.9 6614.7 6617.6 6631.4 6633.0 6383.6 6554.9 6560.5 6576.8 6598.1 5710.6 5805.6 5889.7 5897.1 5898.1 24016.2 24292.1 24415.1 24689.9 24968.3 2473.6 2525.3 3041.9 3092.4 3170.0 15152.8 15306.5 15341.3 15377.8 16113.5 1969.4 1988.3 2074.6 2083.4 2141.4 87169.0 88215.6 88852.6 88895.0 89134.6 10825.2 11923.7 11940.6 12047.1 12070.7 15308.8 16166.5 16400.8 16411.6 17154.9 8679.0 8781.9 8899.6 8928.7 9003.8 8531.4 8534.8 8583.5 8692.7 8773.3 4964.6 5114.4 5117.6 5138.5 5183.0 2071.5 2084.3 2086.3 2091.8 2111.6 454.0 463.6 473.2 474.4 1050.3 11851.5 13071.5 13135.6 13222.1 13240.8 493.4 498.3 499.8 502.5 505.7 6763.7 7029.6 7032.3 7201.0 7226.5 516418.0 524194.0 528233.0 532909.0 538641.0 92729.5 98181.0 98694.0 103294.0 103347.0 510.9 519.3 520.7 521.5 523.4 9761.5 11678.8 12119.7 12330.8 12503.6 41026.6 41734.6 42404.0 44003.6 46349.5 61250.7 61539.4 61760.4 62154.7 62969.7 9003.2 9095.6 9137.5 9175.6 9418.2 10327.4 13458.7 13666.0 13749.0 13954.2 11312.9 11614.1 11882.8 12125.5 12456.8 5723.7 10123.6 10962.5 11570.8 11669.4 11492.3 11551.0 11954.9 11962.8 12423.8 8945.3 9184.9 9234.9 9315.5 9318.5 8781.9 11767.8 11945.8 11945.8 12088.2 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | [#3] mootools.html Cycles:180 Average:6099.84 Median:4558.35 stddev:3505.93 (76.9%) stddev-sans-first:3498.91 03:54:41 INFO - PROCESS | 4020 | Values: 1524.9 1560.5 1697.4 1779.4 1835.8 8979.8 9684.9 9689.3 9695.0 9697.3 8469.7 8688.0 8748.8 8983.4 9433.8 2443.5 2475.9 2478.0 2478.0 2480.7 2406.9 2457.4 2457.9 2458.5 2460.2 2425.5 2439.0 2445.6 2451.0 2451.8 2407.2 2413.6 2418.3 2424.7 2439.0 2362.3 2417.3 2419.4 2420.2 2422.5 9410.5 9553.3 9572.1 9575.2 9577.4 5213.7 5348.8 5353.5 5360.9 5367.4 3377.3 3511.6 3540.5 3561.6 3588.4 1194.8 1221.4 1222.1 1248.0 1254.8 13295.8 13364.4 13395.5 13484.5 13485.9 4545.5 4688.0 4828.1 4855.3 4912.7 11247.4 11322.0 11333.8 11350.3 11362.5 5636.6 5638.2 5640.4 5649.7 5650.0 4571.2 4720.2 4728.0 4897.4 5184.4 4236.5 4285.4 4418.8 4418.9 4464.3 1397.4 1426.3 1435.1 1439.0 1449.8 1015.3 1018.2 1022.0 1036.8 1067.5 5810.0 5984.3 6041.4 6050.1 6147.7 491.2 491.5 498.8 503.2 509.5 4161.8 4184.3 4195.2 4197.8 4250.5 10448.6 10792.9 10807.7 10814.9 10828.7 10710.7 10718.0 10729.9 10739.6 10742.9 8192.8 8207.0 8207.2 8560.8 8561.1 6239.6 6283.8 6369.3 6576.9 6594.7 10658.6 10747.7 10990.3 11048.9 11128.2 10723.0 11052.9 11259.8 11324.0 11371.4 8092.9 8265.4 8317.8 8351.5 8358.1 9145.5 9184.4 9273.7 9275.7 9281.7 8072.2 8084.4 8144.1 8144.7 8200.3 7516.0 7550.4 7755.7 7789.3 7795.1 7513.0 7545.5 7551.5 7636.3 7639.7 5287.9 5321.5 5486.1 5555.3 5567.4 5420.5 5533.2 5583.3 5626.1 5761.3 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | [#4] prototype.html Cycles:180 Average:4800.06 Median:5446.55 stddev:1403.01 (25.8%) stddev-sans-first:1366.69 03:54:41 INFO - PROCESS | 4020 | Values: 354.4 355.4 356.6 356.9 361.9 5454.6 5510.4 5515.9 5516.4 5517.3 5443.2 5443.8 5449.3 5467.1 5481.6 3842.0 4076.6 4137.4 4234.8 4313.7 4285.2 4292.8 4298.0 4312.2 4313.9 4253.8 4272.9 4301.8 4308.2 4314.6 4121.3 4125.6 4202.8 4296.8 4311.2 3787.4 3787.6 3811.5 3815.3 3838.8 5221.6 5239.0 5241.5 5293.2 5326.7 4724.5 4873.9 4911.5 4941.8 4947.6 5549.4 5561.9 5566.0 5577.3 5579.9 4203.6 4207.3 4213.6 4223.0 4228.4 5630.2 5859.5 5862.2 5929.6 5954.2 4182.7 4202.7 4213.5 4217.2 4223.3 4264.8 4274.0 4277.6 4280.4 4298.6 4813.3 4929.3 4937.9 4951.9 4965.6 5752.7 5754.4 5756.9 5769.3 5788.3 1470.0 1472.7 1480.3 1484.7 1486.7 901.7 953.2 958.2 961.2 968.1 5035.3 5057.3 5075.4 5082.8 5086.8 5279.4 5282.3 5288.3 5291.4 5319.9 4950.5 4961.5 4986.6 5264.0 5686.3 3116.8 3120.3 3123.3 3130.6 3139.7 6080.5 6088.2 6096.6 6097.0 6114.4 5733.7 5733.9 6042.4 6048.9 6058.2 5955.2 5982.2 6133.8 6143.0 6156.6 5908.5 5914.9 5921.8 5944.4 5954.7 5562.2 5817.7 5866.4 5874.3 5882.2 6132.0 6140.8 6142.7 6147.8 6157.1 4099.2 4285.5 4312.8 4315.8 4328.7 5843.7 5863.9 5891.9 5898.8 5914.3 5765.5 5797.2 5797.7 5831.6 5835.2 5615.4 5698.6 5742.8 5755.3 5760.9 5688.2 5695.2 5698.9 5719.2 5722.4 5555.7 5788.0 5791.1 5795.6 5796.7 5614.9 5618.9 5652.5 5654.4 5654.7 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - PROCESS | 4020 | [#5] yui.html Cycles:180 Average:21814.67 Median:4977.41 stddev:31028.24 (623.4%) stddev-sans-first:31075.09 03:54:41 INFO - PROCESS | 4020 | Values: 781.2 815.7 818.5 868.2 930.7 15520.4 17685.6 17699.2 17715.0 17723.7 15625.2 15845.4 16020.3 16090.9 16185.3 859.9 874.9 875.9 876.6 876.6 717.2 734.3 862.0 875.7 883.1 927.9 929.9 930.9 931.0 931.1 925.7 927.7 927.8 928.3 928.9 557.9 568.8 646.6 659.8 664.4 13375.2 13870.9 13871.7 13877.1 13891.0 13418.9 13903.2 13980.6 14389.0 14490.6 12992.4 13003.0 13194.7 13221.3 13462.7 4700.9 4750.8 4871.4 4981.1 4997.2 85088.6 85936.0 86499.6 86627.0 87549.0 12171.4 12269.0 12583.2 12744.7 12892.4 24135.3 24297.0 24516.4 24539.5 24588.3 8840.4 8961.9 9011.9 9149.5 9235.7 5021.7 5145.4 5190.8 5266.2 5388.8 3468.8 3593.9 3596.6 3596.9 3609.7 583.9 591.9 595.9 596.6 620.2 548.5 587.5 603.4 629.1 713.6 9992.4 10026.9 10202.2 10360.1 10542.9 616.8 660.0 725.8 736.9 738.9 4968.8 4973.7 4981.1 5063.3 5100.9 77505.0 82175.6 95982.5 134301.7 140207.3 67145.7 97752.0 114441.0 116726.4 117519.4 64464.0 64477.7 88819.6 102492.0 109083.5 9302.5 9556.2 9892.5 9930.4 9999.5 52780.5 54582.0 54636.5 54840.2 55031.2 81515.0 82158.0 83259.6 83828.6 84222.2 14137.9 14218.7 14581.7 14593.5 14823.9 14545.3 15955.6 16286.9 16642.5 17116.1 14690.0 14747.4 15143.7 15659.7 15896.2 14943.6 15153.5 15773.4 15824.0 16055.9 14117.6 14421.9 14847.6 14955.1 15796.7 11871.9 12232.3 12406.6 12968.9 13122.0 10946.6 12689.4 12811.9 13156.7 13209.4 03:54:41 INFO - PROCESS | 4020 | -------- Summary: end -------- 03:54:41 INFO - PROCESS | 4020 | 03:54:41 INFO - TEST-INFO | 4020: exit 0 03:54:41 INFO - TEST-OK | dromaeo_css | took 1115470ms 03:54:41 INFO - TEST-START | kraken 03:54:41 INFO - operating with platform_type : linux_ 03:54:41 INFO - Initialising browser for kraken test... 03:54:41 INFO - TEST-INFO | started process 4335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnhwFR_/profile http://localhost:60557/getInfo.html) 03:54:46 INFO - PROCESS | 4335 | __metrics Screen width/height:1600/1200 03:54:46 INFO - PROCESS | 4335 | colorDepth:24 03:54:46 INFO - PROCESS | 4335 | Browser inner width/height: 1024/697 03:54:46 INFO - PROCESS | 4335 | __metrics 03:54:47 INFO - PROCESS | 4335 | 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" 03:54:47 INFO - PROCESS | 4335 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:54:47 INFO - PROCESS | 4335 | console.error: PushService: 03:54:47 INFO - PROCESS | 4335 | stateChangeProcessEnqueue: Error transitioning state 03:54:47 INFO - PROCESS | 4335 | UnknownError 03:54:48 INFO - TEST-INFO | 4335: exit 0 03:54:48 INFO - Browser initialized. 03:54:48 INFO - Running cycle 1/1 for kraken test... 03:54:48 INFO - TEST-INFO | started process 4429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnhwFR_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:55:00 INFO - PROCESS | 4429 | RSS: Main: 148049920 03:55:00 INFO - PROCESS | 4429 | 03:55:02 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60557/tests/kraken/driver.html?testName=audio-beat-detection) 03:55:03 INFO - PROCESS | 4429 | RSS: Main: 190210048 03:55:03 INFO - PROCESS | 4429 | 03:55:06 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60557/tests/kraken/driver.html?testName=audio-dft) 03:55:06 INFO - PROCESS | 4429 | RSS: Main: 179585024 03:55:06 INFO - PROCESS | 4429 | 03:55:09 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60557/tests/kraken/driver.html?testName=audio-fft) 03:55:10 INFO - PROCESS | 4429 | RSS: Main: 269918208 03:55:10 INFO - PROCESS | 4429 | 03:55:12 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60557/tests/kraken/driver.html?testName=audio-oscillator) 03:55:12 INFO - PROCESS | 4429 | RSS: Main: 177504256 03:55:12 INFO - PROCESS | 4429 | 03:55:14 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60557/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:55:15 INFO - PROCESS | 4429 | RSS: Main: 178323456 03:55:15 INFO - PROCESS | 4429 | 03:55:18 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60557/tests/kraken/driver.html?testName=imaging-darkroom) 03:55:19 INFO - PROCESS | 4429 | RSS: Main: 213569536 03:55:19 INFO - PROCESS | 4429 | 03:55:22 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60557/tests/kraken/driver.html?testName=imaging-desaturate) 03:55:22 INFO - PROCESS | 4429 | RSS: Main: 213401600 03:55:22 INFO - PROCESS | 4429 | 03:55:25 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60557/tests/kraken/driver.html?testName=json-parse-financial) 03:55:26 INFO - PROCESS | 4429 | RSS: Main: 213807104 03:55:26 INFO - PROCESS | 4429 | 03:55:27 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60557/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:55:28 INFO - PROCESS | 4429 | RSS: Main: 175415296 03:55:28 INFO - PROCESS | 4429 | 03:55:30 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:55:30 INFO - PROCESS | 4429 | RSS: Main: 182878208 03:55:30 INFO - PROCESS | 4429 | 03:55:32 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:55:33 INFO - PROCESS | 4429 | RSS: Main: 184905728 03:55:33 INFO - PROCESS | 4429 | 03:55:35 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:55:36 INFO - PROCESS | 4429 | RSS: Main: 185397248 03:55:36 INFO - PROCESS | 4429 | 03:55:39 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:55:39 INFO - PROCESS | 4429 | RSS: Main: 183418880 03:55:39 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | Cycle 1(1): loaded http://localhost:60557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60557/tests/kraken/driver.html?testName=ai-astar) 03:55:41 INFO - PROCESS | 4429 | RSS: Main: 234266624 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | __start_tp_report 03:55:41 INFO - PROCESS | 4429 | _x_x_mozilla_page_load 03:55:41 INFO - PROCESS | 4429 | _x_x_mozilla_page_load_details 03:55:41 INFO - PROCESS | 4429 | |i|pagename|runs| 03:55:41 INFO - PROCESS | 4429 | |0;ai-astar;107;111;110;110;110;110;110;110;108;110 03:55:41 INFO - PROCESS | 4429 | |1;audio-beat-detection;143;116;120;147;119;122;122;121;151;118 03:55:41 INFO - PROCESS | 4429 | |2;audio-dft;160;160;161;160;160;162;159;161;161;159 03:55:41 INFO - PROCESS | 4429 | |3;audio-fft;77;78;76;109;76;76;74;75;98;75 03:55:41 INFO - PROCESS | 4429 | |4;audio-oscillator;81;81;110;82;82;81;101;80;81;125 03:55:41 INFO - PROCESS | 4429 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;117;116 03:55:41 INFO - PROCESS | 4429 | |6;imaging-darkroom;145;146;146;145;146;145;147;145;146;146 03:55:41 INFO - PROCESS | 4429 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;94 03:55:41 INFO - PROCESS | 4429 | |8;json-parse-financial;50;49;49;49;49;50;49;54;49;49 03:55:41 INFO - PROCESS | 4429 | |9;json-stringify-tinderbox;58;58;58;57;57;57;56;57;58;57 03:55:41 INFO - PROCESS | 4429 | |10;stanford-crypto-aes;75;82;73;75;76;79;74;79;77;73 03:55:41 INFO - PROCESS | 4429 | |11;stanford-crypto-ccm;120;127;119;116;117;118;122;132;158;120 03:55:41 INFO - PROCESS | 4429 | |12;stanford-crypto-pbkdf2;152;158;153;154;155;153;152;150;151;150 03:55:41 INFO - PROCESS | 4429 | |13;stanford-crypto-sha256-iterative;62;63;66;61;63;65;66;62;62;61 03:55:41 INFO - PROCESS | 4429 | __end_tp_report 03:55:41 INFO - PROCESS | 4429 | __start_cc_report 03:55:41 INFO - PROCESS | 4429 | _x_x_mozilla_cycle_collect,1725 03:55:41 INFO - PROCESS | 4429 | __end_cc_report 03:55:41 INFO - PROCESS | 4429 | __startTimestamp1474541741601__endTimestamp 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | ------- Summary: start ------- 03:55:41 INFO - PROCESS | 4429 | Number of tests: 14 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:1.17 (1.1%) stddev-sans-first:0.78 03:55:41 INFO - PROCESS | 4429 | Values: 107.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 108.0 110.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:122.00 stddev:13.44 (11.0%) stddev-sans-first:13.09 03:55:41 INFO - PROCESS | 4429 | Values: 143.0 116.0 120.0 147.0 119.0 122.0 122.0 121.0 151.0 118.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 03:55:41 INFO - PROCESS | 4429 | Values: 160.0 160.0 161.0 160.0 160.0 162.0 159.0 161.0 161.0 159.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:11.98 (15.8%) stddev-sans-first:12.60 03:55:41 INFO - PROCESS | 4429 | Values: 77.0 78.0 76.0 109.0 76.0 76.0 74.0 75.0 98.0 75.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:81.00 stddev:15.97 (19.7%) stddev-sans-first:16.58 03:55:41 INFO - PROCESS | 4429 | Values: 81.0 81.0 110.0 82.0 82.0 81.0 101.0 80.0 81.0 125.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 03:55:41 INFO - PROCESS | 4429 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 03:55:41 INFO - PROCESS | 4429 | Values: 145.0 146.0 146.0 145.0 146.0 145.0 147.0 145.0 146.0 146.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 03:55:41 INFO - PROCESS | 4429 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:49.00 stddev:1.57 (3.2%) stddev-sans-first:1.66 03:55:41 INFO - PROCESS | 4429 | Values: 50.0 49.0 49.0 49.0 49.0 50.0 49.0 54.0 49.0 49.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#9] json-stringify-tinderbox Cycles:10 Average:57.30 Median:57.50 stddev:0.67 (1.2%) stddev-sans-first:0.67 03:55:41 INFO - PROCESS | 4429 | Values: 58.0 58.0 58.0 57.0 57.0 57.0 56.0 57.0 58.0 57.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.50 stddev:2.95 (3.9%) stddev-sans-first:3.09 03:55:41 INFO - PROCESS | 4429 | Values: 75.0 82.0 73.0 75.0 76.0 79.0 74.0 79.0 77.0 73.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:121.00 stddev:12.61 (10.4%) stddev-sans-first:13.25 03:55:41 INFO - PROCESS | 4429 | Values: 120.0 127.0 119.0 116.0 117.0 118.0 122.0 132.0 158.0 120.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:153.00 stddev:2.44 (1.6%) stddev-sans-first:2.57 03:55:41 INFO - PROCESS | 4429 | Values: 152.0 158.0 153.0 154.0 155.0 153.0 152.0 150.0 151.0 150.0 03:55:41 INFO - PROCESS | 4429 | 03:55:41 INFO - PROCESS | 4429 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.91 (3.0%) stddev-sans-first:1.99 03:55:41 INFO - PROCESS | 4429 | Values: 62.0 63.0 66.0 61.0 63.0 65.0 66.0 62.0 62.0 61.0 03:55:41 INFO - PROCESS | 4429 | -------- Summary: end -------- 03:55:41 INFO - PROCESS | 4429 | 03:55:42 INFO - TEST-INFO | 4429: exit 0 03:55:42 INFO - TEST-OK | kraken | took 60755ms 03:55:42 INFO - SUITE-END | took 1176s 03:55:42 INFO - Completed test suite (00:19:36) 03:55:42 INFO - javascript benchmark 03:55:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3080.1991482214626, 3124.6562878083414, 3130.0922732407603, 3161.588993430854, 3165.4776961801304, 5513.61404701671, 5574.916376254359, 5616.045272303708, 5622.718864056791, 5628.746706398211, 5577.721113944307, 5646.491815736583, 5663.66018038918, 5669.0, 5692.544596432286, 5045.823396181126, 5104.591632669378, 5137.280780690707, 5173.379194496666, 5181.922271165936, 5071.492850714936, 5132.692038477703, 5183.688978661274, 5193.221016847466, 5194.039102766001, 5010.448850626428, 5028.974855125719, 5038.143515602356, 5061.316722242505, 5070.771815268304, 5121.436641969381, 5133.717645529513, 5146.588272938156, 5199.0, 5230.555402790767, 5034.974825125851, 5097.413797413308, 5164.406093299281, 5182.896342073175, 5207.9479205207845, 5413.0, 5479.753411096471, 5483.75323110457, 5526.171074338841, 5540.944590554083, 5809.651420914759, 5821.53427725781, 5828.88342233153, 5931.4958228550195, 5931.733072011727, 5957.1362152487645, 5962.731677074542, 5970.0, 5977.402259774031, 5977.7608895644125, 5498.037843377393, 5511.641743286674, 5514.80698175562, 5518.862028449281, 5554.222408862763, 6048.334683184846, 6063.363346848606, 6079.331273559905, 6084.148219249309, 6085.5435842312, 5842.853928651818, 5843.503302219274, 5845.912311315334, 5846.269216347957, 5849.912251316234, 5730.656160630376, 5767.596268261265, 5873.177755114288, 5912.497437717842, 5915.112733090071, 5935.2877654681715, 5940.673262970492, 5940.940590594125, 5949.0481522956115, 5971.790987315423, 5886.941130588725, 5944.138099975479, 5944.346121926585, 5945.732442040118, 5973.133895585116, 4481.484629267611, 4525.389072475239, 4535.614472769786, 4552.317152427162, 4561.589456948825, 4055.1241888008703, 4080.714349995472, 4104.343305071234, 4109.835606575703, 4139.461869956946, 5781.0, 5786.71066446676, 5794.681292528867, 5800.738966746422, 5804.4775970162045, 5774.3070831499645, 5788.0449725795615, 5881.323647780475, 6047.425494578008, 6052.061930400795, 5591.7483713233005, 5661.433856614306, 5683.261176047087, 5699.886002279931, 5712.857178570486, 5154.072266991902, 5163.457836927144, 5171.586273098218, 5174.6377753557645, 5200.063988482034, 6014.9699251504335, 6047.9395206047375, 6096.8780624388155, 6112.052631842072, 6131.202943617302, 5917.526597872246, 5929.703514824155, 5962.5528085394635, 6017.247844019497, 6062.818115456544, 6183.501691891366, 6208.875822483616, 6212.751489940532, 6223.751049957951, 6231.688415579293, 5684.8294551163535, 5900.970495147497, 5924.703764811656, 5924.8815023700145, 5954.702264886651, 5766.135079738073, 5975.462208401264, 5995.790147344822, 6013.5189184865985, 6117.877642447215, 5869.090291004731, 5917.644941303535, 5932.406759324033, 5932.525397968066, 5939.851503712442, 5925.140854575976, 5931.881362372815, 5934.19888315072, 5938.792142275, 5940.257467816523, 5572.860678482908, 5674.06377947634, 5779.653220806765, 5798.420157984167, 5827.621204621686, 5960.314563825215, 5970.104484327386, 5973.402659733992, 5979.521638268841, 5984.521238301013, 5633.267675202197, 5678.517326027169, 5806.854828629318, 5812.738426770721, 5822.650640961556], "value": 5504.53059409626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.8736071744577, 610.6275172144991, 620.2464006232427, 643.7296335539073, 650.4763665249475, 33877.153071173234, 45141.0, 46032.53967460324, 46066.769666151624, 46135.76932115339, 47677.28484072733, 48086.0, 48128.27807582889, 48141.0, 48161.0, 8565.48607083574, 9252.352335336529, 9407.38851974623, 9409.247260219166, 9409.670661526849, 8477.109903460141, 10985.956334148243, 11261.718457038558, 11270.09839212861, 11556.133290003241, 11443.656690299264, 11522.481488333004, 11523.827142592867, 11524.596639117633, 11529.827052594175, 11239.831402528887, 11250.437478126061, 11293.604723834625, 11324.320540767498, 11331.490082946288, 11086.0, 11145.052670523015, 11146.331220126818, 11168.329900206014, 11169.944150279196, 44495.555044449466, 44827.55172448266, 44879.0, 44904.3264351035, 45039.774801125786, 3804.7596641985037, 3900.161465284949, 4314.482262128565, 4366.497852746943, 4420.668449866273, 34550.30899382023, 34594.65405345965, 34652.306953861036, 34783.30433391343, 35153.0, 4746.240601503743, 4776.211925032366, 4826.32431459596, 4831.130396528623, 4891.0, 368900.0, 370585.0, 370612.0, 372639.0, 375128.0, 60916.69541652352, 61203.38796611977, 61217.69391153016, 61280.0, 61346.693266534276, 40457.1908561833, 41309.0, 41422.58577414187, 43681.56318436775, 43709.78145109254, 18883.905580472285, 19114.2354305829, 19127.808721912603, 19132.713009304873, 19133.0, 13620.523281685093, 13908.443662253396, 14422.206778627067, 14657.56027319182, 14887.255637218095, 19740.605187896163, 20189.394318170478, 20455.18179272842, 20514.28200013022, 20556.28053018137, 7256.963715181496, 7361.447891408059, 7418.221086785918, 7527.967448788796, 7574.128975167816, 974.7660561465339, 1031.169908223872, 1039.3400190878695, 1086.9021788039115, 1107.9723006924892, 33922.66077339245, 34210.0, 34519.654803451645, 34633.65366346304, 34833.6517426726, 1153.3425947210178, 1158.472894832849, 1173.2198088271205, 1188.0733028237987, 1213.0295763389254, 15250.46623368199, 16602.58493537671, 16666.0, 18109.7283540747, 18498.722519162224, 611737.0, 622341.0, 623140.0, 623532.0, 623649.0, 62574.68712656407, 63059.369406306265, 63087.6845615769, 63214.68392658007, 63286.367136328045, 1309.8362704661918, 1327.9402426890813, 1383.7370899529258, 1423.5871597236892, 1459.5767227504118, 30487.847560762053, 30728.0, 30741.69258307478, 31370.215744605655, 31440.52839207368, 53663.19505207503, 54231.45768542264, 54303.72848135892, 54376.45623543714, 54380.728096359264, 451275.0, 454268.286585676, 454600.0, 454903.0, 455348.0, 30820.0, 31637.20906977344, 31639.0, 31646.683533165295, 33270.0, 51037.0, 51716.0, 51882.481175187764, 51996.74001629968, 52083.958320832615, 43127.35308970305, 44072.55927440685, 44132.33801492916, 44895.32657010213, 45065.0, 38231.0, 38341.80829095837, 39106.217875641756, 39327.0, 40551.797241013606, 42983.570164297955, 43352.56647433485, 43407.0, 43424.78287608668, 43448.78275608602, 32589.18527036843, 34312.828435857664, 34859.651403486656, 34861.30277394387, 34917.12707182341, 26048.609270861332, 26169.214923552325, 26192.8690356547, 26561.867190663925, 26947.326316842235], "value": 21381.750921161965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.393974189361, 421.98945026374355, 430.2062694328963, 432.50910216903804, 439.26861775144386, 15131.31909064092, 19310.0, 19985.100670469827, 20151.09320080596, 20461.590768184626, 17773.55566110848, 18602.0, 18732.34436794709, 18816.0, 18845.52886177846, 5722.084466485363, 6002.8499287517725, 6004.039353703409, 6008.218931538894, 6010.098485227207, 4255.553166917475, 4471.150481408538, 4502.8874278142985, 5903.26209223847, 6535.640539770311, 6608.933910660904, 6614.7023383947835, 6617.602943823386, 6631.403173714365, 6633.0, 6383.648899310536, 6554.9344506555035, 6560.540762146652, 6576.80269591913, 6598.076269322299, 5710.600257981943, 5805.564582656318, 5889.676067816268, 5897.085951677497, 5898.115282707586, 24016.15943441971, 24292.1497747578, 24415.145469908646, 24689.87655061731, 24968.250952471455, 2473.6165894286414, 2525.330787341356, 3041.9087427377212, 3092.443360195164, 3169.969759828065, 15152.772708409382, 15306.464273750365, 15341.309641066178, 15377.846221537866, 16113.516594502184, 1969.3698016634683, 1988.2643421933976, 2074.5643414882893, 2083.4374718825984, 2141.4111119442277, 87169.0, 88215.55892220562, 88852.55573722154, 88895.0, 89134.55432722795, 10825.242233043611, 11923.674883397827, 11940.6268279149, 12047.096467764954, 12070.698232544257, 15308.770368444482, 16166.515004549883, 16400.75398869018, 16411.5897102571, 17154.914225429042, 8678.956605216934, 8781.868271975925, 8899.644014239488, 8928.687495937611, 9003.774905627411, 8531.402801803813, 8534.786630334167, 8583.527905965107, 8692.652293908299, 8773.254273386707, 4964.553190212898, 5114.360704911886, 5117.590592752571, 5138.486151384906, 5182.97408512955, 2071.513194399315, 2084.291340944086, 2086.3428020173596, 2091.8431117666237, 2111.609352269835, 453.99319010214873, 463.59203900567957, 473.2428115015947, 474.3927772451268, 1050.3225419604312, 11851.46668399924, 13071.542496012595, 13135.605931822061, 13222.074454788073, 13240.801387979189, 493.4054464370425, 498.3372115086926, 499.8250612285744, 502.4799332690721, 505.6814207049609, 6763.661816909135, 7029.648517574203, 7032.261612530713, 7201.0, 7226.458015648847, 516418.0, 524194.0, 528233.0, 532909.0, 538641.0, 92729.53635231916, 98181.01818981861, 98694.0, 103294.0, 103347.0, 510.90037442698315, 519.3067255214331, 520.6511637203083, 521.4602886013034, 523.4059342646124, 9761.46311952821, 11678.824817627572, 12119.697007574881, 12330.81503777462, 12503.624891253277, 41026.58973410228, 41734.58265417307, 42404.0, 44003.559964399945, 46349.53650463587, 61250.69374653098, 61539.38460615336, 61760.38239617726, 62154.68922655358, 62969.68515157578, 9003.234725048444, 9095.636174552943, 9137.451752894847, 9175.587098580447, 9418.199453046567, 10327.380357178292, 13458.730825383633, 13666.043376963516, 13749.0, 13954.23251721165, 11312.94343528277, 11614.12894032934, 11882.762344752884, 12125.514979401078, 12456.813147802972, 5723.7424315905055, 10123.64567240143, 10962.451877406258, 11570.826437603275, 11669.416529173677, 11492.310461372344, 11551.018163455865, 11954.880451195377, 11962.76074478523, 12423.751524969632, 8945.284377249674, 9184.862227066465, 9234.907650923406, 9315.534223288943, 9318.534073296443, 8781.91218087811, 11767.823482647596, 11945.820812687643, 11945.820812687643, 12088.15382923222], "value": 8883.278797620005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.8551387618177, 1560.4538411555955, 1697.422876222085, 1779.421687951416, 1835.7705286839146, 8979.775505612362, 9684.903150968488, 9689.32174747768, 9694.951525242364, 9697.27270454716, 8469.745907622766, 8688.044315125348, 8748.825023499527, 8983.371164018521, 9433.764155896106, 2443.499082688049, 2475.8762061896923, 2477.987610061947, 2478.033566908907, 2480.702315722107, 2406.9398265043337, 2457.447074408253, 2457.8893949772305, 2458.4591389894285, 2460.151247819508, 2425.5391475619663, 2439.0, 2445.645381419693, 2451.0, 2451.84063035903, 2407.1935901473116, 2413.5896897527464, 2418.262429958862, 2424.660547523348, 2439.0365805506904, 2362.2558893948353, 2417.2627348658652, 2419.395151212197, 2420.2497225859865, 2422.47916697909, 9410.529473526365, 9553.331266811327, 9572.138507534286, 9575.23398128148, 9577.377470464398, 5213.687178769248, 5348.759305831247, 5353.544948679367, 5360.9463905360835, 5367.43641917597, 3377.257003459235, 3511.631278715749, 3540.451230059345, 3561.626029266916, 3588.3540962626716, 1194.796884529632, 1221.3832014832542, 1222.0651201830606, 1248.0, 1254.8243245945575, 13295.800562991562, 13364.39860206293, 13395.531156409575, 13484.528041518499, 13485.932570337183, 4545.545445455428, 4688.038952014829, 4828.106800241966, 4855.271709243571, 4912.656114072052, 11247.381394023407, 11322.0, 11333.829992550118, 11350.31898086101, 11362.545498180145, 5636.577256705764, 5638.210650508932, 5640.407757185519, 5649.661020338793, 5650.0, 4571.1543364477675, 4720.197566413718, 4728.0, 4897.363342765489, 5184.429712731597, 4236.491621005499, 4285.421468101828, 4418.8453404130705, 4418.889527761831, 4464.281251718112, 1397.3641992893208, 1426.3296250762126, 1435.067206315903, 1438.978415323771, 1449.811524501808, 1015.3146626027402, 1018.221060888421, 1021.9642312519026, 1036.7978244242452, 1067.5036108209565, 5809.970950145223, 5984.308522577063, 6041.426064523863, 6050.092486127116, 6147.66187859663, 491.1674711364203, 491.51585688097197, 498.8478514053176, 503.1597232621531, 509.48796459557906, 4161.771102589326, 4184.267753143188, 4195.244855925963, 4197.769122698281, 4250.51119121305, 10448.641676581996, 10792.946035269773, 10807.67576972692, 10814.945925270324, 10828.72928176786, 10710.678679639623, 10718.0, 10729.9463502682, 10739.570417183382, 10742.89257107419, 8192.836143277102, 8207.0, 8207.179282071864, 8560.828783424296, 8561.101084386175, 6239.625622462667, 6283.7800676976085, 6369.331220221995, 6576.901346479903, 6594.736210551524, 10658.62694805678, 10747.731306717395, 10990.340579565252, 11048.944755276494, 11128.165387596124, 10722.981316774894, 11052.889471105185, 11259.774804504028, 11324.0, 11371.43142842838, 8092.878606820785, 8265.380096492667, 8317.750467485985, 8351.457155284887, 8358.122397148212, 9145.542722863964, 9184.357095003554, 9273.72178834636, 9275.67535136294, 9281.72154835356, 8072.152423995396, 8084.353251739964, 8144.144864789112, 8144.674213031412, 8200.343972482067, 7515.962420187864, 7550.395968322631, 7755.68977240904, 7789.298963093349, 7795.103563090317, 7513.0, 7545.471816972759, 7551.509151905108, 7636.274553917369, 7639.732609358646, 5287.920681189708, 5321.5476684482255, 5486.1222204447095, 5555.333359996826, 5567.387587365468, 5420.53925416344, 5533.225348451139, 5583.274174357308, 5626.127950167731, 5761.251037365116], "value": 4807.648773846594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.42406090103583, 355.443730561671, 356.6415752169069, 356.8857965451057, 361.8516408272609, 5454.581808729729, 5510.448955104493, 5515.862103447411, 5516.365617953927, 5517.282753242083, 5443.176535860492, 5443.836684899449, 5449.264349312852, 5467.0705979983395, 5481.561475081994, 3842.0, 4076.5923407659143, 4137.379393091045, 4234.788260586974, 4313.676474264427, 4285.164392943378, 4292.819474644482, 4298.0, 4312.22379971605, 4313.935290970638, 4253.8085786139545, 4272.914541709164, 4301.784910754465, 4308.2029824482415, 4314.590113939171, 4121.29937910556, 4125.64931980782, 4202.810873510699, 4296.785160741966, 4311.180875633635, 3787.3561494545993, 3787.5833658297565, 3811.5045044144085, 3815.275097731422, 3838.846446142151, 5221.582273418117, 5239.0, 5241.479970808461, 5293.206019097128, 5326.748214169667, 4724.456687480915, 4873.853784386474, 4911.533404326583, 4941.752912354404, 4947.6289278304275, 5549.361823390322, 5561.888762224733, 5565.998120338338, 5577.274954255961, 5579.944200557983, 4203.558626344251, 4207.326827707552, 4213.641840443566, 4222.9788851055855, 4228.4080228768, 5630.239917611111, 5859.53123750099, 5862.179294898718, 5929.636183677779, 5954.19618351517, 4182.728122672017, 4202.705810593229, 4213.5154457237195, 4217.177650358212, 4223.303154979455, 4264.805854360722, 4274.0, 4277.615014648696, 4280.400743895857, 4298.570142985676, 4813.253945638432, 4929.260610908393, 4937.876553086202, 4951.925721114186, 4965.577925876269, 5752.683602401824, 5754.424557544212, 5756.942430575725, 5769.336526299446, 5788.305403351625, 1469.9853001469849, 1472.6686495538631, 1480.326451464581, 1484.6511069898565, 1486.717523670499, 901.7069452427987, 953.1802649721234, 958.1855422890627, 961.245422343457, 968.0851595242569, 5035.320231768665, 5057.291979122926, 5075.441701412841, 5082.796688132434, 5086.77109530072, 5279.392869819943, 5282.31329927107, 5288.286081379041, 5291.417944026154, 5319.86700332487, 4950.504949505021, 4961.50384961501, 4986.576141028053, 5263.973680131575, 5686.34607020198, 3116.750659947199, 3120.2667373167287, 3123.2660324823646, 3130.624325081005, 3139.6860313968878, 6080.543959203077, 6088.178095956989, 6096.603720758137, 6097.0, 6114.370820547208, 5733.655980641091, 5733.913991290134, 6042.365551617105, 6048.850718363585, 6058.242719660043, 5955.196048533479, 5982.162497250389, 6133.754649813957, 6142.999999999911, 6156.630602163795, 5908.497777688945, 5914.911276330945, 5921.792737254175, 5944.405559444021, 5954.702264886651, 5562.2490963721, 5817.738201780845, 5866.413358664099, 5874.295084589877, 5882.176495290577, 6132.0, 6140.785072522441, 6142.662153581596, 6147.7848275311935, 6157.076438534255, 4099.221147981874, 4285.485741711014, 4312.784360782011, 4315.762633055087, 4328.653707703439, 5843.743595127068, 5863.912041319299, 5891.852703682443, 5898.793542226002, 5914.34942156371, 5765.4811067004175, 5797.188393624811, 5797.681127538026, 5831.620944638585, 5835.183074369676, 5615.382307946041, 5698.6010979232715, 5742.827715168552, 5755.251817263729, 5760.884782304247, 5688.208214412447, 5695.202671626059, 5698.943010569841, 5719.2279042328755, 5722.370539240597, 5555.666660000251, 5787.971060144672, 5791.102379131242, 5795.580082879722, 5796.739146738322, 5614.915776263441, 5618.943810562006, 5652.491275785199, 5654.445027617438, 5654.671152279293], "value": 4356.821133644182, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.160252728317, 815.7104227999064, 818.4884447220487, 868.2186032570687, 930.7440453875179, 15520.379184832607, 17685.55786105348, 17699.203535840887, 17715.0, 17723.73414398782, 15625.21873906303, 15845.366185352601, 16020.279087441064, 16090.919545402257, 16185.271662775174, 859.9054104048565, 874.899386570543, 875.9211670949613, 876.6405773632812, 876.6449587916904, 717.196739651591, 734.2620666230431, 861.995690021549, 875.7285241575132, 883.1433509495779, 927.8886533615976, 929.8791157149561, 930.9441433513977, 930.9674161404353, 931.0782325497776, 925.6945208081385, 927.7309580221728, 927.7866090799087, 928.2527565309923, 928.9210417114554, 557.8967890940189, 568.8208214412447, 646.6475770704977, 659.8053574195624, 664.3788058165583, 13375.197488150645, 13870.861291387158, 13871.653208669763, 13877.097988630807, 13891.0, 13418.932905335509, 13903.235322057282, 13980.58058258254, 14389.0, 14490.637734056732, 12992.350382480838, 13003.0, 13194.713376428654, 13221.272829994346, 13462.730745385135, 4700.905981880377, 4750.786214620351, 4871.36672232611, 4981.103401387749, 4997.175466048134, 85088.57455712743, 85936.0, 86499.56750216335, 86627.0, 87549.0, 12171.391430428443, 12269.0, 12583.245005299712, 12744.745105097845, 12892.419841107172, 24135.275941721775, 24297.0, 24516.38709032253, 24539.50920981606, 24588.262352129466, 8840.381173317935, 8961.910380896108, 9011.909880901107, 9149.54252287383, 9235.676751313806, 5021.698698078127, 5145.382554093458, 5190.8183213587345, 5266.210068489757, 5388.757505912244, 3468.7745296555645, 3593.874214402483, 3596.6223546527763, 3596.9280614387567, 3609.7473176877365, 583.9474447299764, 591.8697886464971, 595.8599729063667, 596.582392325374, 620.2154274842328, 548.4926443040172, 587.5152998776027, 603.3513972479602, 629.1443636654146, 713.6039498078616, 9992.400455972665, 10026.899731002597, 10202.183825293958, 10360.119389851796, 10542.947285263677, 616.7748771698298, 659.9571027883172, 725.836686745478, 736.9447291453163, 738.8780851159606, 4968.826091086867, 4973.726445045485, 4981.128302547112, 5063.265826455144, 5100.872478188075, 77505.0, 82175.5891220552, 95982.52008739911, 134301.65698342893, 140207.29896350452, 67145.6642716793, 97752.0, 114441.0, 116726.41636791761, 117519.41240293915, 64464.0, 64477.677611611645, 88819.55590222008, 102492.0, 109083.45458272817, 9302.488363140092, 9556.187724043533, 9892.455914924749, 9930.404175749478, 9999.450030248407, 52780.47219527756, 54582.0, 54636.453635463135, 54840.17739733987, 55031.17453238124, 81515.0, 82158.0, 83259.5837020811, 83828.58085709777, 84222.15777842143, 14137.858621413654, 14218.715625687635, 14581.708365832412, 14593.489227876973, 14823.925880370529, 14545.272736363351, 15955.559984057301, 16286.918565407097, 16642.50072497827, 17116.144192790063, 14690.0, 14747.409487364092, 15143.697126057197, 15659.686806264039, 15896.205189740698, 14943.551693449213, 15153.54539363821, 15773.44792932242, 15824.0, 16055.919720401791, 14117.647058823612, 14421.855781442051, 14847.554573362817, 14955.102693838404, 15796.68406631884, 11871.940640296743, 12232.327222002876, 12406.62780116598, 12968.870311296767, 13122.015848811569, 10946.56213751473, 12689.36553172356, 12811.935940320238, 13156.736865262832, 13209.405576748877], "value": 8010.863122769554, "unit": "score"}], "name": "dromaeo_css", "value": 7482.16209143792, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 108.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 120.0, 147.0, 119.0, 122.0, 122.0, 121.0, 151.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 160.0, 160.0, 162.0, 159.0, 161.0, 161.0, 159.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 109.0, 76.0, 76.0, 74.0, 75.0, 98.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 110.0, 82.0, 82.0, 81.0, 101.0, 80.0, 81.0, 125.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 49.0, 50.0, 49.0, 54.0, 49.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 57.0, 57.0, 57.0, 56.0, 57.0, 58.0, 57.0], "value": 57.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 73.0, 75.0, 76.0, 79.0, 74.0, 79.0, 77.0, 73.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 127.0, 119.0, 116.0, 117.0, 118.0, 122.0, 132.0, 158.0, 120.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 158.0, 153.0, 154.0, 155.0, 153.0, 152.0, 150.0, 151.0, 150.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 66.0, 61.0, 63.0, 65.0, 66.0, 62.0, 62.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1451.1, "alertThreshold": 2.0}]} 03:55:42 INFO - Return code: 0 03:55:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:55:42 INFO - # TBPL SUCCESS # 03:55:42 INFO - Running post-action listener: _resource_record_post_action 03:55:42 INFO - [mozharness: 2016-09-22 10:55:42.941032Z] Finished run-tests step (success) 03:55:42 INFO - Running post-run listener: _resource_record_post_run 03:55:43 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 370507776; Read time: 36; Write time: 411416 03:55:43 INFO - TinderboxPrint: CPU usage
13.0% 03:55:43 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 36 03:55:43 INFO - TinderboxPrint: I/O write bytes / time
370,507,776 / 411,416 03:55:43 INFO - TinderboxPrint: CPU idle
8,278.1 (87.0%) 03:55:43 INFO - TinderboxPrint: CPU user
1,185.2 (12.5%) 03:55:43 INFO - TinderboxPrint: Swap in / out
0 / 0 03:55:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 108158976; Read time: 0; Write time: 169492 03:55:43 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 262144; Write bytes: 244858880; Read time: 20; Write time: 236464 03:55:43 INFO - Running post-run listener: _upload_blobber_files 03:55:43 INFO - Blob upload gear active. 03:55:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:55:43 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 03:55:43 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'] 03:55:43 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 03:55:43 INFO - (blobuploader) - INFO - Open directory for files ... 03:55:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:55:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:55:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:44 INFO - (blobuploader) - INFO - Done attempting. 03:55:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:55:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:55:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:45 INFO - (blobuploader) - INFO - Done attempting. 03:55:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:55:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:55:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:55:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:55:49 INFO - (blobuploader) - INFO - Done attempting. 03:55:49 INFO - (blobuploader) - INFO - Iteration through files over. 03:55:49 INFO - Return code: 0 03:55:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:55:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:55:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1598ba71e12d93f6f6a3b945ad323412afa23452ad48b188bea7fb88ffcd2225c3ccffe27ff62b51691fea23cfcf7a578ff6141cd3aec765ebe9d9eec76afa7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e69fcefbe23a1439dfee31c95f44b49d20f74000fe434146285de1e58316e1875824eacc0ea04c1f58919d3959c84888e5599e53813655ce61796b91293267", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9b70b502f8f2f630d7100a94d9158ab7af5024a86f95d3685e2538e1668b40314d318a98cb63bfd183890c3ecd3f83d33fa5f4e9d519955c1dab142be6104c"} 03:55:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:55:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:55:49 INFO - Contents: 03:55:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1598ba71e12d93f6f6a3b945ad323412afa23452ad48b188bea7fb88ffcd2225c3ccffe27ff62b51691fea23cfcf7a578ff6141cd3aec765ebe9d9eec76afa7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e69fcefbe23a1439dfee31c95f44b49d20f74000fe434146285de1e58316e1875824eacc0ea04c1f58919d3959c84888e5599e53813655ce61796b91293267", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9b70b502f8f2f630d7100a94d9158ab7af5024a86f95d3685e2538e1668b40314d318a98cb63bfd183890c3ecd3f83d33fa5f4e9d519955c1dab142be6104c"} 03:55:49 INFO - Running post-run listener: copy_logs_to_upload_dir 03:55:49 INFO - Copying logs to upload dir... 03:55:49 INFO - mkdir: /builds/slave/test/build/upload/logs 03:55:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.165368 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-22 03:55:48.776183) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 03:55:48.780112) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1598ba71e12d93f6f6a3b945ad323412afa23452ad48b188bea7fb88ffcd2225c3ccffe27ff62b51691fea23cfcf7a578ff6141cd3aec765ebe9d9eec76afa7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e69fcefbe23a1439dfee31c95f44b49d20f74000fe434146285de1e58316e1875824eacc0ea04c1f58919d3959c84888e5599e53813655ce61796b91293267", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9b70b502f8f2f630d7100a94d9158ab7af5024a86f95d3685e2538e1668b40314d318a98cb63bfd183890c3ecd3f83d33fa5f4e9d519955c1dab142be6104c"} build_url:https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/Nk1AfDrVS-eVGEAJhhJSFA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1598ba71e12d93f6f6a3b945ad323412afa23452ad48b188bea7fb88ffcd2225c3ccffe27ff62b51691fea23cfcf7a578ff6141cd3aec765ebe9d9eec76afa7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e69fcefbe23a1439dfee31c95f44b49d20f74000fe434146285de1e58316e1875824eacc0ea04c1f58919d3959c84888e5599e53813655ce61796b91293267", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf9b70b502f8f2f630d7100a94d9158ab7af5024a86f95d3685e2538e1668b40314d318a98cb63bfd183890c3ecd3f83d33fa5f4e9d519955c1dab142be6104c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 03:55:48.806036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 03:55:48.806382) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474539980.357545-874577000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 03:55:48.892567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 03:55:48.893371) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 03:55:48.894167) ========= ========= Total master_lag: 0.21 =========