builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1474319362.86 results: success (0) buildid: 20160919123029 builduid: 6e9096f2559a43249a190996a0ec4034 revision: 1fdbce2821b589bff8e03407712646f7c6716756 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:22.860610) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:22.861103) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:22.861441) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:22.876565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:22.876974) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 14:09:22-- 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% 221M=0s 2016-09-19 14:09:23 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.238340 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.129351) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.129706) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020305 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.179819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.180218) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1fdbce2821b589bff8e03407712646f7c6716756 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 1fdbce2821b589bff8e03407712646f7c6716756 --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=a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 14:09:23,312 truncating revision to first 12 chars 2016-09-19 14:09:23,312 Setting DEBUG logging. 2016-09-19 14:09:23,312 attempt 1/10 2016-09-19 14:09:23,312 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1fdbce2821b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 14:09:23,615 unpacking tar archive at: autoland-1fdbce2821b5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631751 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.825414) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.825732) ========= script_repo_revision: 1fdbce2821b589bff8e03407712646f7c6716756 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.826117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.826511) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 14:09:23.835136) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-19 14:09:23.835492) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:09:24 INFO - MultiFileLogger online at 20160919 14:09:24 in /builds/slave/test 14:09:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 14:09:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:09:24 INFO - {'append_to_log': False, 14:09:24 INFO - 'base_work_dir': '/builds/slave/test', 14:09:24 INFO - 'blob_upload_branch': 'Autoland', 14:09:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:09:24 INFO - 'branch': 'Autoland', 14:09:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:09:24 INFO - 'config_files': ('talos/linux_config.py',), 14:09:24 INFO - 'default_actions': ('clobber', 14:09:24 INFO - 'read-buildbot-config', 14:09:24 INFO - 'download-and-extract', 14:09:24 INFO - 'populate-webroot', 14:09:24 INFO - 'create-virtualenv', 14:09:24 INFO - 'install', 14:09:24 INFO - 'run-tests'), 14:09:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:09:24 INFO - 'download_minidump_stackwalk': True, 14:09:24 INFO - 'download_symbols': 'ondemand', 14:09:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:09:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:09:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:09:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:09:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:09:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:09:24 INFO - 'installer_path': 'installer.exe', 14:09:24 INFO - 'log_level': 'info', 14:09:24 INFO - 'log_name': 'talos', 14:09:24 INFO - 'log_to_console': True, 14:09:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:09:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:09:24 INFO - 'opt_config_files': (), 14:09:24 INFO - 'pip_index': False, 14:09:24 INFO - 'sps_profile': False, 14:09:24 INFO - 'sps_profile_interval': 0, 14:09:24 INFO - 'suite': 'dromaeojs', 14:09:24 INFO - 'system_bits': '32', 14:09:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:09:24 INFO - 'title': 'talos-linux64-ix-062', 14:09:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:09:24 INFO - 'use_talos_json': True, 14:09:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:09:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:09:24 INFO - 'work_dir': 'build'} 14:09:24 INFO - [mozharness: 2016-09-19 21:09:24.060034Z] Running clobber step. 14:09:24 INFO - Running pre-action listener: _resource_record_pre_action 14:09:24 INFO - Running main action method: clobber 14:09:24 INFO - rmtree: /builds/slave/test/build 14:09:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:09:24 INFO - Running post-action listener: _resource_record_post_action 14:09:24 INFO - [mozharness: 2016-09-19 21:09:24.511551Z] Finished clobber step (success) 14:09:24 INFO - [mozharness: 2016-09-19 21:09:24.511652Z] Running read-buildbot-config step. 14:09:24 INFO - Running pre-action listener: _resource_record_pre_action 14:09:24 INFO - Running main action method: read_buildbot_config 14:09:24 INFO - Using buildbot properties: 14:09:24 INFO - { 14:09:24 INFO - "project": "", 14:09:24 INFO - "product": "firefox", 14:09:24 INFO - "script_repo_revision": "production", 14:09:24 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 14:09:24 INFO - "repository": "", 14:09:24 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 14:09:24 INFO - "buildid": "20160919123029", 14:09:24 INFO - "pgo_build": "True", 14:09:24 INFO - "basedir": "/builds/slave/test", 14:09:24 INFO - "buildnumber": 217, 14:09:24 INFO - "slavename": "talos-linux64-ix-062", 14:09:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:09:24 INFO - "platform": "ubuntu64_hw", 14:09:24 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 14:09:24 INFO - "branch": "autoland", 14:09:24 INFO - "repo_path": "integration/autoland", 14:09:24 INFO - "revision": "1fdbce2821b589bff8e03407712646f7c6716756", 14:09:24 INFO - "stage_platform": "linux64-pgo", 14:09:24 INFO - "builduid": "6e9096f2559a43249a190996a0ec4034", 14:09:24 INFO - "slavebuilddir": "test" 14:09:24 INFO - } 14:09:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 14:09:24 INFO - Running post-action listener: _resource_record_post_action 14:09:24 INFO - [mozharness: 2016-09-19 21:09:24.514144Z] Finished read-buildbot-config step (success) 14:09:24 INFO - [mozharness: 2016-09-19 21:09:24.514233Z] Running download-and-extract step. 14:09:24 INFO - Running pre-action listener: _resource_record_pre_action 14:09:24 INFO - Running main action method: download_and_extract 14:09:24 INFO - mkdir: /builds/slave/test/build/tests 14:09:24 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')]} 14:09:24 INFO - trying https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:09:24 INFO - Downloading https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:09:25 INFO - Downloaded 1361 bytes. 14:09:25 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:09:25 INFO - Using the following test package requirements: 14:09:25 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:09:25 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:09:25 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:09:25 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'jsshell-linux-x86_64.zip'], 14:09:25 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:09:25 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:09:25 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:09:25 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:09:25 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:09:25 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:09:25 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:09:25 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:09:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:09:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:09:25 INFO - Fetch https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:09:25 INFO - Expected file size: 21940024 14:09:29 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:09:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:09:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:09:29 INFO - Fetch https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:09:30 INFO - Expected file size: 21940024 14:09:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:09:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:09:35 INFO - Fetch https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:09:35 INFO - Expected file size: 14414059 14:09: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')]} 14:09:36 INFO - trying https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:09:36 INFO - Downloading https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:09:39 INFO - Downloaded 59563602 bytes. 14:09:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:09:39 INFO - mkdir: /builds/slave/test/properties 14:09:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:39 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:39 INFO - Contents: 14:09:39 INFO - build_url:https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:09:40 INFO - Running post-action listener: _resource_record_post_action 14:09:40 INFO - Running post-action listener: set_extra_try_arguments 14:09:40 INFO - [mozharness: 2016-09-19 21:09:40.671217Z] Finished download-and-extract step (success) 14:09:40 INFO - [mozharness: 2016-09-19 21:09:40.671448Z] Running populate-webroot step. 14:09:40 INFO - Running pre-action listener: _resource_record_pre_action 14:09:40 INFO - Running main action method: populate_webroot 14:09:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:09:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:09:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:09:40 INFO - '--tppagecycles', 14:09:40 INFO - '7'], 14:09:40 INFO - 'tests': ['tsvgm']}}, 14:09:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:09:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:09:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['damp', 'tps']}, 14:09:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'tests': ['damp', 'tps']}, 14:09:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['dromaeo_dom']}, 14:09:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['basic_compositor_video']}, 14:09:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:09:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tsvgx', 14:09:40 INFO - 'tsvgr_opacity', 14:09:40 INFO - 'tart', 14:09:40 INFO - 'tscrollx', 14:09:40 INFO - 'cart']}, 14:09:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:40 INFO - 'tsvgr_opacity', 14:09:40 INFO - 'tart', 14:09:40 INFO - 'tscrollx', 14:09:40 INFO - 'cart']}, 14:09:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tp5o']}, 14:09:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'tests': ['tp5o']}, 14:09:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s', 14:09:40 INFO - '--xperf_path', 14:09:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:40 INFO - 'tests': ['tp5n']}, 14:09:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--xperf_path', 14:09:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:40 INFO - 'tests': ['tp5n']}}, 14:09:40 INFO - 'talos.zip': {'path': '', 14:09:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:09:40 INFO - Running post-action listener: _resource_record_post_action 14:09:40 INFO - [mozharness: 2016-09-19 21:09:40.688683Z] Finished populate-webroot step (success) 14:09:40 INFO - [mozharness: 2016-09-19 21:09:40.688773Z] Running create-virtualenv step. 14:09:40 INFO - Running pre-action listener: _resource_record_pre_action 14:09:40 INFO - Running main action method: create_virtualenv 14:09:40 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:40 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 14:09:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:40 INFO - Using real prefix '/usr' 14:09:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:41 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:43 INFO - Installing pip.................done. 14:09:43 INFO - Return code: 0 14:09:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09:43 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')]} 14:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:43 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')]} 14:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:43 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:43 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 14:09:43 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 14:09:43 INFO - Using env: {'DISPLAY': ':0', 14:09:43 INFO - 'HOME': '/home/cltbld', 14:09:43 INFO - 'LANG': 'en_US.UTF-8', 14:09:43 INFO - 'LANGUAGE': 'en_US:en', 14:09:43 INFO - 'LOGNAME': 'cltbld', 14:09:43 INFO - 'MAIL': '/var/mail/cltbld', 14:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:43 INFO - 'MOZ_NO_REMOTE': '1', 14:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:43 INFO - 'NO_EM_RESTART': '1', 14:09:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:43 INFO - 'PWD': '/builds/slave/test', 14:09:43 INFO - 'SHELL': '/bin/bash', 14:09:43 INFO - 'SHLVL': '1', 14:09:43 INFO - 'TERM': 'linux', 14:09:43 INFO - 'TMOUT': '86400', 14:09:43 INFO - 'USER': 'cltbld', 14:09:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:43 INFO - '_': '/tools/buildbot/bin/python'} 14:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:43 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 14:09:43 INFO - Cleaning up... 14:09:43 INFO - Return code: 0 14:09:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:09:43 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')]} 14:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:43 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')]} 14:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:43 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:43 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 14:09:43 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 14:09:43 INFO - Using env: {'DISPLAY': ':0', 14:09:43 INFO - 'HOME': '/home/cltbld', 14:09:43 INFO - 'LANG': 'en_US.UTF-8', 14:09:43 INFO - 'LANGUAGE': 'en_US:en', 14:09:43 INFO - 'LOGNAME': 'cltbld', 14:09:43 INFO - 'MAIL': '/var/mail/cltbld', 14:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:43 INFO - 'MOZ_NO_REMOTE': '1', 14:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:43 INFO - 'NO_EM_RESTART': '1', 14:09:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:43 INFO - 'PWD': '/builds/slave/test', 14:09:43 INFO - 'SHELL': '/bin/bash', 14:09:43 INFO - 'SHLVL': '1', 14:09:43 INFO - 'TERM': 'linux', 14:09:43 INFO - 'TMOUT': '86400', 14:09:43 INFO - 'USER': 'cltbld', 14:09:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:43 INFO - '_': '/tools/buildbot/bin/python'} 14:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:43 INFO - Downloading/unpacking psutil>=3.1.1 14:09:43 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 14:09:43 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 14:09:43 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 14:09:43 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 14:09:43 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 14:09:43 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 14:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:45 INFO - Installing collected packages: psutil 14:09:45 INFO - Running setup.py install for psutil 14:09:45 INFO - building 'psutil._psutil_linux' extension 14:09:45 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 14:09:45 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 14:09:45 INFO - building 'psutil._psutil_posix' extension 14:09:45 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 14:09:46 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 14:09:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:46 INFO - Successfully installed psutil 14:09:46 INFO - Cleaning up... 14:09:46 INFO - Return code: 0 14:09:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:09:46 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')]} 14:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:46 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')]} 14:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:46 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:46 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 14:09:46 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 14:09:46 INFO - Using env: {'DISPLAY': ':0', 14:09:46 INFO - 'HOME': '/home/cltbld', 14:09:46 INFO - 'LANG': 'en_US.UTF-8', 14:09:46 INFO - 'LANGUAGE': 'en_US:en', 14:09:46 INFO - 'LOGNAME': 'cltbld', 14:09:46 INFO - 'MAIL': '/var/mail/cltbld', 14:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:46 INFO - 'MOZ_NO_REMOTE': '1', 14:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:46 INFO - 'NO_EM_RESTART': '1', 14:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:46 INFO - 'PWD': '/builds/slave/test', 14:09:46 INFO - 'SHELL': '/bin/bash', 14:09:46 INFO - 'SHLVL': '1', 14:09:46 INFO - 'TERM': 'linux', 14:09:46 INFO - 'TMOUT': '86400', 14:09:46 INFO - 'USER': 'cltbld', 14:09:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:46 INFO - '_': '/tools/buildbot/bin/python'} 14:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:09:46 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 14:09:46 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 14:09:46 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 14:09:46 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 14:09:46 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 14:09:46 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 14:09:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:09:48 INFO - Installing collected packages: mozsystemmonitor 14:09:48 INFO - Running setup.py install for mozsystemmonitor 14:09:48 INFO - Successfully installed mozsystemmonitor 14:09:48 INFO - Cleaning up... 14:09:48 INFO - Return code: 0 14:09:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:09:48 INFO - Using env: {'DISPLAY': ':0', 14:09:48 INFO - 'HOME': '/home/cltbld', 14:09:48 INFO - 'LANG': 'en_US.UTF-8', 14:09:48 INFO - 'LANGUAGE': 'en_US:en', 14:09:48 INFO - 'LOGNAME': 'cltbld', 14:09:48 INFO - 'MAIL': '/var/mail/cltbld', 14:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:48 INFO - 'MOZ_NO_REMOTE': '1', 14:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:48 INFO - 'NO_EM_RESTART': '1', 14:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:48 INFO - 'PWD': '/builds/slave/test', 14:09:48 INFO - 'SHELL': '/bin/bash', 14:09:48 INFO - 'SHLVL': '1', 14:09:48 INFO - 'TERM': 'linux', 14:09:48 INFO - 'TMOUT': '86400', 14:09:48 INFO - 'USER': 'cltbld', 14:09:48 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:48 INFO - '_': '/tools/buildbot/bin/python'} 14:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:48 INFO - Downloading/unpacking jsonschema==2.5.1 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:50 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:50 INFO - warning: no files found matching '*.txt' 14:09:50 INFO - no previously-included directories found matching 'build' 14:09:50 INFO - no previously-included directories found matching 'dist' 14:09:50 INFO - no previously-included directories found matching '.git*' 14:09:50 INFO - Installing collected packages: jsonschema, functools32 14:09:50 INFO - Running setup.py install for functools32 14:09:51 INFO - warning: no files found matching '*.txt' 14:09:51 INFO - no previously-included directories found matching 'build' 14:09:51 INFO - no previously-included directories found matching 'dist' 14:09:51 INFO - no previously-included directories found matching '.git*' 14:09:51 INFO - Successfully installed jsonschema functools32 14:09:51 INFO - Cleaning up... 14:09:51 INFO - Return code: 0 14:09:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:09:51 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')]} 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:51 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')]} 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:51 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:51 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 14:09:51 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 14:09:51 INFO - Using env: {'DISPLAY': ':0', 14:09:51 INFO - 'HOME': '/home/cltbld', 14:09:51 INFO - 'LANG': 'en_US.UTF-8', 14:09:51 INFO - 'LANGUAGE': 'en_US:en', 14:09:51 INFO - 'LOGNAME': 'cltbld', 14:09:51 INFO - 'MAIL': '/var/mail/cltbld', 14:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:51 INFO - 'MOZ_NO_REMOTE': '1', 14:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:51 INFO - 'NO_EM_RESTART': '1', 14:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:51 INFO - 'PWD': '/builds/slave/test', 14:09:51 INFO - 'SHELL': '/bin/bash', 14:09:51 INFO - 'SHLVL': '1', 14:09:51 INFO - 'TERM': 'linux', 14:09:51 INFO - 'TMOUT': '86400', 14:09:51 INFO - 'USER': 'cltbld', 14:09:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:51 INFO - '_': '/tools/buildbot/bin/python'} 14:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:09:51 INFO - Cleaning up... 14:09:51 INFO - Return code: 0 14:09:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:51 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')]} 14:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:51 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')]} 14:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:51 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:51 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 14:09:51 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 14:09:51 INFO - Using env: {'DISPLAY': ':0', 14:09:51 INFO - 'HOME': '/home/cltbld', 14:09:51 INFO - 'LANG': 'en_US.UTF-8', 14:09:51 INFO - 'LANGUAGE': 'en_US:en', 14:09:51 INFO - 'LOGNAME': 'cltbld', 14:09:51 INFO - 'MAIL': '/var/mail/cltbld', 14:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:51 INFO - 'MOZ_NO_REMOTE': '1', 14:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:51 INFO - 'NO_EM_RESTART': '1', 14:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:51 INFO - 'PWD': '/builds/slave/test', 14:09:51 INFO - 'SHELL': '/bin/bash', 14:09:51 INFO - 'SHLVL': '1', 14:09:51 INFO - 'TERM': 'linux', 14:09:51 INFO - 'TMOUT': '86400', 14:09:51 INFO - 'USER': 'cltbld', 14:09:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:51 INFO - '_': '/tools/buildbot/bin/python'} 14:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:51 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:51 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 14:09:51 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 14:09:51 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 14:09:51 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 14:09:51 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 14:09:51 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 14:09:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 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 14:09:53 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 14:09:54 INFO - Downloading docopt-0.6.1.tar.gz 14:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:54 INFO - Running setup.py install for blobuploader 14:09:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:54 INFO - Running setup.py install for requests 14:09:54 INFO - Running setup.py install for docopt 14:09:54 INFO - Successfully installed blobuploader requests docopt 14:09:54 INFO - Cleaning up... 14:09:55 INFO - Return code: 0 14:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:55 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:55 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 14:09:55 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 14:09:55 INFO - Using env: {'DISPLAY': ':0', 14:09:55 INFO - 'HOME': '/home/cltbld', 14:09:55 INFO - 'LANG': 'en_US.UTF-8', 14:09:55 INFO - 'LANGUAGE': 'en_US:en', 14:09:55 INFO - 'LOGNAME': 'cltbld', 14:09:55 INFO - 'MAIL': '/var/mail/cltbld', 14:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:55 INFO - 'MOZ_NO_REMOTE': '1', 14:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:55 INFO - 'NO_EM_RESTART': '1', 14:09:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:55 INFO - 'PWD': '/builds/slave/test', 14:09:55 INFO - 'SHELL': '/bin/bash', 14:09:55 INFO - 'SHLVL': '1', 14:09:55 INFO - 'TERM': 'linux', 14:09:55 INFO - 'TMOUT': '86400', 14:09:55 INFO - 'USER': 'cltbld', 14:09:55 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:55 INFO - '_': '/tools/buildbot/bin/python'} 14:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:55 INFO - Running setup.py (path:/tmp/pip-2vzUVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:55 INFO - Running setup.py (path:/tmp/pip-9H4m_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:55 INFO - Running setup.py (path:/tmp/pip-6rvSLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:55 INFO - Running setup.py (path:/tmp/pip-oGcuHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:55 INFO - Running setup.py (path:/tmp/pip-57jGRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:55 INFO - Running setup.py (path:/tmp/pip-MPTosz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:55 INFO - Running setup.py (path:/tmp/pip-7XrYzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:55 INFO - Running setup.py (path:/tmp/pip-yFVCrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:55 INFO - Running setup.py (path:/tmp/pip-cZ0xDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:55 INFO - Running setup.py (path:/tmp/pip-MeijTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:55 INFO - Running setup.py (path:/tmp/pip-u2GiuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:56 INFO - Running setup.py (path:/tmp/pip-UjFlbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:56 INFO - Running setup.py (path:/tmp/pip-ylt4HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:56 INFO - Running setup.py (path:/tmp/pip-xz0Sj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:56 INFO - Running setup.py (path:/tmp/pip-OjXakO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:56 INFO - Running setup.py (path:/tmp/pip-QGJolT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:56 INFO - Running setup.py (path:/tmp/pip-sNM3FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:56 INFO - Running setup.py install for manifestparser 14:09:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:56 INFO - Running setup.py install for mozcrash 14:09:56 INFO - Running setup.py install for mozdebug 14:09:56 INFO - Running setup.py install for mozdevice 14:09:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:09:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:09:56 INFO - Running setup.py install for mozfile 14:09:57 INFO - Running setup.py install for mozhttpd 14:09:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Running setup.py install for mozinfo 14:09:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Running setup.py install for mozInstall 14:09:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Running setup.py install for mozleak 14:09:57 INFO - Running setup.py install for mozlog 14:09:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Running setup.py install for moznetwork 14:09:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Running setup.py install for mozprocess 14:09:57 INFO - Running setup.py install for mozprofile 14:09:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:09:57 INFO - Running setup.py install for mozrunner 14:09:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:09:58 INFO - Running setup.py install for mozscreenshot 14:09:58 INFO - Running setup.py install for moztest 14:09:58 INFO - Running setup.py install for mozversion 14:09:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:09:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:58 INFO - Cleaning up... 14:09:58 INFO - Return code: 0 14:09:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:58 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')]} 14:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:58 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')]} 14:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:58 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:09:58 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 14:09:58 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 14:09:58 INFO - Using env: {'DISPLAY': ':0', 14:09:58 INFO - 'HOME': '/home/cltbld', 14:09:58 INFO - 'LANG': 'en_US.UTF-8', 14:09:58 INFO - 'LANGUAGE': 'en_US:en', 14:09:58 INFO - 'LOGNAME': 'cltbld', 14:09:58 INFO - 'MAIL': '/var/mail/cltbld', 14:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:58 INFO - 'MOZ_NO_REMOTE': '1', 14:09:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:58 INFO - 'NO_EM_RESTART': '1', 14:09:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:58 INFO - 'PWD': '/builds/slave/test', 14:09:58 INFO - 'SHELL': '/bin/bash', 14:09:58 INFO - 'SHLVL': '1', 14:09:58 INFO - 'TERM': 'linux', 14:09:58 INFO - 'TMOUT': '86400', 14:09:58 INFO - 'USER': 'cltbld', 14:09:58 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:58 INFO - '_': '/tools/buildbot/bin/python'} 14:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:58 INFO - Running setup.py (path:/tmp/pip-cLP6Rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:58 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)) 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:58 INFO - Running setup.py (path:/tmp/pip-tTmb4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:58 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)) 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:58 INFO - Running setup.py (path:/tmp/pip-7K831P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:58 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)) 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:58 INFO - Running setup.py (path:/tmp/pip-q3DLq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:58 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)) 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:58 INFO - Running setup.py (path:/tmp/pip-sxQ5hD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:58 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)) 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:58 INFO - Running setup.py (path:/tmp/pip-DOBuoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:58 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)) 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:58 INFO - Running setup.py (path:/tmp/pip-D5EVwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:59 INFO - Running setup.py (path:/tmp/pip-W62mJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:59 INFO - Running setup.py (path:/tmp/pip-egfbuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:59 INFO - Running setup.py (path:/tmp/pip-sIUbnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:59 INFO - Running setup.py (path:/tmp/pip-53ZxqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:59 INFO - Running setup.py (path:/tmp/pip-WS_lMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:59 INFO - Running setup.py (path:/tmp/pip-_KNEgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:59 INFO - Running setup.py (path:/tmp/pip-RkcIQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:59 INFO - Running setup.py (path:/tmp/pip-DA28iT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:59 INFO - Running setup.py (path:/tmp/pip-5Cz27i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:59 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)) 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:59 INFO - Running setup.py (path:/tmp/pip-lI0_F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:59 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)) 14:09:59 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)) 14:09:59 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)) 14:09:59 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)) 14:09:59 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)) 14:09:59 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)) 14:09:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:09:59 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 14:09:59 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 14:09:59 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 14:09:59 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 14:09:59 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 14:09:59 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 14:10:01 INFO - Downloading blessings-1.6.tar.gz 14:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:10:01 INFO - Installing collected packages: blessings 14:10:01 INFO - Running setup.py install for blessings 14:10:01 INFO - Successfully installed blessings 14:10:01 INFO - Cleaning up... 14:10:01 INFO - Return code: 0 14:10:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:10:02 INFO - Reading from file tmpfile_stdout 14:10:02 INFO - Current package versions: 14:10:02 INFO - argparse == 1.2.1 14:10:02 INFO - blessings == 1.6 14:10:02 INFO - blobuploader == 1.2.4 14:10:02 INFO - docopt == 0.6.1 14:10:02 INFO - functools32 == 3.2.3-2 14:10:02 INFO - jsonschema == 2.5.1 14:10:02 INFO - manifestparser == 1.1 14:10:02 INFO - mozInstall == 1.12 14:10:02 INFO - mozcrash == 0.17 14:10:02 INFO - mozdebug == 0.1 14:10:02 INFO - mozdevice == 0.48 14:10:02 INFO - mozfile == 1.2 14:10:02 INFO - mozhttpd == 0.7 14:10:02 INFO - mozinfo == 0.9 14:10:02 INFO - mozleak == 0.1 14:10:02 INFO - mozlog == 3.4 14:10:02 INFO - moznetwork == 0.27 14:10:02 INFO - mozprocess == 0.23 14:10:02 INFO - mozprofile == 0.28 14:10:02 INFO - mozrunner == 6.12 14:10:02 INFO - mozscreenshot == 0.1 14:10:02 INFO - mozsystemmonitor == 0.3 14:10:02 INFO - moztest == 0.7 14:10:02 INFO - mozversion == 1.4 14:10:02 INFO - psutil == 3.1.1 14:10:02 INFO - requests == 1.2.3 14:10:02 INFO - wsgiref == 0.1.2 14:10:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:02 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 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:10:02 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 14:10:02 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 14:10:02 INFO - Using env: {'DISPLAY': ':0', 14:10:02 INFO - 'HOME': '/home/cltbld', 14:10:02 INFO - 'LANG': 'en_US.UTF-8', 14:10:02 INFO - 'LANGUAGE': 'en_US:en', 14:10:02 INFO - 'LOGNAME': 'cltbld', 14:10:02 INFO - 'MAIL': '/var/mail/cltbld', 14:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:02 INFO - 'MOZ_NO_REMOTE': '1', 14:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:02 INFO - 'NO_EM_RESTART': '1', 14:10:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:02 INFO - 'PWD': '/builds/slave/test', 14:10:02 INFO - 'SHELL': '/bin/bash', 14:10:02 INFO - 'SHLVL': '1', 14:10:02 INFO - 'TERM': 'linux', 14:10:02 INFO - 'TMOUT': '86400', 14:10:02 INFO - 'USER': 'cltbld', 14:10:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:02 INFO - '_': '/tools/buildbot/bin/python'} 14:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 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)) 14:10:02 INFO - Cleaning up... 14:10:02 INFO - Return code: 0 14:10:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ed6df3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', '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 14:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:10:02 INFO - Using env: {'DISPLAY': ':0', 14:10:02 INFO - 'HOME': '/home/cltbld', 14:10:02 INFO - 'LANG': 'en_US.UTF-8', 14:10:02 INFO - 'LANGUAGE': 'en_US:en', 14:10:02 INFO - 'LOGNAME': 'cltbld', 14:10:02 INFO - 'MAIL': '/var/mail/cltbld', 14:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:02 INFO - 'MOZ_NO_REMOTE': '1', 14:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:02 INFO - 'NO_EM_RESTART': '1', 14:10:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:02 INFO - 'PWD': '/builds/slave/test', 14:10:02 INFO - 'SHELL': '/bin/bash', 14:10:02 INFO - 'SHLVL': '1', 14:10:02 INFO - 'TERM': 'linux', 14:10:02 INFO - 'TMOUT': '86400', 14:10:02 INFO - 'USER': 'cltbld', 14:10:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:02 INFO - '_': '/tools/buildbot/bin/python'} 14:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:10:02 INFO - Cleaning up... 14:10:02 INFO - Return code: 0 14:10:02 INFO - Running post-action listener: _resource_record_post_action 14:10:02 INFO - Running post-action listener: _start_resource_monitoring 14:10:02 INFO - Starting resource monitoring. 14:10:02 INFO - [mozharness: 2016-09-19 21:10:02.616991Z] Finished create-virtualenv step (success) 14:10:02 INFO - [mozharness: 2016-09-19 21:10:02.617487Z] Running install step. 14:10:02 INFO - Running pre-action listener: _resource_record_pre_action 14:10:02 INFO - Running main action method: install 14:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:10:02 INFO - Reading from file tmpfile_stdout 14:10:02 INFO - Detecting whether we're running mozinstall >=1.0... 14:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:10:02 INFO - Reading from file tmpfile_stdout 14:10:02 INFO - Output received: 14:10:02 INFO - Usage: mozinstall [options] installer 14:10:02 INFO - Options: 14:10:02 INFO - -h, --help show this help message and exit 14:10:02 INFO - -d DEST, --destination=DEST 14:10:02 INFO - Directory to install application into. [default: 14:10:02 INFO - "/builds/slave/test"] 14:10:02 INFO - --app=APP Application being installed. [default: firefox] 14:10:02 INFO - mkdir: /builds/slave/test/build/application 14:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:10:19 INFO - Reading from file tmpfile_stdout 14:10:19 INFO - Output received: 14:10:19 INFO - /builds/slave/test/build/application/firefox/firefox 14:10:19 INFO - Running post-action listener: _resource_record_post_action 14:10:19 INFO - [mozharness: 2016-09-19 21:10:19.884046Z] Finished install step (success) 14:10:19 INFO - [mozharness: 2016-09-19 21:10:19.884290Z] Running run-tests step. 14:10:19 INFO - Running pre-action listener: _resource_record_pre_action 14:10:19 INFO - Running main action method: run_tests 14:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:19 INFO - Python 2.7.3 14:10:19 INFO - Return code: 0 14:10:19 INFO - grabbing minidump binary from tooltool 14:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:10:19 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 0x1957790>, '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 0x1958690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1958b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10:19 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 14:10:19 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 14:10:19 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 14:10:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:19 INFO - Return code: 0 14:10:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:10:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:10:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 14:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 14:10:19 INFO - Using env: {'DISPLAY': ':0', 14:10:19 INFO - 'HOME': '/home/cltbld', 14:10:19 INFO - 'LANG': 'en_US.UTF-8', 14:10:19 INFO - 'LANGUAGE': 'en_US:en', 14:10:19 INFO - 'LOGNAME': 'cltbld', 14:10:19 INFO - 'MAIL': '/var/mail/cltbld', 14:10:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:19 INFO - 'MOZ_NO_REMOTE': '1', 14:10:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:10:19 INFO - 'NO_EM_RESTART': '1', 14:10:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:19 INFO - 'PWD': '/builds/slave/test', 14:10:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:19 INFO - 'SHELL': '/bin/bash', 14:10:19 INFO - 'SHLVL': '1', 14:10:19 INFO - 'TERM': 'linux', 14:10:19 INFO - 'TMOUT': '86400', 14:10:19 INFO - 'USER': 'cltbld', 14:10:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558', 14:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:19 INFO - '_': '/tools/buildbot/bin/python'} 14:10:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 14:10:20 INFO - mozversion application_buildid: 20160919123029 14:10:20 INFO - mozversion application_changeset: 1fdbce2821b589bff8e03407712646f7c6716756 14:10:20 INFO - mozversion application_display_name: Nightly 14:10:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:20 INFO - mozversion application_name: Firefox 14:10:20 INFO - mozversion application_remotingname: firefox 14:10:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:10:20 INFO - mozversion application_vendor: Mozilla 14:10:20 INFO - mozversion application_version: 51.0a1 14:10:20 INFO - mozversion platform_buildid: 20160919123029 14:10:20 INFO - mozversion platform_changeset: 1fdbce2821b589bff8e03407712646f7c6716756 14:10:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:10:20 INFO - mozversion platform_version: 51.0a1 14:10:20 INFO - using testdate: 1474319420 14:10:20 INFO - actual date: 1474319420 14:10:20 INFO - starting webserver on 'localhost:53817' 14:10:20 INFO - SUITE-START | Running 2 tests 14:10:20 INFO - TEST-START | dromaeo_css 14:10:20 INFO - operating with platform_type : linux_ 14:10:20 INFO - Initialising browser for dromaeo_css test... 14:10:20 INFO - TEST-INFO | started process 12327 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJwOCxq/profile http://localhost:53817/getInfo.html) 14:10:24 INFO - PROCESS | 12327 | __metrics Screen width/height:1600/1200 14:10:24 INFO - PROCESS | 12327 | colorDepth:24 14:10:24 INFO - PROCESS | 12327 | Browser inner width/height: 1024/697 14:10:24 INFO - PROCESS | 12327 | __metrics 14:10:24 INFO - PROCESS | 12327 | 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" 14:10:24 INFO - PROCESS | 12327 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:10:25 INFO - PROCESS | 12327 | console.error: PushService: 14:10:25 INFO - PROCESS | 12327 | stateChangeProcessEnqueue: Error transitioning state 14:10:25 INFO - PROCESS | 12327 | UnknownError 14:10:25 INFO - TEST-INFO | 12327: exit 0 14:10:25 INFO - Browser initialized. 14:10:25 INFO - Running cycle 1/1 for dromaeo_css test... 14:10:25 INFO - TEST-INFO | started process 12409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJwOCxq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:10:37 INFO - PROCESS | 12409 | RSS: Main: 173367296 14:10:37 INFO - PROCESS | 12409 | 14:11:07 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:11:09 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:11:27 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:11:29 INFO - PROCESS | 12409 | 1474319489095 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] 14:11:36 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 14:11:41 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 14:11:43 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 14:11:45 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:11:54 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 14:12:02 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:12:07 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 14:12:10 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.0501 seconds. 14:12:16 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.09999 seconds. 14:12:23 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 14:12:40 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:12:42 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:13:14 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 14:13:22 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 14:13:24 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:13:31 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:13:33 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:13:35 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 14:13:39 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:53817/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53817/tests/dromaeo/cssquery-ext.html) 14:13:40 INFO - PROCESS | 12409 | RSS: Main: 288714752 14:13:40 INFO - PROCESS | 12409 | 14:13:43 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01678 seconds. 14:13:44 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 14:13:50 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.03332 seconds. 14:14:00 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:14:10 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:14:12 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:14:31 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 14:15:15 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:15:23 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:15:44 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 14:15:48 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:15:56 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 14:15:58 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:16:04 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:16:06 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:16:08 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 14:16:14 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:16:34 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:16:42 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:53817/tests/dromaeo/cssquery-ext.html (next: http://localhost:53817/tests/dromaeo/cssquery-jquery.html) 14:16:43 INFO - PROCESS | 12409 | RSS: Main: 277708800 14:16:43 INFO - PROCESS | 12409 | 14:16:46 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:16:47 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:16:49 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 14:16:59 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:17:01 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:17:08 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 2.00004 seconds. 14:17:08 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 2.00004 seconds. 14:17:32 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:17:52 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:18:14 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:18:32 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:18:46 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:18:48 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:18:50 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:18:54 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:19:06 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:19:17 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:19:20 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:19:37 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:19:45 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:53817/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53817/tests/dromaeo/cssquery-mootools.html) 14:19:45 INFO - PROCESS | 12409 | RSS: Main: 214794240 14:19:45 INFO - PROCESS | 12409 | 14:19:49 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:19:51 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.03346 seconds. 14:19:56 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:20:03 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:20:14 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:20:30 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:20:42 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:21:08 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 14:21:29 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:21:45 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:21:47 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:21:52 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:22:13 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:22:17 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:22:23 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 14:22:31 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:22:33 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:22:41 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 14:22:47 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:53817/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53817/tests/dromaeo/cssquery-prototype.html) 14:22:48 INFO - PROCESS | 12409 | RSS: Main: 216449024 14:22:48 INFO - PROCESS | 12409 | 14:23:02 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:23:17 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:23:24 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:23:39 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 14:23:46 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:23:55 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:24:03 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:24:05 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:24:12 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:24:13 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 14:24:16 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:24:19 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:24:30 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:24:50 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:24:52 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:24:54 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:24:56 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:24:58 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:25:02 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:25:04 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:25:15 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 14:25:18 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:25:26 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 14:25:32 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:25:34 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:25:40 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:25:43 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:25:51 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:53817/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53817/tests/dromaeo/cssquery-yui.html) 14:25:51 INFO - PROCESS | 12409 | RSS: Main: 217550848 14:25:51 INFO - PROCESS | 12409 | 14:25:55 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:26:02 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:26:12 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:26:18 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 14:26:20 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:26:38 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:26:50 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:27:23 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:27:53 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:28:07 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:28:09 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 14:28:19 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:28:29 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:28:34 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:28:36 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 14:28:40 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:28:42 INFO - PROCESS | 12409 | [GFX1-]: Refresh driver waiting for the compositor for 1.01343 seconds. 14:28:54 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:53817/tests/dromaeo/cssquery-yui.html (next: http://localhost:53817/tests/dromaeo/cssquery-dojo.html) 14:28:54 INFO - PROCESS | 12409 | RSS: Main: 304783360 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | __start_tp_report 14:28:54 INFO - PROCESS | 12409 | _x_x_mozilla_page_load 14:28:54 INFO - PROCESS | 12409 | _x_x_mozilla_page_load_details 14:28:54 INFO - PROCESS | 12409 | |i|pagename|runs| 14:28:54 INFO - PROCESS | 12409 | |0;dojo.html;2972.152936413122;2992.9700702992964;3007.8195308281506;3022.0480548627193;3049.695030496949;5146.536811686948;5179.792808287669;5251.763670634821;5268.762905669245;5282.86792830179;5130.743462826862;5205.193195054763;5225.6864588124645;5284.841454756353;5296.364436267653;4797.352357431737;4800.5679488846;4814.7351895645725;4823.252395878644;4831.613470922318;4776.90446191076;4799.5440433158965;4799.664023518355;4816.759162041902;4852.078105160026;4887.633427492934;4902.65681402302;4907.6073914086965;4914.311996320518;4963.900721985576;4711.952880471185;4845.685030473008;4856.878578035542;4863.513648635144;4903.53416425439;4720.551547602972;4741.431028276595;4773.570378665937;4781.665283430161;4849.684770489942;5203.375594928632;5216.895662086737;5222.895542089137;5223;5237.738113094369;5597.356304025048;5600.775968961237;5600.859978500529;5601.383847776741;5627.7467513961565;5519.254900588409;5536.363318218416;5588.4411558844195;5596.888062238732;5605.495505404533;5241.0828105081455;5244.895102097937;5246.213068039825;5249.580033597303;5302.045631786277;5701.344345400249;5711.314642242957;5715.657060576379;5724.169995350734;5785.4214578541805;5375.731213439312;5435.809746658848;5467.234587157801;5481.671099734029;5531.806386776524;5619.578531610146;5621.4659607337235;5628.831135065954;5677.489025987681;5697.487226149667;5548.167774833807;5564.4991950724625;5604.439556044363;5607.943920560824;5624.746886390122;5480.479354461319;5548.4174161712435;5559.138333558305;5612.214289999403;5693.3168019837885;4264.7654379009455;4318.222719910384;4386.649068074546;4391.275439552438;4393.011572396185;3853.4797802296894;3857.1514266861245;3896.688264938798;3931.783751893616;3958.148997965451;5304.389995150607;5304.549113325334;5310.7610157543;5316.946830531645;5320.733963301819;5482.23248745176;5491.423400542965;5618.297712785902;5625.212470254168;5693.060644993612;5249.028929648028;5292.179712144623;5307.840764777063;5313.840584782462;5319.946800532022;4798.1123492154065;4809.182438985311;4840.540148685869;4858.076965376567;4915.926261106087;5616.550675945996;5657.773689052392;5697.886042279214;5704.80033198836;5716.114002329646;5472.069748142746;5524.254225679642;5539.25220095282;5587.329520457572;5632.633878797865;5759.39526349744;5769.423057694197;5780.710964451845;5780.884382312414;5785.132230165341;5444.482774136451;5486.643368181056;5524.14375771756;5526.668399896019;5529.170624406372;5558.861028474321;5575.665460072409;5597.188407680945;5624.606277560531;5694;5461.9453805461435;5505;5511.862203444785;5515.531179849597;5521.4478552144465;5433.320834895638;5453.672779633234;5484.232207491034;5486.972565137149;5554.555635549227;5335.973320133375;5383.569314454756;5433.483819037185;5472.343318801769;5495.752691128829;5532.612717109763;5536.861578460571;5551.167324901297;5553.111502159635;5596.608237423341;5278.788848446173;5422;5425.647332923348;5429.728513574384;5431.918521222264 14:28:54 INFO - PROCESS | 12409 | |1;ext.html;556.418542622959;601.954853385996;613.3835495327196;615.7690865925279;645.1741770533719;31642.367152656934;32287.67712322876;34254.486182707245;34265;43708;41647;46404.767976160074;46486.30270545944;46493;46510.767446162805;8892.777680557989;9024.909750902472;9031.95484022579;9033.683821066264;9088.727338179831;8177.264046235838;8358.958205208966;8988.910110898905;9041.773955651128;10802.18983576231;10919;11361.318320900731;11382.430878456085;11383.373914434702;11385.829212561819;11118.555257789678;11158.386288754115;11169.944150279196;11188.720281992975;11189.66431007063;10894.67315980514;10965.287256328313;10967.067799237075;10973.396463194522;10973.451327433677;42671;42840;42911;42997.57002429998;43062.56937430617;3347.4644056951015;3549.716022718202;3853.133045064866;3992.5807790182193;4005.239004589119;31307.217319566964;31578;31843.52234716481;31906;33039.6696033039;4066.410370496291;4207.705460617758;4357.760323182224;4434.445694288214;4475.060237350161;353778;356958.2152089249;356964;357093;357666;60408.93865920059;60563;60658;60911.39088609057;60949.69525152346;64123;64302.35697643057;64448.677756610916;64451.35548644547;64514;17051.403200887908;17129.571760705832;17275.308987640605;17279.481615551555;17286.481405557854;13177.27524986135;13280;13285.468581256835;13291.800622990662;13304.46782128704;16164.35342586307;16579.585510362336;17752;17917.641647166984;18144.818551814315;6629.668516574152;6662.333766623299;6685.364890335411;6711.261761206263;6742.797716068526;862.1637012098255;956.53129966316;979.2264111352094;996.292632231124;1072.8015317166348;30306;30923.226919326746;31011.8449407756;31524.842375788434;31792.36415271728;1055.208593554834;1088.3633074651418;1140.7091191746051;1155.7052951497315;1166.6674997625637;15533.301001454962;16531.421400250932;16596.91701541509;16726.330946761995;16963.406280780113;530560;535918;535956;536988;537865;51116;52705.2094218587;56686.71656641773;58732.7063364689;58768.11847822286;1335.5192130832893;1346.5219846954385;1365.4469939674448;1371.1430356027483;1388.645895296693;30254.394912102074;32244.516332254567;32421.837890810395;32881.50677739788;33159;52231.477685222664;52574.737126314125;52776.47223527716;52809.47190528046;52988.73505632601;454492;458254;458347;459641;461820;40724.59275407208;41082.589174107874;42333;42430.151396972506;42433.57566424296;46971.53028469672;47612.2858157121;47925.5207447935;48187.5181248183;48226.27660585164;39536.60463395329;39845.2030959385;41088.58911410967;41106.1778764417;42688.35967460428;39601.207975840895;39663.603363967144;40483.59516404798;41474.585254147074;41633.79183104186;38317.42523862088;38730.225395492496;39130.4130438038;39496.40755388729;39642.2071558573;33790.49314260239;35653;36657.816710916275;36978.26043479169;37380;26818.59772103459;26866.59700104539;27539.586906196822;27587;27654.5851812227 14:28:54 INFO - PROCESS | 12409 | |2;jquery.html;367.7076724004416;379.4251708661379;387.155999920174;393.69685342286436;415.42671113862866;14254.503277155893;14778.630534236649;16993.745093823603;19481.90259048706;19990.90004549975;18987.810121898776;19464.70802937957;19482.805171948276;19631.5092122697;19760.90119549404;5626;5662.886742265152;5850.297964244297;5885.382034886339;5900.409959004087;5729.6562206267545;5795.739191736382;5830.096335068071;5832.1834943108;5842.47417732404;5641.58960259935;5686.886262274752;5719.6854173020465;5789.855253618651;5804.622699524539;5704.971475142618;5716.456936591017;5733.541316694655;5739.942600573982;5742.598018138732;4942.18453955097;5080.695158290478;5163.457836927106;5166.896662066775;5171.612129090333;23338.883305583535;23412.414689632726;23670.64494032591;23676.1713340034;23681.526369472685;2259.5932732108213;2332.346942856003;2433.4281443860677;2615.2677250369925;2667.5998600209928;14028.158310501403;14132;14756.114633122046;14850.1832399218;15192.164430956293;1897.127321432143;1912.177763561668;1915.6168766246806;1943.5141214696325;2008.9196432142696;82609.17390826075;82954;84716;85521;85631;10195.388276703421;10258.384496930208;10350.17043693823;10406.739831504121;10615;14701.485448009269;14704.558863233906;14708.55874323772;14712.779308310384;14725.55823325302;8021.919780802234;8185.590720463953;8243.670253189925;8420.578971051546;8538.188872057144;7969.481983671043;7972.720954766555;8052.637631306605;8280.668773249123;8282;6495.902561461582;6502.44729198014;6508.642024688593;6544.247411547733;6644.96677516619;1828.506303298102;1864.2077117225126;1889.8204670556274;1890.1305399516377;1927.354336297328;870.5429649433971;940.4263399326405;968.7190714692802;977.5894124467741;1045.5608644369383;18585.25658973626;20142;20197.697034544493;20200.69698954517;20725.68911466329;982.9262805289632;998.1415982255255;1004.4877112672443;1041.385582506325;1077.9191560632985;10483.633072842567;11539.134564907665;11564.653060408033;11567.42162891852;11608.767824643628;492045;502280;503566;519974;521023;82030.58984705039;84174;87675;90180.54909725409;102964;973.6105557776805;981.5975450065439;1015.6343716262139;1022.8210063238903;1045.9686209413578;17216;17635.647287054442;18233.452996410128;18306.542336441697;18378;42553.361699573914;42882.571174287856;43322.78338608287;43471.78264108659;44651.77674111609;56283;58387;59998.70000649969;61146;61431.38568614436;8253.380996425172;8259;8429.11494293115;8626.482411055356;8750.781230469289;11902.226355286879;12324.137310388187;12790.104692671795;12994.350282486026;13435.664108397368;11592.304461732323;11696.181267311207;11795.233309834834;11879.940600296943;12028.097892658261;11674.182807203415;11837.704057398634;11972.521099155936;12043.617856681582;12343.814842777545;11858.644240672793;11862.822057669315;11967;12061.698457538632;12385.752284954071;8410.579471026302;8721.345899057469;8727.345449091217;8849.734507964771;9067.546622668971;10938.398388088732;11250.437478126225;11462.770744585228;11836.171467997501;12021.819672704742 14:28:54 INFO - PROCESS | 12409 | |3;mootools.html;1338.0232430325868;1435.081547809402;1462.2030993108756;1556.9455069072585;1747.6067884725937;7593.240675932411;8476.279516241118;8709.34679899007;8711.30309575234;9015.41399809012;8776.517291548962;8845.336599755012;8860.822783544325;8869.822603547926;8870.37907346486;2344.4138965258726;2366.6095094309503;2371.430856594422;2377.4769550698907;2380.3811009137576;2278.7835155660187;2314.710661167354;2325.046730840356;2330.731965823935;2331.160782118436;2320.7447180810095;2337.158622895765;2350.2479206653793;2353.317537914003;2357.4106473381653;2275.6017696902977;2297.6438652008796;2307.3539408965516;2311.9422014449606;2314.282572402552;2257.7064981552458;2265.4676151104477;2269.1490690990877;2272.011674921398;2276.3284830974735;8496.320294376497;8538.82922341556;8566.400351975364;8580.528070956096;8613.397062205648;5077.441481437039;5268.288781014552;5270.367555893279;5274.235235890775;5280.07598670235;3094.7166375470542;3243.9675603244136;3257.4299497587826;3292.7859689120132;3297.7196938260277;1065.5631191211644;1066.8666416697913;1072.5388083124253;1082.9025387715064;1092.3336764573603;11543.365114918675;11608.187426880122;11647.592334268345;11666.533338666528;11686.47410866513;4211.957880421157;4314.395984562164;4377.693561450732;4409.40473036136;4497;9140.497272650067;9142.085791420805;9145.314101442418;9148.085191480799;9153.862692059625;5101.20931255656;5107.97446012775;5123.308353372322;5123.462036486116;5128.230765385222;4319.200947824663;4375.365571992043;4436.889077773081;4581.175388430114;4802.903941921212;3587.9641203588153;3616.439451884962;3789.905252368713;3790.2608991246443;3815.847366105325;1077.5582011375297;1083.106437189322;1091.1216470740972;1092.1481244629201;1114.5820317380983;930.5207817973749;935.3499317974079;940.4263399326405;955.4362925873768;985.9260555458369;5578.721063946787;5871.3541510433815;5904.143899134601;6064.9090263646085;6228.719707613168;447.3759106047046;456.91090237403387;958.836997710391;1013.8681971343825;1056.4664844253602;3860.285847118236;4141.710080294411;4161.146964872254;4174.165166966619;4362.563743625612;10691.679249622524;11119.72200694989;11124.833127503094;11126.554937802559;11140.49867755953;10215.744606384751;10424.68725938223;11071.5571377144;11079.169062320198;11084.113270938306;7945.64244608994;8080.191980801873;8104.270615644738;8121.878171827428;8152.307053900485;6069.9089513656445;6097.085437184458;6111.358307377839;6234.15838861766;6276.246850377984;9394.765130871783;9943.055409736064;9947.701568952943;10051.994800520182;10147.18822494184;10177.08406243412;10303.681671623825;10311.432871192157;10327.173826093744;10336.948315258376;7623.390128789572;7679.500832446097;7720.420968427278;7728.033995750531;7761.844763104594;8235.21765432283;8442.493450392996;8496.192861678132;8505.404621676424;8508.021577518664;7542.509736867086;7588.468807183444;7671.693132274647;7809.4142939278645;7952.204779522001;7419.406447484081;7432.665530051053;7466.962665186639;7474.171464524134;7820.726274580363;6888.552244104118;7004.474664400089;7012.298770122947;7015.824604384727;7020.368166865007;4982.676126051795;5033.295338652665;5096.949030509796;5152.460601469325;5251.2123181523075;5319.202119681924;5322.254884316121;5388.865278368072;5474.562035037108;5588.050031494575 14:28:54 INFO - PROCESS | 12409 | |4;prototype.html;299.27276717576297;304.95273232648947;308.0082135523613;316.6548462176228;318.087090051552;5227.163653815391;5237.554807841333;5257.211418287259;5262.289590905227;5277.340332458442;5120.92318615221;5146.794128234867;5232.659877107995;5233.162693968966;5250.558490302009;4076.206469153563;4085.53016403113;4094.549599544048;4094.877153685394;4118.485189351331;3812.1994381179984;3950.407438884162;3995.3008223560905;4020.798960051986;4094.488188976378;3920.333543297632;3941.822617982183;3949.368101103824;3956.129651476685;3969.622885825842;3810.8856734298015;3926.11662375966;3938.310795610785;3953.2488827122897;3997.8001099944886;3400.3199360128074;3421.007907706762;3488.0930957951114;3494.580650321978;3495.16116132129;4991.425986011619;5018.874528136789;5023.899522009576;5029.346184995964;5036.589754868643;4296.806643701041;4544.659150563721;4549.294859296781;4551.453825540956;4554.86335409935;5026.5727413169925;5137.691738495702;5339.599530035224;5355.651882627656;5385.488378604026;3818.7135964802474;3825.9043523911846;3830.8850734477733;3832;3845.5577608574813;5271.42014378418;5563.944360556424;5611.130274807411;5623.184638227475;5626.549876009909;3867.903302417462;3902.297586434412;3908.257431088084;3909.58949310324;3925.293447179478;3941.2117576484816;3944.7633142010995;3947.8618248361167;3954.4661470701085;3970.404439334065;4558.703684260512;4579.694786985693;4589.609883159968;4602.125596136723;4614.515475875052;5170;5386.522199788063;5400.91898621521;5424.511793938565;5493.368262649767;1349.9190048597115;1364.863513648647;1365.3241645385597;1366.473907545602;1367.3026756354332;839.9664013439394;871.6034204436966;878.4202426398674;887.2414085956543;890.6615486115235;4601.194790911577;4610.515895830889;4622.399088118523;4643.86068417948;4647.814807224204;4838.322634831126;4861.440934292531;4868.440129385095;4885.070397193089;4998.875028124253;4419.204543182258;4610.939483918717;4617.492075871652;4617.868622187586;4654.883627909329;2587.560114780492;2589.7539733725644;2597.8179928132654;2612.3991481959256;2785.442911417684;5580.693061881636;5637.351704553947;5752.137179423036;5808.157817116495;5808.664007278349;5695.943040569624;5707.942920570741;5712.742926568231;5721.199032135504;5723.7996670116345;5626.1842032905;5701.942980570224;5731.942680573141;5765.423457654285;5796.826190178683;5486.177073439016;5513.145462453326;5515.855962101564;5516.448355164452;5558.888822223614;5366.6779993200535;5487.42382049895;5498.697571633438;5509.5041446270025;5517.324097812638;5652.208690783212;5663.9716801415725;5675.77296908119;5684.77260909559;5687.175359572756;3870.8258128384855;3885.902852428712;3888.747231429948;3938.2714197873483;3954.051027753376;5379.953656942466;5579.191017302387;5593.874327755427;5623.578231632726;5656.406077361982;5391.568674505952;5413.864653383697;5414.049343107937;5417.024935511646;5432.402435731988;5365.59758018158;5379.704116273642;5381.381141168816;5392.299001129828;5417.431169727122;5114.23831131185;5322.946770532245;5433.293671822637;5434.918476222939;5447.972760136174;5490.313710786152;5494.033222060043;5500.4499550044675;5500.50495455395;5508.807191748269;5350.234422640441;5394.730263486731;5396.541293990055;5397.839464515148;5426.945730542644 14:28:54 INFO - PROCESS | 12409 | |5;yui.html;756.5498528375618;767.9681671226064;777.2460713108285;787.1498781316179;809.842080794245;15225.84774152258;15647.374105035813;15697.45058922938;15697.84302156978;16124.596885077879;12681.048921330914;12883.162594431355;12906.870931290685;12944.352782360867;13021.414036368362;661.3750006244102;767.2327672327687;816.5753808019826;819.0253598218131;819.6680344460473;678.9864202715943;794.253401802304;812.1715849833166;817.4114637461018;819.4755356571799;844.4553263145299;845.3406343052429;846.2003406780594;846.2975730143996;846.6190214403513;686.5057158845623;850.5152063323908;851.9787005324916;853.6030745703277;854.1671869926803;520.7448350308322;594.2245369792419;612.2897438970773;616.7286393986676;618.2889676871633;15658.530244092695;15692.058476491447;15692.372305107781;15700.68598628021;15721.541260922579;14050;14350.71298574033;14770.187639679812;14773.113613183243;15177.24113794306;13005.804912926315;13078.607641770763;13191;13520.729585408335;13630.863691363058;4319.568043195687;4350.630196433275;4370.781460926941;4407.2507673695545;4413.820453080876;82466.58766706187;82587;82724.93187670296;83870;84005;11379.487923043484;11451.427428628534;11781.705457363463;11859.789774630055;11860.881391185978;22158.335249942527;22172.556548869255;22180.112795488323;22408.215712449986;23332.300030999097;8209.958950205211;8312.70905518316;8371.916280837235;8487.70293039753;8526.616302266413;4470.888227794331;4659.114768194032;4718.457377401574;4803.279508073817;4920.384951881015;3200.7439404847555;3386.745994050456;3395.439752440869;3401.200717831308;3403.5064915587595;556.7438978070052;563.8674911395818;563.8731285460739;569.7777866632017;571.5598988778618;529.2220435959134;552.7844140785097;578.6614830324223;592.2862950145064;595.2172892646156;8751.56242187888;8935.151160639729;8938.972018217988;9060;9204.585793639168;568.2158621102934;624.4567226512872;625.6683957502531;636.176151883303;661.9338066193342;4392.38506609075;4442.133783912106;4469.5306992765945;4647.86056418308;4661.114388266218;69317.65341173363;70601;117249;123897;138176.30911845376;107448;110142;111149;114414;118242.40878795552;90234.09765902258;90458.54770726104;91718;95837;95911;8155.836883262183;8366.247037766387;9754.80490390174;9845.753856153653;9868.703938881845;44872.32691509695;47271.76364118295;49468;49776.75111624419;49852.50147498624;51563.48436515587;51676;62322.37677623166;71357.28642713507;74369;11833.467493962964;11932.701682458008;12181.939090304491;12373.505059797868;12614.621561353175;14834.77747833805;14928;15436.536903892453;15484.148371839658;15918.124503152438;13288.335583220995;13373.197608143542;13802.17186968785;14099;14503.419863205354;13691.383887724876;13790.379432925341;14512.85487145115;14779.187144706715;15062.694990362383;13872.4451021958;13906;13920.164790112625;14342.784858226927;14472.565823025327;10924.890751092387;10953.507092181031;11459.31244125355;11814.704632383915;11856.585019524275;11085.390303533382;11611.428799536954;11658.358790266617;11845.5854045108;11984.880151198377 14:28:54 INFO - PROCESS | 12409 | __end_tp_report 14:28:54 INFO - PROCESS | 12409 | __start_cc_report 14:28:54 INFO - PROCESS | 12409 | _x_x_mozilla_cycle_collect,989 14:28:54 INFO - PROCESS | 12409 | __end_cc_report 14:28:54 INFO - PROCESS | 12409 | __startTimestamp1474320534982__endTimestamp 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | ------- Summary: start ------- 14:28:54 INFO - PROCESS | 12409 | Number of tests: 6 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | [#0] dojo.html Cycles:165 Average:5213.04 Median:5431.92 stddev:572.09 (10.5%) stddev-sans-first:546.16 14:28:54 INFO - PROCESS | 12409 | Values: 2972.2 2993.0 3007.8 3022.0 3049.7 5146.5 5179.8 5251.8 5268.8 5282.9 5130.7 5205.2 5225.7 5284.8 5296.4 4797.4 4800.6 4814.7 4823.3 4831.6 4776.9 4799.5 4799.7 4816.8 4852.1 4887.6 4902.7 4907.6 4914.3 4963.9 4712.0 4845.7 4856.9 4863.5 4903.5 4720.6 4741.4 4773.6 4781.7 4849.7 5203.4 5216.9 5222.9 5223.0 5237.7 5597.4 5600.8 5600.9 5601.4 5627.7 5519.3 5536.4 5588.4 5596.9 5605.5 5241.1 5244.9 5246.2 5249.6 5302.0 5701.3 5711.3 5715.7 5724.2 5785.4 5375.7 5435.8 5467.2 5481.7 5531.8 5619.6 5621.5 5628.8 5677.5 5697.5 5548.2 5564.5 5604.4 5607.9 5624.7 5480.5 5548.4 5559.1 5612.2 5693.3 4264.8 4318.2 4386.6 4391.3 4393.0 3853.5 3857.2 3896.7 3931.8 3958.1 5304.4 5304.5 5310.8 5316.9 5320.7 5482.2 5491.4 5618.3 5625.2 5693.1 5249.0 5292.2 5307.8 5313.8 5319.9 4798.1 4809.2 4840.5 4858.1 4915.9 5616.6 5657.8 5697.9 5704.8 5716.1 5472.1 5524.3 5539.3 5587.3 5632.6 5759.4 5769.4 5780.7 5780.9 5785.1 5444.5 5486.6 5524.1 5526.7 5529.2 5558.9 5575.7 5597.2 5624.6 5694.0 5461.9 5505.0 5511.9 5515.5 5521.4 5433.3 5453.7 5484.2 5487.0 5554.6 5336.0 5383.6 5433.5 5472.3 5495.8 5532.6 5536.9 5551.2 5553.1 5596.6 5278.8 5422.0 5425.6 5429.7 5431.9 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | [#1] ext.html Cycles:180 Average:61408.14 Median:39652.91 stddev:120842.46 (304.8%) stddev-sans-first:121095.09 14:28:54 INFO - PROCESS | 12409 | Values: 556.4 602.0 613.4 615.8 645.2 31642.4 32287.7 34254.5 34265.0 43708.0 41647.0 46404.8 46486.3 46493.0 46510.8 8892.8 9024.9 9032.0 9033.7 9088.7 8177.3 8359.0 8988.9 9041.8 10802.2 10919.0 11361.3 11382.4 11383.4 11385.8 11118.6 11158.4 11169.9 11188.7 11189.7 10894.7 10965.3 10967.1 10973.4 10973.5 42671.0 42840.0 42911.0 42997.6 43062.6 3347.5 3549.7 3853.1 3992.6 4005.2 31307.2 31578.0 31843.5 31906.0 33039.7 4066.4 4207.7 4357.8 4434.4 4475.1 353778.0 356958.2 356964.0 357093.0 357666.0 60408.9 60563.0 60658.0 60911.4 60949.7 64123.0 64302.4 64448.7 64451.4 64514.0 17051.4 17129.6 17275.3 17279.5 17286.5 13177.3 13280.0 13285.5 13291.8 13304.5 16164.4 16579.6 17752.0 17917.6 18144.8 6629.7 6662.3 6685.4 6711.3 6742.8 862.2 956.5 979.2 996.3 1072.8 30306.0 30923.2 31011.8 31524.8 31792.4 1055.2 1088.4 1140.7 1155.7 1166.7 15533.3 16531.4 16596.9 16726.3 16963.4 530560.0 535918.0 535956.0 536988.0 537865.0 51116.0 52705.2 56686.7 58732.7 58768.1 1335.5 1346.5 1365.4 1371.1 1388.6 30254.4 32244.5 32421.8 32881.5 33159.0 52231.5 52574.7 52776.5 52809.5 52988.7 454492.0 458254.0 458347.0 459641.0 461820.0 40724.6 41082.6 42333.0 42430.2 42433.6 46971.5 47612.3 47925.5 48187.5 48226.3 39536.6 39845.2 41088.6 41106.2 42688.4 39601.2 39663.6 40483.6 41474.6 41633.8 38317.4 38730.2 39130.4 39496.4 39642.2 33790.5 35653.0 36657.8 36978.3 37380.0 26818.6 26866.6 27539.6 27587.0 27654.6 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | [#2] jquery.html Cycles:180 Average:29758.62 Median:2524.35 stddev:83648.61 (3313.7%) stddev-sans-first:83854.16 14:28:54 INFO - PROCESS | 12409 | Values: 367.7 379.4 387.2 393.7 415.4 14254.5 14778.6 16993.7 19481.9 19990.9 18987.8 19464.7 19482.8 19631.5 19760.9 5626.0 5662.9 5850.3 5885.4 5900.4 5729.7 5795.7 5830.1 5832.2 5842.5 5641.6 5686.9 5719.7 5789.9 5804.6 5705.0 5716.5 5733.5 5739.9 5742.6 4942.2 5080.7 5163.5 5166.9 5171.6 23338.9 23412.4 23670.6 23676.2 23681.5 2259.6 2332.3 2433.4 2615.3 2667.6 14028.2 14132.0 14756.1 14850.2 15192.2 1897.1 1912.2 1915.6 1943.5 2008.9 82609.2 82954.0 84716.0 85521.0 85631.0 10195.4 10258.4 10350.2 10406.7 10615.0 14701.5 14704.6 14708.6 14712.8 14725.6 8021.9 8185.6 8243.7 8420.6 8538.2 7969.5 7972.7 8052.6 8280.7 8282.0 6495.9 6502.4 6508.6 6544.2 6645.0 1828.5 1864.2 1889.8 1890.1 1927.4 870.5 940.4 968.7 977.6 1045.6 18585.3 20142.0 20197.7 20200.7 20725.7 982.9 998.1 1004.5 1041.4 1077.9 10483.6 11539.1 11564.7 11567.4 11608.8 492045.0 502280.0 503566.0 519974.0 521023.0 82030.6 84174.0 87675.0 90180.5 102964.0 973.6 981.6 1015.6 1022.8 1046.0 17216.0 17635.6 18233.5 18306.5 18378.0 42553.4 42882.6 43322.8 43471.8 44651.8 56283.0 58387.0 59998.7 61146.0 61431.4 8253.4 8259.0 8429.1 8626.5 8750.8 11902.2 12324.1 12790.1 12994.4 13435.7 11592.3 11696.2 11795.2 11879.9 12028.1 11674.2 11837.7 11972.5 12043.6 12343.8 11858.6 11862.8 11967.0 12061.7 12385.8 8410.6 8721.3 8727.3 8849.7 9067.5 10938.4 11250.4 11462.8 11836.2 12021.8 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | [#3] mootools.html Cycles:180 Average:5696.17 Median:4892.79 stddev:3231.65 (66.0%) stddev-sans-first:3224.11 14:28:54 INFO - PROCESS | 12409 | Values: 1338.0 1435.1 1462.2 1556.9 1747.6 7593.2 8476.3 8709.3 8711.3 9015.4 8776.5 8845.3 8860.8 8869.8 8870.4 2344.4 2366.6 2371.4 2377.5 2380.4 2278.8 2314.7 2325.0 2330.7 2331.2 2320.7 2337.2 2350.2 2353.3 2357.4 2275.6 2297.6 2307.4 2311.9 2314.3 2257.7 2265.5 2269.1 2272.0 2276.3 8496.3 8538.8 8566.4 8580.5 8613.4 5077.4 5268.3 5270.4 5274.2 5280.1 3094.7 3244.0 3257.4 3292.8 3297.7 1065.6 1066.9 1072.5 1082.9 1092.3 11543.4 11608.2 11647.6 11666.5 11686.5 4212.0 4314.4 4377.7 4409.4 4497.0 9140.5 9142.1 9145.3 9148.1 9153.9 5101.2 5108.0 5123.3 5123.5 5128.2 4319.2 4375.4 4436.9 4581.2 4802.9 3588.0 3616.4 3789.9 3790.3 3815.8 1077.6 1083.1 1091.1 1092.1 1114.6 930.5 935.3 940.4 955.4 985.9 5578.7 5871.4 5904.1 6064.9 6228.7 447.4 456.9 958.8 1013.9 1056.5 3860.3 4141.7 4161.1 4174.2 4362.6 10691.7 11119.7 11124.8 11126.6 11140.5 10215.7 10424.7 11071.6 11079.2 11084.1 7945.6 8080.2 8104.3 8121.9 8152.3 6069.9 6097.1 6111.4 6234.2 6276.2 9394.8 9943.1 9947.7 10052.0 10147.2 10177.1 10303.7 10311.4 10327.2 10336.9 7623.4 7679.5 7720.4 7728.0 7761.8 8235.2 8442.5 8496.2 8505.4 8508.0 7542.5 7588.5 7671.7 7809.4 7952.2 7419.4 7432.7 7467.0 7474.2 7820.7 6888.6 7004.5 7012.3 7015.8 7020.4 4982.7 5033.3 5096.9 5152.5 5251.2 5319.2 5322.3 5388.9 5474.6 5588.1 14:28:54 INFO - PROCESS | 12409 | 14:28:54 INFO - PROCESS | 12409 | [#4] prototype.html Cycles:180 Average:4484.05 Median:5075.41 stddev:1345.24 (26.5%) stddev-sans-first:1311.83 14:28:55 INFO - PROCESS | 12409 | Values: 299.3 305.0 308.0 316.7 318.1 5227.2 5237.6 5257.2 5262.3 5277.3 5120.9 5146.8 5232.7 5233.2 5250.6 4076.2 4085.5 4094.5 4094.9 4118.5 3812.2 3950.4 3995.3 4020.8 4094.5 3920.3 3941.8 3949.4 3956.1 3969.6 3810.9 3926.1 3938.3 3953.2 3997.8 3400.3 3421.0 3488.1 3494.6 3495.2 4991.4 5018.9 5023.9 5029.3 5036.6 4296.8 4544.7 4549.3 4551.5 4554.9 5026.6 5137.7 5339.6 5355.7 5385.5 3818.7 3825.9 3830.9 3832.0 3845.6 5271.4 5563.9 5611.1 5623.2 5626.5 3867.9 3902.3 3908.3 3909.6 3925.3 3941.2 3944.8 3947.9 3954.5 3970.4 4558.7 4579.7 4589.6 4602.1 4614.5 5170.0 5386.5 5400.9 5424.5 5493.4 1349.9 1364.9 1365.3 1366.5 1367.3 840.0 871.6 878.4 887.2 890.7 4601.2 4610.5 4622.4 4643.9 4647.8 4838.3 4861.4 4868.4 4885.1 4998.9 4419.2 4610.9 4617.5 4617.9 4654.9 2587.6 2589.8 2597.8 2612.4 2785.4 5580.7 5637.4 5752.1 5808.2 5808.7 5695.9 5707.9 5712.7 5721.2 5723.8 5626.2 5701.9 5731.9 5765.4 5796.8 5486.2 5513.1 5515.9 5516.4 5558.9 5366.7 5487.4 5498.7 5509.5 5517.3 5652.2 5664.0 5675.8 5684.8 5687.2 3870.8 3885.9 3888.7 3938.3 3954.1 5380.0 5579.2 5593.9 5623.6 5656.4 5391.6 5413.9 5414.0 5417.0 5432.4 5365.6 5379.7 5381.4 5392.3 5417.4 5114.2 5322.9 5433.3 5434.9 5448.0 5490.3 5494.0 5500.4 5500.5 5508.8 5350.2 5394.7 5396.5 5397.8 5426.9 14:28:55 INFO - PROCESS | 12409 | 14:28:55 INFO - PROCESS | 12409 | [#5] yui.html Cycles:180 Average:20877.58 Median:4470.21 stddev:30639.38 (685.4%) stddev-sans-first:30688.08 14:28:55 INFO - PROCESS | 12409 | Values: 756.5 768.0 777.2 787.1 809.8 15225.8 15647.4 15697.5 15697.8 16124.6 12681.0 12883.2 12906.9 12944.4 13021.4 661.4 767.2 816.6 819.0 819.7 679.0 794.3 812.2 817.4 819.5 844.5 845.3 846.2 846.3 846.6 686.5 850.5 852.0 853.6 854.2 520.7 594.2 612.3 616.7 618.3 15658.5 15692.1 15692.4 15700.7 15721.5 14050.0 14350.7 14770.2 14773.1 15177.2 13005.8 13078.6 13191.0 13520.7 13630.9 4319.6 4350.6 4370.8 4407.3 4413.8 82466.6 82587.0 82724.9 83870.0 84005.0 11379.5 11451.4 11781.7 11859.8 11860.9 22158.3 22172.6 22180.1 22408.2 23332.3 8210.0 8312.7 8371.9 8487.7 8526.6 4470.9 4659.1 4718.5 4803.3 4920.4 3200.7 3386.7 3395.4 3401.2 3403.5 556.7 563.9 563.9 569.8 571.6 529.2 552.8 578.7 592.3 595.2 8751.6 8935.2 8939.0 9060.0 9204.6 568.2 624.5 625.7 636.2 661.9 4392.4 4442.1 4469.5 4647.9 4661.1 69317.7 70601.0 117249.0 123897.0 138176.3 107448.0 110142.0 111149.0 114414.0 118242.4 90234.1 90458.5 91718.0 95837.0 95911.0 8155.8 8366.2 9754.8 9845.8 9868.7 44872.3 47271.8 49468.0 49776.8 49852.5 51563.5 51676.0 62322.4 71357.3 74369.0 11833.5 11932.7 12181.9 12373.5 12614.6 14834.8 14928.0 15436.5 15484.1 15918.1 13288.3 13373.2 13802.2 14099.0 14503.4 13691.4 13790.4 14512.9 14779.2 15062.7 13872.4 13906.0 13920.2 14342.8 14472.6 10924.9 10953.5 11459.3 11814.7 11856.6 11085.4 11611.4 11658.4 11845.6 11984.9 14:28:55 INFO - PROCESS | 12409 | -------- Summary: end -------- 14:28:55 INFO - PROCESS | 12409 | 14:28:55 INFO - TEST-INFO | 12409: exit 0 14:28:55 INFO - TEST-OK | dromaeo_css | took 1115813ms 14:28:55 INFO - TEST-START | kraken 14:28:55 INFO - operating with platform_type : linux_ 14:28:55 INFO - Initialising browser for kraken test... 14:28:55 INFO - TEST-INFO | started process 12717 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU_KCAS/profile http://localhost:53817/getInfo.html) 14:28:59 INFO - PROCESS | 12717 | __metrics Screen width/height:1600/1200 14:28:59 INFO - PROCESS | 12717 | colorDepth:24 14:28:59 INFO - PROCESS | 12717 | Browser inner width/height: 1024/697 14:28:59 INFO - PROCESS | 12717 | __metrics 14:29:00 INFO - PROCESS | 12717 | 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" 14:29:00 INFO - PROCESS | 12717 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:29:00 INFO - PROCESS | 12717 | console.error: PushService: 14:29:00 INFO - PROCESS | 12717 | stateChangeProcessEnqueue: Error transitioning state 14:29:00 INFO - PROCESS | 12717 | UnknownError 14:29:01 INFO - TEST-INFO | 12717: exit 0 14:29:01 INFO - Browser initialized. 14:29:01 INFO - Running cycle 1/1 for kraken test... 14:29:01 INFO - TEST-INFO | started process 12802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU_KCAS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:13 INFO - PROCESS | 12802 | RSS: Main: 173617152 14:29:13 INFO - PROCESS | 12802 | 14:29:16 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53817/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:17 INFO - PROCESS | 12802 | RSS: Main: 236314624 14:29:17 INFO - PROCESS | 12802 | 14:29:19 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53817/tests/kraken/driver.html?testName=audio-dft) 14:29:20 INFO - PROCESS | 12802 | RSS: Main: 216604672 14:29:20 INFO - PROCESS | 12802 | 14:29:23 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53817/tests/kraken/driver.html?testName=audio-fft) 14:29:24 INFO - PROCESS | 12802 | RSS: Main: 298332160 14:29:24 INFO - PROCESS | 12802 | 14:29:26 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53817/tests/kraken/driver.html?testName=audio-oscillator) 14:29:27 INFO - PROCESS | 12802 | RSS: Main: 208027648 14:29:27 INFO - PROCESS | 12802 | 14:29:29 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53817/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:29 INFO - PROCESS | 12802 | RSS: Main: 212365312 14:29:29 INFO - PROCESS | 12802 | 14:29:32 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53817/tests/kraken/driver.html?testName=imaging-darkroom) 14:29:33 INFO - PROCESS | 12802 | RSS: Main: 249155584 14:29:33 INFO - PROCESS | 12802 | 14:29:36 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53817/tests/kraken/driver.html?testName=imaging-desaturate) 14:29:37 INFO - PROCESS | 12802 | RSS: Main: 248340480 14:29:37 INFO - PROCESS | 12802 | 14:29:40 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53817/tests/kraken/driver.html?testName=json-parse-financial) 14:29:40 INFO - PROCESS | 12802 | RSS: Main: 248115200 14:29:40 INFO - PROCESS | 12802 | 14:29:42 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53817/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:29:42 INFO - PROCESS | 12802 | RSS: Main: 210075648 14:29:42 INFO - PROCESS | 12802 | 14:29:44 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:29:45 INFO - PROCESS | 12802 | RSS: Main: 218513408 14:29:45 INFO - PROCESS | 12802 | 14:29:47 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:29:48 INFO - PROCESS | 12802 | RSS: Main: 222289920 14:29:48 INFO - PROCESS | 12802 | 14:29:50 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:29:51 INFO - PROCESS | 12802 | RSS: Main: 222543872 14:29:51 INFO - PROCESS | 12802 | 14:29:54 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:29:54 INFO - PROCESS | 12802 | RSS: Main: 221286400 14:29:54 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | Cycle 1(1): loaded http://localhost:53817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53817/tests/kraken/driver.html?testName=ai-astar) 14:29:56 INFO - PROCESS | 12802 | RSS: Main: 272404480 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | __start_tp_report 14:29:56 INFO - PROCESS | 12802 | _x_x_mozilla_page_load 14:29:56 INFO - PROCESS | 12802 | _x_x_mozilla_page_load_details 14:29:56 INFO - PROCESS | 12802 | |i|pagename|runs| 14:29:56 INFO - PROCESS | 12802 | |0;ai-astar;112;115;116;116;113;118;114;115;114;115 14:29:56 INFO - PROCESS | 12802 | |1;audio-beat-detection;150;121;117;163;120;124;119;118;148;119 14:29:56 INFO - PROCESS | 12802 | |2;audio-dft;162;162;162;163;163;162;161;161;163;164 14:29:56 INFO - PROCESS | 12802 | |3;audio-fft;76;77;77;112;78;76;76;76;97;75 14:29:56 INFO - PROCESS | 12802 | |4;audio-oscillator;84;82;116;83;83;83;108;83;83;109 14:29:56 INFO - PROCESS | 12802 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;118;118;117 14:29:56 INFO - PROCESS | 12802 | |6;imaging-darkroom;146;146;146;146;146;145;145;145;146;146 14:29:56 INFO - PROCESS | 12802 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;94;95 14:29:56 INFO - PROCESS | 12802 | |8;json-parse-financial;52;51;53;50;52;53;52;51;52;52 14:29:56 INFO - PROCESS | 12802 | |9;json-stringify-tinderbox;60;61;61;60;60;61;60;61;61;61 14:29:56 INFO - PROCESS | 12802 | |10;stanford-crypto-aes;80;95;80;79;81;89;80;87;80;78 14:29:56 INFO - PROCESS | 12802 | |11;stanford-crypto-ccm;155;125;137;126;129;141;133;132;172;127 14:29:56 INFO - PROCESS | 12802 | |12;stanford-crypto-pbkdf2;164;166;164;164;162;169;161;167;163;161 14:29:56 INFO - PROCESS | 12802 | |13;stanford-crypto-sha256-iterative;67;66;65;66;66;69;64;64;64;64 14:29:56 INFO - PROCESS | 12802 | __end_tp_report 14:29:56 INFO - PROCESS | 12802 | __start_cc_report 14:29:56 INFO - PROCESS | 12802 | _x_x_mozilla_cycle_collect,1636 14:29:56 INFO - PROCESS | 12802 | __end_cc_report 14:29:56 INFO - PROCESS | 12802 | __startTimestamp1474320596652__endTimestamp 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | ------- Summary: start ------- 14:29:56 INFO - PROCESS | 12802 | Number of tests: 14 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:1.69 (1.5%) stddev-sans-first:1.45 14:29:56 INFO - PROCESS | 12802 | Values: 112.0 115.0 116.0 116.0 113.0 118.0 114.0 115.0 114.0 115.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:122.50 stddev:16.95 (13.8%) stddev-sans-first:16.34 14:29:56 INFO - PROCESS | 12802 | Values: 150.0 121.0 117.0 163.0 120.0 124.0 119.0 118.0 148.0 119.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 14:29:56 INFO - PROCESS | 12802 | Values: 162.0 162.0 162.0 163.0 163.0 162.0 161.0 161.0 163.0 164.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.50 stddev:12.40 (16.2%) stddev-sans-first:12.96 14:29:56 INFO - PROCESS | 12802 | Values: 76.0 77.0 77.0 112.0 78.0 76.0 76.0 76.0 97.0 75.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:83.00 stddev:13.69 (16.5%) stddev-sans-first:14.25 14:29:56 INFO - PROCESS | 12802 | Values: 84.0 82.0 116.0 83.0 83.0 83.0 108.0 83.0 83.0 109.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 14:29:56 INFO - PROCESS | 12802 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 14:29:56 INFO - PROCESS | 12802 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 146.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 14:29:56 INFO - PROCESS | 12802 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.92 (1.8%) stddev-sans-first:0.97 14:29:56 INFO - PROCESS | 12802 | Values: 52.0 51.0 53.0 50.0 52.0 53.0 52.0 51.0 52.0 52.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 14:29:56 INFO - PROCESS | 12802 | Values: 60.0 61.0 61.0 60.0 60.0 61.0 60.0 61.0 61.0 61.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:80.50 stddev:5.55 (6.9%) stddev-sans-first:5.78 14:29:56 INFO - PROCESS | 12802 | Values: 80.0 95.0 80.0 79.0 81.0 89.0 80.0 87.0 80.0 78.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:135.00 stddev:15.02 (11.1%) stddev-sans-first:14.57 14:29:56 INFO - PROCESS | 12802 | Values: 155.0 125.0 137.0 126.0 129.0 141.0 133.0 132.0 172.0 127.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.00 stddev:2.60 (1.6%) stddev-sans-first:2.76 14:29:56 INFO - PROCESS | 12802 | Values: 164.0 166.0 164.0 164.0 162.0 169.0 161.0 167.0 163.0 161.0 14:29:56 INFO - PROCESS | 12802 | 14:29:56 INFO - PROCESS | 12802 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.65 (2.5%) stddev-sans-first:1.66 14:29:56 INFO - PROCESS | 12802 | Values: 67.0 66.0 65.0 66.0 66.0 69.0 64.0 64.0 64.0 64.0 14:29:56 INFO - PROCESS | 12802 | -------- Summary: end -------- 14:29:56 INFO - PROCESS | 12802 | 14:29:57 INFO - TEST-INFO | 12802: exit 0 14:29:57 INFO - TEST-OK | kraken | took 61908ms 14:29:57 INFO - SUITE-END | took 1177s 14:29:58 INFO - Completed test suite (00:19:38) 14:29:58 INFO - javascript benchmark 14:29:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2972.152936413122, 2992.9700702992964, 3007.8195308281506, 3022.0480548627193, 3049.695030496949, 5146.536811686948, 5179.792808287669, 5251.763670634821, 5268.762905669245, 5282.86792830179, 5130.743462826862, 5205.193195054763, 5225.6864588124645, 5284.841454756353, 5296.364436267653, 4797.352357431737, 4800.5679488846, 4814.7351895645725, 4823.252395878644, 4831.613470922318, 4776.90446191076, 4799.5440433158965, 4799.664023518355, 4816.759162041902, 4852.078105160026, 4887.633427492934, 4902.65681402302, 4907.6073914086965, 4914.311996320518, 4963.900721985576, 4711.952880471185, 4845.685030473008, 4856.878578035542, 4863.513648635144, 4903.53416425439, 4720.551547602972, 4741.431028276595, 4773.570378665937, 4781.665283430161, 4849.684770489942, 5203.375594928632, 5216.895662086737, 5222.895542089137, 5223.0, 5237.738113094369, 5597.356304025048, 5600.775968961237, 5600.859978500529, 5601.383847776741, 5627.7467513961565, 5519.254900588409, 5536.363318218416, 5588.4411558844195, 5596.888062238732, 5605.495505404533, 5241.0828105081455, 5244.895102097937, 5246.213068039825, 5249.580033597303, 5302.045631786277, 5701.344345400249, 5711.314642242957, 5715.657060576379, 5724.169995350734, 5785.4214578541805, 5375.731213439312, 5435.809746658848, 5467.234587157801, 5481.671099734029, 5531.806386776524, 5619.578531610146, 5621.4659607337235, 5628.831135065954, 5677.489025987681, 5697.487226149667, 5548.167774833807, 5564.4991950724625, 5604.439556044363, 5607.943920560824, 5624.746886390122, 5480.479354461319, 5548.4174161712435, 5559.138333558305, 5612.214289999403, 5693.3168019837885, 4264.7654379009455, 4318.222719910384, 4386.649068074546, 4391.275439552438, 4393.011572396185, 3853.4797802296894, 3857.1514266861245, 3896.688264938798, 3931.783751893616, 3958.148997965451, 5304.389995150607, 5304.549113325334, 5310.7610157543, 5316.946830531645, 5320.733963301819, 5482.23248745176, 5491.423400542965, 5618.297712785902, 5625.212470254168, 5693.060644993612, 5249.028929648028, 5292.179712144623, 5307.840764777063, 5313.840584782462, 5319.946800532022, 4798.1123492154065, 4809.182438985311, 4840.540148685869, 4858.076965376567, 4915.926261106087, 5616.550675945996, 5657.773689052392, 5697.886042279214, 5704.80033198836, 5716.114002329646, 5472.069748142746, 5524.254225679642, 5539.25220095282, 5587.329520457572, 5632.633878797865, 5759.39526349744, 5769.423057694197, 5780.710964451845, 5780.884382312414, 5785.132230165341, 5444.482774136451, 5486.643368181056, 5524.14375771756, 5526.668399896019, 5529.170624406372, 5558.861028474321, 5575.665460072409, 5597.188407680945, 5624.606277560531, 5694.0, 5461.9453805461435, 5505.0, 5511.862203444785, 5515.531179849597, 5521.4478552144465, 5433.320834895638, 5453.672779633234, 5484.232207491034, 5486.972565137149, 5554.555635549227, 5335.973320133375, 5383.569314454756, 5433.483819037185, 5472.343318801769, 5495.752691128829, 5532.612717109763, 5536.861578460571, 5551.167324901297, 5553.111502159635, 5596.608237423341, 5278.788848446173, 5422.0, 5425.647332923348, 5429.728513574384, 5431.918521222264], "value": 5175.151422806387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.418542622959, 601.954853385996, 613.3835495327196, 615.7690865925279, 645.1741770533719, 31642.367152656934, 32287.67712322876, 34254.486182707245, 34265.0, 43708.0, 41647.0, 46404.767976160074, 46486.30270545944, 46493.0, 46510.767446162805, 8892.777680557989, 9024.909750902472, 9031.95484022579, 9033.683821066264, 9088.727338179831, 8177.264046235838, 8358.958205208966, 8988.910110898905, 9041.773955651128, 10802.18983576231, 10919.0, 11361.318320900731, 11382.430878456085, 11383.373914434702, 11385.829212561819, 11118.555257789678, 11158.386288754115, 11169.944150279196, 11188.720281992975, 11189.66431007063, 10894.67315980514, 10965.287256328313, 10967.067799237075, 10973.396463194522, 10973.451327433677, 42671.0, 42840.0, 42911.0, 42997.57002429998, 43062.56937430617, 3347.4644056951015, 3549.716022718202, 3853.133045064866, 3992.5807790182193, 4005.239004589119, 31307.217319566964, 31578.0, 31843.52234716481, 31906.0, 33039.6696033039, 4066.410370496291, 4207.705460617758, 4357.760323182224, 4434.445694288214, 4475.060237350161, 353778.0, 356958.2152089249, 356964.0, 357093.0, 357666.0, 60408.93865920059, 60563.0, 60658.0, 60911.39088609057, 60949.69525152346, 64123.0, 64302.35697643057, 64448.677756610916, 64451.35548644547, 64514.0, 17051.403200887908, 17129.571760705832, 17275.308987640605, 17279.481615551555, 17286.481405557854, 13177.27524986135, 13280.0, 13285.468581256835, 13291.800622990662, 13304.46782128704, 16164.35342586307, 16579.585510362336, 17752.0, 17917.641647166984, 18144.818551814315, 6629.668516574152, 6662.333766623299, 6685.364890335411, 6711.261761206263, 6742.797716068526, 862.1637012098255, 956.53129966316, 979.2264111352094, 996.292632231124, 1072.8015317166348, 30306.0, 30923.226919326746, 31011.8449407756, 31524.842375788434, 31792.36415271728, 1055.208593554834, 1088.3633074651418, 1140.7091191746051, 1155.7052951497315, 1166.6674997625637, 15533.301001454962, 16531.421400250932, 16596.91701541509, 16726.330946761995, 16963.406280780113, 530560.0, 535918.0, 535956.0, 536988.0, 537865.0, 51116.0, 52705.2094218587, 56686.71656641773, 58732.7063364689, 58768.11847822286, 1335.5192130832893, 1346.5219846954385, 1365.4469939674448, 1371.1430356027483, 1388.645895296693, 30254.394912102074, 32244.516332254567, 32421.837890810395, 32881.50677739788, 33159.0, 52231.477685222664, 52574.737126314125, 52776.47223527716, 52809.47190528046, 52988.73505632601, 454492.0, 458254.0, 458347.0, 459641.0, 461820.0, 40724.59275407208, 41082.589174107874, 42333.0, 42430.151396972506, 42433.57566424296, 46971.53028469672, 47612.2858157121, 47925.5207447935, 48187.5181248183, 48226.27660585164, 39536.60463395329, 39845.2030959385, 41088.58911410967, 41106.1778764417, 42688.35967460428, 39601.207975840895, 39663.603363967144, 40483.59516404798, 41474.585254147074, 41633.79183104186, 38317.42523862088, 38730.225395492496, 39130.4130438038, 39496.40755388729, 39642.2071558573, 33790.49314260239, 35653.0, 36657.816710916275, 36978.26043479169, 37380.0, 26818.59772103459, 26866.59700104539, 27539.586906196822, 27587.0, 27654.5851812227], "value": 20607.218007171516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.7076724004416, 379.4251708661379, 387.155999920174, 393.69685342286436, 415.42671113862866, 14254.503277155893, 14778.630534236649, 16993.745093823603, 19481.90259048706, 19990.90004549975, 18987.810121898776, 19464.70802937957, 19482.805171948276, 19631.5092122697, 19760.90119549404, 5626.0, 5662.886742265152, 5850.297964244297, 5885.382034886339, 5900.409959004087, 5729.6562206267545, 5795.739191736382, 5830.096335068071, 5832.1834943108, 5842.47417732404, 5641.58960259935, 5686.886262274752, 5719.6854173020465, 5789.855253618651, 5804.622699524539, 5704.971475142618, 5716.456936591017, 5733.541316694655, 5739.942600573982, 5742.598018138732, 4942.18453955097, 5080.695158290478, 5163.457836927106, 5166.896662066775, 5171.612129090333, 23338.883305583535, 23412.414689632726, 23670.64494032591, 23676.1713340034, 23681.526369472685, 2259.5932732108213, 2332.346942856003, 2433.4281443860677, 2615.2677250369925, 2667.5998600209928, 14028.158310501403, 14132.0, 14756.114633122046, 14850.1832399218, 15192.164430956293, 1897.127321432143, 1912.177763561668, 1915.6168766246806, 1943.5141214696325, 2008.9196432142696, 82609.17390826075, 82954.0, 84716.0, 85521.0, 85631.0, 10195.388276703421, 10258.384496930208, 10350.17043693823, 10406.739831504121, 10615.0, 14701.485448009269, 14704.558863233906, 14708.55874323772, 14712.779308310384, 14725.55823325302, 8021.919780802234, 8185.590720463953, 8243.670253189925, 8420.578971051546, 8538.188872057144, 7969.481983671043, 7972.720954766555, 8052.637631306605, 8280.668773249123, 8282.0, 6495.902561461582, 6502.44729198014, 6508.642024688593, 6544.247411547733, 6644.96677516619, 1828.506303298102, 1864.2077117225126, 1889.8204670556274, 1890.1305399516377, 1927.354336297328, 870.5429649433971, 940.4263399326405, 968.7190714692802, 977.5894124467741, 1045.5608644369383, 18585.25658973626, 20142.0, 20197.697034544493, 20200.69698954517, 20725.68911466329, 982.9262805289632, 998.1415982255255, 1004.4877112672443, 1041.385582506325, 1077.9191560632985, 10483.633072842567, 11539.134564907665, 11564.653060408033, 11567.42162891852, 11608.767824643628, 492045.0, 502280.0, 503566.0, 519974.0, 521023.0, 82030.58984705039, 84174.0, 87675.0, 90180.54909725409, 102964.0, 973.6105557776805, 981.5975450065439, 1015.6343716262139, 1022.8210063238903, 1045.9686209413578, 17216.0, 17635.647287054442, 18233.452996410128, 18306.542336441697, 18378.0, 42553.361699573914, 42882.571174287856, 43322.78338608287, 43471.78264108659, 44651.77674111609, 56283.0, 58387.0, 59998.70000649969, 61146.0, 61431.38568614436, 8253.380996425172, 8259.0, 8429.11494293115, 8626.482411055356, 8750.781230469289, 11902.226355286879, 12324.137310388187, 12790.104692671795, 12994.350282486026, 13435.664108397368, 11592.304461732323, 11696.181267311207, 11795.233309834834, 11879.940600296943, 12028.097892658261, 11674.182807203415, 11837.704057398634, 11972.521099155936, 12043.617856681582, 12343.814842777545, 11858.644240672793, 11862.822057669315, 11967.0, 12061.698457538632, 12385.752284954071, 8410.579471026302, 8721.345899057469, 8727.345449091217, 8849.734507964771, 9067.546622668971, 10938.398388088732, 11250.437478126225, 11462.770744585228, 11836.171467997501, 12021.819672704742], "value": 9405.643023447907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.0232430325868, 1435.081547809402, 1462.2030993108756, 1556.9455069072585, 1747.6067884725937, 7593.240675932411, 8476.279516241118, 8709.34679899007, 8711.30309575234, 9015.41399809012, 8776.517291548962, 8845.336599755012, 8860.822783544325, 8869.822603547926, 8870.37907346486, 2344.4138965258726, 2366.6095094309503, 2371.430856594422, 2377.4769550698907, 2380.3811009137576, 2278.7835155660187, 2314.710661167354, 2325.046730840356, 2330.731965823935, 2331.160782118436, 2320.7447180810095, 2337.158622895765, 2350.2479206653793, 2353.317537914003, 2357.4106473381653, 2275.6017696902977, 2297.6438652008796, 2307.3539408965516, 2311.9422014449606, 2314.282572402552, 2257.7064981552458, 2265.4676151104477, 2269.1490690990877, 2272.011674921398, 2276.3284830974735, 8496.320294376497, 8538.82922341556, 8566.400351975364, 8580.528070956096, 8613.397062205648, 5077.441481437039, 5268.288781014552, 5270.367555893279, 5274.235235890775, 5280.07598670235, 3094.7166375470542, 3243.9675603244136, 3257.4299497587826, 3292.7859689120132, 3297.7196938260277, 1065.5631191211644, 1066.8666416697913, 1072.5388083124253, 1082.9025387715064, 1092.3336764573603, 11543.365114918675, 11608.187426880122, 11647.592334268345, 11666.533338666528, 11686.47410866513, 4211.957880421157, 4314.395984562164, 4377.693561450732, 4409.40473036136, 4497.0, 9140.497272650067, 9142.085791420805, 9145.314101442418, 9148.085191480799, 9153.862692059625, 5101.20931255656, 5107.97446012775, 5123.308353372322, 5123.462036486116, 5128.230765385222, 4319.200947824663, 4375.365571992043, 4436.889077773081, 4581.175388430114, 4802.903941921212, 3587.9641203588153, 3616.439451884962, 3789.905252368713, 3790.2608991246443, 3815.847366105325, 1077.5582011375297, 1083.106437189322, 1091.1216470740972, 1092.1481244629201, 1114.5820317380983, 930.5207817973749, 935.3499317974079, 940.4263399326405, 955.4362925873768, 985.9260555458369, 5578.721063946787, 5871.3541510433815, 5904.143899134601, 6064.9090263646085, 6228.719707613168, 447.3759106047046, 456.91090237403387, 958.836997710391, 1013.8681971343825, 1056.4664844253602, 3860.285847118236, 4141.710080294411, 4161.146964872254, 4174.165166966619, 4362.563743625612, 10691.679249622524, 11119.72200694989, 11124.833127503094, 11126.554937802559, 11140.49867755953, 10215.744606384751, 10424.68725938223, 11071.5571377144, 11079.169062320198, 11084.113270938306, 7945.64244608994, 8080.191980801873, 8104.270615644738, 8121.878171827428, 8152.307053900485, 6069.9089513656445, 6097.085437184458, 6111.358307377839, 6234.15838861766, 6276.246850377984, 9394.765130871783, 9943.055409736064, 9947.701568952943, 10051.994800520182, 10147.18822494184, 10177.08406243412, 10303.681671623825, 10311.432871192157, 10327.173826093744, 10336.948315258376, 7623.390128789572, 7679.500832446097, 7720.420968427278, 7728.033995750531, 7761.844763104594, 8235.21765432283, 8442.493450392996, 8496.192861678132, 8505.404621676424, 8508.021577518664, 7542.509736867086, 7588.468807183444, 7671.693132274647, 7809.4142939278645, 7952.204779522001, 7419.406447484081, 7432.665530051053, 7466.962665186639, 7474.171464524134, 7820.726274580363, 6888.552244104118, 7004.474664400089, 7012.298770122947, 7015.824604384727, 7020.368166865007, 4982.676126051795, 5033.295338652665, 5096.949030509796, 5152.460601469325, 5251.2123181523075, 5319.202119681924, 5322.254884316121, 5388.865278368072, 5474.562035037108, 5588.050031494575], "value": 4530.0606366336415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.27276717576297, 304.95273232648947, 308.0082135523613, 316.6548462176228, 318.087090051552, 5227.163653815391, 5237.554807841333, 5257.211418287259, 5262.289590905227, 5277.340332458442, 5120.92318615221, 5146.794128234867, 5232.659877107995, 5233.162693968966, 5250.558490302009, 4076.206469153563, 4085.53016403113, 4094.549599544048, 4094.877153685394, 4118.485189351331, 3812.1994381179984, 3950.407438884162, 3995.3008223560905, 4020.798960051986, 4094.488188976378, 3920.333543297632, 3941.822617982183, 3949.368101103824, 3956.129651476685, 3969.622885825842, 3810.8856734298015, 3926.11662375966, 3938.310795610785, 3953.2488827122897, 3997.8001099944886, 3400.3199360128074, 3421.007907706762, 3488.0930957951114, 3494.580650321978, 3495.16116132129, 4991.425986011619, 5018.874528136789, 5023.899522009576, 5029.346184995964, 5036.589754868643, 4296.806643701041, 4544.659150563721, 4549.294859296781, 4551.453825540956, 4554.86335409935, 5026.5727413169925, 5137.691738495702, 5339.599530035224, 5355.651882627656, 5385.488378604026, 3818.7135964802474, 3825.9043523911846, 3830.8850734477733, 3832.0, 3845.5577608574813, 5271.42014378418, 5563.944360556424, 5611.130274807411, 5623.184638227475, 5626.549876009909, 3867.903302417462, 3902.297586434412, 3908.257431088084, 3909.58949310324, 3925.293447179478, 3941.2117576484816, 3944.7633142010995, 3947.8618248361167, 3954.4661470701085, 3970.404439334065, 4558.703684260512, 4579.694786985693, 4589.609883159968, 4602.125596136723, 4614.515475875052, 5170.0, 5386.522199788063, 5400.91898621521, 5424.511793938565, 5493.368262649767, 1349.9190048597115, 1364.863513648647, 1365.3241645385597, 1366.473907545602, 1367.3026756354332, 839.9664013439394, 871.6034204436966, 878.4202426398674, 887.2414085956543, 890.6615486115235, 4601.194790911577, 4610.515895830889, 4622.399088118523, 4643.86068417948, 4647.814807224204, 4838.322634831126, 4861.440934292531, 4868.440129385095, 4885.070397193089, 4998.875028124253, 4419.204543182258, 4610.939483918717, 4617.492075871652, 4617.868622187586, 4654.883627909329, 2587.560114780492, 2589.7539733725644, 2597.8179928132654, 2612.3991481959256, 2785.442911417684, 5580.693061881636, 5637.351704553947, 5752.137179423036, 5808.157817116495, 5808.664007278349, 5695.943040569624, 5707.942920570741, 5712.742926568231, 5721.199032135504, 5723.7996670116345, 5626.1842032905, 5701.942980570224, 5731.942680573141, 5765.423457654285, 5796.826190178683, 5486.177073439016, 5513.145462453326, 5515.855962101564, 5516.448355164452, 5558.888822223614, 5366.6779993200535, 5487.42382049895, 5498.697571633438, 5509.5041446270025, 5517.324097812638, 5652.208690783212, 5663.9716801415725, 5675.77296908119, 5684.77260909559, 5687.175359572756, 3870.8258128384855, 3885.902852428712, 3888.747231429948, 3938.2714197873483, 3954.051027753376, 5379.953656942466, 5579.191017302387, 5593.874327755427, 5623.578231632726, 5656.406077361982, 5391.568674505952, 5413.864653383697, 5414.049343107937, 5417.024935511646, 5432.402435731988, 5365.59758018158, 5379.704116273642, 5381.381141168816, 5392.299001129828, 5417.431169727122, 5114.23831131185, 5322.946770532245, 5433.293671822637, 5434.918476222939, 5447.972760136174, 5490.313710786152, 5494.033222060043, 5500.4499550044675, 5500.50495455395, 5508.807191748269, 5350.234422640441, 5394.730263486731, 5396.541293990055, 5397.839464515148, 5426.945730542644], "value": 4050.5413200928156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.5498528375618, 767.9681671226064, 777.2460713108285, 787.1498781316179, 809.842080794245, 15225.84774152258, 15647.374105035813, 15697.45058922938, 15697.84302156978, 16124.596885077879, 12681.048921330914, 12883.162594431355, 12906.870931290685, 12944.352782360867, 13021.414036368362, 661.3750006244102, 767.2327672327687, 816.5753808019826, 819.0253598218131, 819.6680344460473, 678.9864202715943, 794.253401802304, 812.1715849833166, 817.4114637461018, 819.4755356571799, 844.4553263145299, 845.3406343052429, 846.2003406780594, 846.2975730143996, 846.6190214403513, 686.5057158845623, 850.5152063323908, 851.9787005324916, 853.6030745703277, 854.1671869926803, 520.7448350308322, 594.2245369792419, 612.2897438970773, 616.7286393986676, 618.2889676871633, 15658.530244092695, 15692.058476491447, 15692.372305107781, 15700.68598628021, 15721.541260922579, 14050.0, 14350.71298574033, 14770.187639679812, 14773.113613183243, 15177.24113794306, 13005.804912926315, 13078.607641770763, 13191.0, 13520.729585408335, 13630.863691363058, 4319.568043195687, 4350.630196433275, 4370.781460926941, 4407.2507673695545, 4413.820453080876, 82466.58766706187, 82587.0, 82724.93187670296, 83870.0, 84005.0, 11379.487923043484, 11451.427428628534, 11781.705457363463, 11859.789774630055, 11860.881391185978, 22158.335249942527, 22172.556548869255, 22180.112795488323, 22408.215712449986, 23332.300030999097, 8209.958950205211, 8312.70905518316, 8371.916280837235, 8487.70293039753, 8526.616302266413, 4470.888227794331, 4659.114768194032, 4718.457377401574, 4803.279508073817, 4920.384951881015, 3200.7439404847555, 3386.745994050456, 3395.439752440869, 3401.200717831308, 3403.5064915587595, 556.7438978070052, 563.8674911395818, 563.8731285460739, 569.7777866632017, 571.5598988778618, 529.2220435959134, 552.7844140785097, 578.6614830324223, 592.2862950145064, 595.2172892646156, 8751.56242187888, 8935.151160639729, 8938.972018217988, 9060.0, 9204.585793639168, 568.2158621102934, 624.4567226512872, 625.6683957502531, 636.176151883303, 661.9338066193342, 4392.38506609075, 4442.133783912106, 4469.5306992765945, 4647.86056418308, 4661.114388266218, 69317.65341173363, 70601.0, 117249.0, 123897.0, 138176.30911845376, 107448.0, 110142.0, 111149.0, 114414.0, 118242.40878795552, 90234.09765902258, 90458.54770726104, 91718.0, 95837.0, 95911.0, 8155.836883262183, 8366.247037766387, 9754.80490390174, 9845.753856153653, 9868.703938881845, 44872.32691509695, 47271.76364118295, 49468.0, 49776.75111624419, 49852.50147498624, 51563.48436515587, 51676.0, 62322.37677623166, 71357.28642713507, 74369.0, 11833.467493962964, 11932.701682458008, 12181.939090304491, 12373.505059797868, 12614.621561353175, 14834.77747833805, 14928.0, 15436.536903892453, 15484.148371839658, 15918.124503152438, 13288.335583220995, 13373.197608143542, 13802.17186968785, 14099.0, 14503.419863205354, 13691.383887724876, 13790.379432925341, 14512.85487145115, 14779.187144706715, 15062.694990362383, 13872.4451021958, 13906.0, 13920.164790112625, 14342.784858226927, 14472.565823025327, 10924.890751092387, 10953.507092181031, 11459.31244125355, 11814.704632383915, 11856.585019524275, 11085.390303533382, 11611.428799536954, 11658.358790266617, 11845.5854045108, 11984.880151198377], "value": 7457.643027610117, "unit": "score"}], "name": "dromaeo_css", "value": 7182.349669308063, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 116.0, 116.0, 113.0, 118.0, 114.0, 115.0, 114.0, 115.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 121.0, 117.0, 163.0, 120.0, 124.0, 119.0, 118.0, 148.0, 119.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 163.0, 162.0, 161.0, 161.0, 163.0, 164.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 112.0, 78.0, 76.0, 76.0, 76.0, 97.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 116.0, 83.0, 83.0, 83.0, 108.0, 83.0, 83.0, 109.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 50.0, 52.0, 53.0, 52.0, 51.0, 52.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 60.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 95.0, 80.0, 79.0, 81.0, 89.0, 80.0, 87.0, 80.0, 78.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 137.0, 126.0, 129.0, 141.0, 133.0, 132.0, 172.0, 127.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 164.0, 164.0, 162.0, 169.0, 161.0, 167.0, 163.0, 161.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 66.0, 66.0, 69.0, 64.0, 64.0, 64.0, 64.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1500.8, "alertThreshold": 2.0}]} 14:29:58 INFO - Return code: 0 14:29:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:29:58 INFO - # TBPL SUCCESS # 14:29:58 INFO - Running post-action listener: _resource_record_post_action 14:29:58 INFO - [mozharness: 2016-09-19 21:29:58.444922Z] Finished run-tests step (success) 14:29:58 INFO - Running post-run listener: _resource_record_post_run 14:29:58 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 425984; Write bytes: 393506816; Read time: 248; Write time: 265344 14:29:58 INFO - TinderboxPrint: CPU usage
13.1% 14:29:58 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 248 14:29:58 INFO - TinderboxPrint: I/O write bytes / time
393,506,816 / 265,344 14:29:58 INFO - TinderboxPrint: CPU idle
8,280.5 (86.8%) 14:29:58 INFO - TinderboxPrint: CPU user
1,200.6 (12.6%) 14:29:58 INFO - TinderboxPrint: Swap in / out
0 / 0 14:29:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103526400; Read time: 0; Write time: 77184 14:29:58 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 421888; Write bytes: 289980416; Read time: 240; Write time: 188160 14:29:59 INFO - Running post-run listener: _upload_blobber_files 14:29:59 INFO - Blob upload gear active. 14:29:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:29:59 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 14:29:59 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:29:59 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:29:59 INFO - (blobuploader) - INFO - Open directory for files ... 14:29:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:29:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:30:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:04 INFO - (blobuploader) - INFO - Done attempting. 14:30:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:30:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:05 INFO - (blobuploader) - INFO - Done attempting. 14:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:30:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:06 INFO - (blobuploader) - INFO - Done attempting. 14:30:06 INFO - (blobuploader) - INFO - Iteration through files over. 14:30:06 INFO - Return code: 0 14:30:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:30:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:30:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4e267f9a8e029aa7966a389a48e9f8363a14065804612a8a8dead9af27cd944a5560f978e1df1e3b988334d429e0a651e3559780a0d82c238c53d1c2ac42ca9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47873d9e086bc05a0ba4323e92d46dd189480ea5705e2e2f71f6c8f52b89c78ac84ec4a0914a10b6a4a91ed535a661b1c9b9c6233524ba00146fc7f92b060fdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9d3246b56866a53080e213f63e9c3240512a5cdb9db5e9e7d2f973a922e92dcdee31f4192a077f5abb671cea151420743ee9c6f065732321dad118784fe943f"} 14:30:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:30:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:30:06 INFO - Contents: 14:30:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4e267f9a8e029aa7966a389a48e9f8363a14065804612a8a8dead9af27cd944a5560f978e1df1e3b988334d429e0a651e3559780a0d82c238c53d1c2ac42ca9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47873d9e086bc05a0ba4323e92d46dd189480ea5705e2e2f71f6c8f52b89c78ac84ec4a0914a10b6a4a91ed535a661b1c9b9c6233524ba00146fc7f92b060fdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9d3246b56866a53080e213f63e9c3240512a5cdb9db5e9e7d2f973a922e92dcdee31f4192a077f5abb671cea151420743ee9c6f065732321dad118784fe943f"} 14:30:06 INFO - Running post-run listener: copy_logs_to_upload_dir 14:30:06 INFO - Copying logs to upload dir... 14:30:06 INFO - mkdir: /builds/slave/test/build/upload/logs 14:30:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.192333 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-19 14:30:06.043617) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 14:30:06.047146) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4e267f9a8e029aa7966a389a48e9f8363a14065804612a8a8dead9af27cd944a5560f978e1df1e3b988334d429e0a651e3559780a0d82c238c53d1c2ac42ca9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47873d9e086bc05a0ba4323e92d46dd189480ea5705e2e2f71f6c8f52b89c78ac84ec4a0914a10b6a4a91ed535a661b1c9b9c6233524ba00146fc7f92b060fdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9d3246b56866a53080e213f63e9c3240512a5cdb9db5e9e7d2f973a922e92dcdee31f4192a077f5abb671cea151420743ee9c6f065732321dad118784fe943f"} build_url:https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011440 build_url: 'https://queue.taskcluster.net/v1/task/FqNgGEEyQsKakQiy2-xrCg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4e267f9a8e029aa7966a389a48e9f8363a14065804612a8a8dead9af27cd944a5560f978e1df1e3b988334d429e0a651e3559780a0d82c238c53d1c2ac42ca9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47873d9e086bc05a0ba4323e92d46dd189480ea5705e2e2f71f6c8f52b89c78ac84ec4a0914a10b6a4a91ed535a661b1c9b9c6233524ba00146fc7f92b060fdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9d3246b56866a53080e213f63e9c3240512a5cdb9db5e9e7d2f973a922e92dcdee31f4192a077f5abb671cea151420743ee9c6f065732321dad118784fe943f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 14:30:06.072796) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:30:06.073157) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1474319362.26567-149150558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 14:30:06.156706) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 14:30:06.157032) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 14:30:06.157331) ========= ========= Total master_lag: 0.17 =========