builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1474197244.89 results: success (0) buildid: 20160917163004 builduid: 82db576dbede4acda10282dbf203727c revision: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:04.886572) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:04.887172) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:04.887517) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:04.902491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:04.902819) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 04:14:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 15.8M=0.001s 2016-09-18 04:14:05 (15.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120307 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.037888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.038301) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017273 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.089726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.090137) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 --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=c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 04:14:05,257 truncating revision to first 12 chars 2016-09-18 04:14:05,257 Setting DEBUG logging. 2016-09-18 04:14:05,257 attempt 1/10 2016-09-18 04:14:05,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/eaf5eb6f8fa0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-18 04:14:05,714 unpacking tar archive at: mozilla-central-eaf5eb6f8fa0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.781044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.887851) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.888724) ========= script_repo_revision: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.889835) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.890516) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 04:14:05.902252) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-18 04:14:05.902606) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:14:06 INFO - MultiFileLogger online at 20160918 04:14:06 in /builds/slave/test 04:14:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:14:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:14:06 INFO - {'append_to_log': False, 04:14:06 INFO - 'base_work_dir': '/builds/slave/test', 04:14:06 INFO - 'blob_upload_branch': 'Firefox', 04:14:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:14:06 INFO - 'branch': 'Firefox', 04:14:06 INFO - 'buildbot_json_path': 'buildprops.json', 04:14:06 INFO - 'config_files': ('talos/linux_config.py',), 04:14:06 INFO - 'default_actions': ('clobber', 04:14:06 INFO - 'read-buildbot-config', 04:14:06 INFO - 'download-and-extract', 04:14:06 INFO - 'populate-webroot', 04:14:06 INFO - 'create-virtualenv', 04:14:06 INFO - 'install', 04:14:06 INFO - 'run-tests'), 04:14:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:14:06 INFO - 'download_minidump_stackwalk': True, 04:14:06 INFO - 'download_symbols': 'ondemand', 04:14:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:14:06 INFO - 'tooltool.py': '/tools/tooltool.py', 04:14:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:14:06 INFO - '/tools/misc-python/virtualenv.py')}, 04:14:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:14:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:14:06 INFO - 'installer_path': 'installer.exe', 04:14:06 INFO - 'log_level': 'info', 04:14:06 INFO - 'log_name': 'talos', 04:14:06 INFO - 'log_to_console': True, 04:14:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:14:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:14:06 INFO - 'opt_config_files': (), 04:14:06 INFO - 'pip_index': False, 04:14:06 INFO - 'sps_profile': False, 04:14:06 INFO - 'sps_profile_interval': 0, 04:14:06 INFO - 'suite': 'dromaeojs', 04:14:06 INFO - 'system_bits': '32', 04:14:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:14:06 INFO - 'title': 'talos-linux64-ix-004', 04:14:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:14:06 INFO - 'use_talos_json': True, 04:14:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:14:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:14:06 INFO - 'work_dir': 'build'} 04:14:06 INFO - [mozharness: 2016-09-18 11:14:06.158109Z] Running clobber step. 04:14:06 INFO - Running pre-action listener: _resource_record_pre_action 04:14:06 INFO - Running main action method: clobber 04:14:06 INFO - rmtree: /builds/slave/test/build 04:14:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:14:06 INFO - Running post-action listener: _resource_record_post_action 04:14:06 INFO - [mozharness: 2016-09-18 11:14:06.618387Z] Finished clobber step (success) 04:14:06 INFO - [mozharness: 2016-09-18 11:14:06.618489Z] Running read-buildbot-config step. 04:14:06 INFO - Running pre-action listener: _resource_record_pre_action 04:14:06 INFO - Running main action method: read_buildbot_config 04:14:06 INFO - Using buildbot properties: 04:14:06 INFO - { 04:14:06 INFO - "project": "", 04:14:06 INFO - "product": "firefox", 04:14:06 INFO - "script_repo_revision": "production", 04:14:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:14:06 INFO - "repository": "", 04:14:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:14:06 INFO - "buildid": "20160917163004", 04:14:06 INFO - "pgo_build": "True", 04:14:06 INFO - "basedir": "/builds/slave/test", 04:14:06 INFO - "buildnumber": 458, 04:14:06 INFO - "slavename": "talos-linux64-ix-004", 04:14:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:14:06 INFO - "platform": "ubuntu64_hw", 04:14:06 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:14:06 INFO - "branch": "mozilla-central", 04:14:06 INFO - "repo_path": "mozilla-central", 04:14:06 INFO - "revision": "eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7", 04:14:06 INFO - "stage_platform": "linux64-pgo", 04:14:06 INFO - "builduid": "82db576dbede4acda10282dbf203727c", 04:14:06 INFO - "slavebuilddir": "test" 04:14:06 INFO - } 04:14:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 04:14:06 INFO - Running post-action listener: _resource_record_post_action 04:14:06 INFO - [mozharness: 2016-09-18 11:14:06.621069Z] Finished read-buildbot-config step (success) 04:14:06 INFO - [mozharness: 2016-09-18 11:14:06.621160Z] Running download-and-extract step. 04:14:06 INFO - Running pre-action listener: _resource_record_pre_action 04:14:06 INFO - Running main action method: download_and_extract 04:14:06 INFO - mkdir: /builds/slave/test/build/tests 04:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:06 INFO - trying https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:14:06 INFO - Downloading https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/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 04:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/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 04:14:07 INFO - Downloaded 1361 bytes. 04:14:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:14:07 INFO - Using the following test package requirements: 04:14:07 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:14:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:14:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:14:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'jsshell-linux-x86_64.zip'], 04:14:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:14:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:14:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:14:07 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:14:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:14:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:14:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:14:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:14:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:14:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:14:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:14:07 INFO - Expected file size: 21936433 04:14:12 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 04:14:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:14:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:14:12 INFO - Fetch https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:14:12 INFO - Expected file size: 21936433 04:14:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 04:14:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:14:17 INFO - Fetch https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:14:17 INFO - Expected file size: 14414048 04:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:18 INFO - trying https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:14:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/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 04:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/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 04:14:19 INFO - Downloaded 59567506 bytes. 04:14:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:14:19 INFO - mkdir: /builds/slave/test/properties 04:14:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:14:19 INFO - Writing to file /builds/slave/test/properties/build_url 04:14:19 INFO - Contents: 04:14:19 INFO - build_url:https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:14:20 INFO - Running post-action listener: _resource_record_post_action 04:14:20 INFO - Running post-action listener: set_extra_try_arguments 04:14:20 INFO - [mozharness: 2016-09-18 11:14:20.227964Z] Finished download-and-extract step (success) 04:14:20 INFO - [mozharness: 2016-09-18 11:14:20.228195Z] Running populate-webroot step. 04:14:20 INFO - Running pre-action listener: _resource_record_pre_action 04:14:20 INFO - Running main action method: populate_webroot 04:14:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:14:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:14:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:14:20 INFO - '--tppagecycles', 04:14:20 INFO - '7'], 04:14:20 INFO - 'tests': ['tsvgm']}}, 04:14:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:14:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:14:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:14:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:14:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['damp', 'tps']}, 04:14:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'tests': ['damp', 'tps']}, 04:14:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['dromaeo_dom']}, 04:14:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:14:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['basic_compositor_video']}, 04:14:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:14:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['a11yr', 04:14:20 INFO - 'ts_paint', 04:14:20 INFO - 'tpaint', 04:14:20 INFO - 'sessionrestore', 04:14:20 INFO - 'sessionrestore_no_auto_restore', 04:14:20 INFO - 'tabpaint']}, 04:14:20 INFO - 'other-e10s': {'tests': ['a11yr', 04:14:20 INFO - 'ts_paint', 04:14:20 INFO - 'tpaint', 04:14:20 INFO - 'sessionrestore', 04:14:20 INFO - 'sessionrestore_no_auto_restore', 04:14:20 INFO - 'tabpaint']}, 04:14:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:14:20 INFO - 'ts_paint', 04:14:20 INFO - 'tpaint', 04:14:20 INFO - 'sessionrestore', 04:14:20 INFO - 'sessionrestore_no_auto_restore', 04:14:20 INFO - 'tabpaint']}, 04:14:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:14:20 INFO - 'ts_paint', 04:14:20 INFO - 'tpaint', 04:14:20 INFO - 'sessionrestore', 04:14:20 INFO - 'sessionrestore_no_auto_restore', 04:14:20 INFO - 'tabpaint']}, 04:14:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['a11yr', 04:14:20 INFO - 'ts_paint', 04:14:20 INFO - 'tpaint', 04:14:20 INFO - 'sessionrestore', 04:14:20 INFO - 'sessionrestore_no_auto_restore', 04:14:20 INFO - 'tabpaint']}, 04:14:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['a11yr', 04:14:20 INFO - 'ts_paint', 04:14:20 INFO - 'tpaint', 04:14:20 INFO - 'sessionrestore', 04:14:20 INFO - 'sessionrestore_no_auto_restore', 04:14:20 INFO - 'tabpaint']}, 04:14:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['tsvgx', 04:14:20 INFO - 'tsvgr_opacity', 04:14:20 INFO - 'tart', 04:14:20 INFO - 'tscrollx', 04:14:20 INFO - 'cart']}, 04:14:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:14:20 INFO - 'tsvgr_opacity', 04:14:20 INFO - 'tart', 04:14:20 INFO - 'tscrollx', 04:14:20 INFO - 'cart']}, 04:14:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'talos_options': ['--disable-e10s'], 04:14:20 INFO - 'tests': ['tp5o']}, 04:14:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'tests': ['tp5o']}, 04:14:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'talos_options': ['--disable-e10s', 04:14:20 INFO - '--xperf_path', 04:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:20 INFO - 'tests': ['tp5n']}, 04:14:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:14:20 INFO - 'talos_options': ['--xperf_path', 04:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:20 INFO - 'tests': ['tp5n']}}, 04:14:20 INFO - 'talos.zip': {'path': '', 04:14:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:14:20 INFO - Running post-action listener: _resource_record_post_action 04:14:20 INFO - [mozharness: 2016-09-18 11:14:20.247056Z] Finished populate-webroot step (success) 04:14:20 INFO - [mozharness: 2016-09-18 11:14:20.247145Z] Running create-virtualenv step. 04:14:20 INFO - Running pre-action listener: _resource_record_pre_action 04:14:20 INFO - Running main action method: create_virtualenv 04:14:20 INFO - Creating virtualenv /builds/slave/test/build/venv 04:14:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:14:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:14:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:14:20 INFO - Using real prefix '/usr' 04:14:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:14:20 INFO - Installing distribute.............................................................................................................................................................................................done. 04:14:22 INFO - Installing pip.................done. 04:14:22 INFO - Return code: 0 04:14:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:14:22 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')]} 04:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:22 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')]} 04:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:22 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:14:22 INFO - Using env: {'DISPLAY': ':0', 04:14:22 INFO - 'HOME': '/home/cltbld', 04:14:22 INFO - 'LANG': 'en_US.UTF-8', 04:14:22 INFO - 'LANGUAGE': 'en_US:en', 04:14:22 INFO - 'LOGNAME': 'cltbld', 04:14:22 INFO - 'MAIL': '/var/mail/cltbld', 04:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:22 INFO - 'MOZ_NO_REMOTE': '1', 04:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:22 INFO - 'NO_EM_RESTART': '1', 04:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:22 INFO - 'PWD': '/builds/slave/test', 04:14:22 INFO - 'SHELL': '/bin/bash', 04:14:22 INFO - 'SHLVL': '1', 04:14:22 INFO - 'TERM': 'linux', 04:14:22 INFO - 'TMOUT': '86400', 04:14:22 INFO - 'USER': 'cltbld', 04:14:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:22 INFO - '_': '/tools/buildbot/bin/python'} 04:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:14:23 INFO - Cleaning up... 04:14:23 INFO - Return code: 0 04:14:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:23 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:23 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 04:14:23 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 04:14:23 INFO - Using env: {'DISPLAY': ':0', 04:14:23 INFO - 'HOME': '/home/cltbld', 04:14:23 INFO - 'LANG': 'en_US.UTF-8', 04:14:23 INFO - 'LANGUAGE': 'en_US:en', 04:14:23 INFO - 'LOGNAME': 'cltbld', 04:14:23 INFO - 'MAIL': '/var/mail/cltbld', 04:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:23 INFO - 'MOZ_NO_REMOTE': '1', 04:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:23 INFO - 'NO_EM_RESTART': '1', 04:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:23 INFO - 'PWD': '/builds/slave/test', 04:14:23 INFO - 'SHELL': '/bin/bash', 04:14:23 INFO - 'SHLVL': '1', 04:14:23 INFO - 'TERM': 'linux', 04:14:23 INFO - 'TMOUT': '86400', 04:14:23 INFO - 'USER': 'cltbld', 04:14:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:23 INFO - '_': '/tools/buildbot/bin/python'} 04:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:23 INFO - Downloading/unpacking psutil>=3.1.1 04:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:14:25 INFO - Installing collected packages: psutil 04:14:25 INFO - Running setup.py install for psutil 04:14:25 INFO - building 'psutil._psutil_linux' extension 04:14:25 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 04:14:25 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 04:14:25 INFO - building 'psutil._psutil_posix' extension 04:14:25 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 04:14:25 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 04:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:14:25 INFO - Successfully installed psutil 04:14:25 INFO - Cleaning up... 04:14:25 INFO - Return code: 0 04:14:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:25 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:25 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 04:14:25 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 04:14:25 INFO - Using env: {'DISPLAY': ':0', 04:14:25 INFO - 'HOME': '/home/cltbld', 04:14:25 INFO - 'LANG': 'en_US.UTF-8', 04:14:25 INFO - 'LANGUAGE': 'en_US:en', 04:14:25 INFO - 'LOGNAME': 'cltbld', 04:14:25 INFO - 'MAIL': '/var/mail/cltbld', 04:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:25 INFO - 'MOZ_NO_REMOTE': '1', 04:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:25 INFO - 'NO_EM_RESTART': '1', 04:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:25 INFO - 'PWD': '/builds/slave/test', 04:14:25 INFO - 'SHELL': '/bin/bash', 04:14:25 INFO - 'SHLVL': '1', 04:14:25 INFO - 'TERM': 'linux', 04:14:25 INFO - 'TMOUT': '86400', 04:14:25 INFO - 'USER': 'cltbld', 04:14:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:25 INFO - '_': '/tools/buildbot/bin/python'} 04:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:14:28 INFO - Installing collected packages: mozsystemmonitor 04:14:28 INFO - Running setup.py install for mozsystemmonitor 04:14:28 INFO - Successfully installed mozsystemmonitor 04:14:28 INFO - Cleaning up... 04:14:28 INFO - Return code: 0 04:14:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:28 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:28 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 04:14:28 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 04:14:28 INFO - Using env: {'DISPLAY': ':0', 04:14:28 INFO - 'HOME': '/home/cltbld', 04:14:28 INFO - 'LANG': 'en_US.UTF-8', 04:14:28 INFO - 'LANGUAGE': 'en_US:en', 04:14:28 INFO - 'LOGNAME': 'cltbld', 04:14:28 INFO - 'MAIL': '/var/mail/cltbld', 04:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:28 INFO - 'MOZ_NO_REMOTE': '1', 04:14:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:28 INFO - 'NO_EM_RESTART': '1', 04:14:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:28 INFO - 'PWD': '/builds/slave/test', 04:14:28 INFO - 'SHELL': '/bin/bash', 04:14:28 INFO - 'SHLVL': '1', 04:14:28 INFO - 'TERM': 'linux', 04:14:28 INFO - 'TMOUT': '86400', 04:14:28 INFO - 'USER': 'cltbld', 04:14:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:28 INFO - '_': '/tools/buildbot/bin/python'} 04:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:28 INFO - Downloading/unpacking jsonschema==2.5.1 04:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:14:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:30 INFO - Downloading functools32-3.2.3-2.tar.gz 04:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:14:30 INFO - warning: no files found matching '*.txt' 04:14:30 INFO - no previously-included directories found matching 'build' 04:14:30 INFO - no previously-included directories found matching 'dist' 04:14:30 INFO - no previously-included directories found matching '.git*' 04:14:30 INFO - Installing collected packages: jsonschema, functools32 04:14:30 INFO - Running setup.py install for functools32 04:14:30 INFO - warning: no files found matching '*.txt' 04:14:30 INFO - no previously-included directories found matching 'build' 04:14:30 INFO - no previously-included directories found matching 'dist' 04:14:30 INFO - no previously-included directories found matching '.git*' 04:14:30 INFO - Successfully installed jsonschema functools32 04:14:30 INFO - Cleaning up... 04:14:30 INFO - Return code: 0 04:14:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:14:30 INFO - Using env: {'DISPLAY': ':0', 04:14:30 INFO - 'HOME': '/home/cltbld', 04:14:30 INFO - 'LANG': 'en_US.UTF-8', 04:14:30 INFO - 'LANGUAGE': 'en_US:en', 04:14:30 INFO - 'LOGNAME': 'cltbld', 04:14:30 INFO - 'MAIL': '/var/mail/cltbld', 04:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:30 INFO - 'MOZ_NO_REMOTE': '1', 04:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:30 INFO - 'NO_EM_RESTART': '1', 04:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:30 INFO - 'PWD': '/builds/slave/test', 04:14:30 INFO - 'SHELL': '/bin/bash', 04:14:30 INFO - 'SHLVL': '1', 04:14:30 INFO - 'TERM': 'linux', 04:14:30 INFO - 'TMOUT': '86400', 04:14:30 INFO - 'USER': 'cltbld', 04:14:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:30 INFO - '_': '/tools/buildbot/bin/python'} 04:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:14:30 INFO - Cleaning up... 04:14:30 INFO - Return code: 0 04:14:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:14:30 INFO - Using env: {'DISPLAY': ':0', 04:14:30 INFO - 'HOME': '/home/cltbld', 04:14:30 INFO - 'LANG': 'en_US.UTF-8', 04:14:30 INFO - 'LANGUAGE': 'en_US:en', 04:14:30 INFO - 'LOGNAME': 'cltbld', 04:14:30 INFO - 'MAIL': '/var/mail/cltbld', 04:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:30 INFO - 'MOZ_NO_REMOTE': '1', 04:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:30 INFO - 'NO_EM_RESTART': '1', 04:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:30 INFO - 'PWD': '/builds/slave/test', 04:14:30 INFO - 'SHELL': '/bin/bash', 04:14:30 INFO - 'SHLVL': '1', 04:14:30 INFO - 'TERM': 'linux', 04:14:30 INFO - 'TMOUT': '86400', 04:14:30 INFO - 'USER': 'cltbld', 04:14:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:30 INFO - '_': '/tools/buildbot/bin/python'} 04:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:31 INFO - Downloading/unpacking blobuploader==1.2.4 04:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:33 INFO - Downloading blobuploader-1.2.4.tar.gz 04:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:14:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:14:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:33 INFO - Downloading docopt-0.6.1.tar.gz 04:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:14:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:14:33 INFO - Running setup.py install for blobuploader 04:14:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:14:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:14:33 INFO - Running setup.py install for requests 04:14:34 INFO - Running setup.py install for docopt 04:14:34 INFO - Successfully installed blobuploader requests docopt 04:14:34 INFO - Cleaning up... 04:14:34 INFO - Return code: 0 04:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:34 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:14:34 INFO - Using env: {'DISPLAY': ':0', 04:14:34 INFO - 'HOME': '/home/cltbld', 04:14:34 INFO - 'LANG': 'en_US.UTF-8', 04:14:34 INFO - 'LANGUAGE': 'en_US:en', 04:14:34 INFO - 'LOGNAME': 'cltbld', 04:14:34 INFO - 'MAIL': '/var/mail/cltbld', 04:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:34 INFO - 'MOZ_NO_REMOTE': '1', 04:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:34 INFO - 'NO_EM_RESTART': '1', 04:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:34 INFO - 'PWD': '/builds/slave/test', 04:14:34 INFO - 'SHELL': '/bin/bash', 04:14:34 INFO - 'SHLVL': '1', 04:14:34 INFO - 'TERM': 'linux', 04:14:34 INFO - 'TMOUT': '86400', 04:14:34 INFO - 'USER': 'cltbld', 04:14:34 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:34 INFO - '_': '/tools/buildbot/bin/python'} 04:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:14:34 INFO - Running setup.py (path:/tmp/pip-s2is14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:14:34 INFO - Running setup.py (path:/tmp/pip-6PAAoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:14:34 INFO - Running setup.py (path:/tmp/pip-7thYwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:14:34 INFO - Running setup.py (path:/tmp/pip-A1RzTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:14:34 INFO - Running setup.py (path:/tmp/pip-70olZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:14:35 INFO - Running setup.py (path:/tmp/pip-OkbUq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:14:35 INFO - Running setup.py (path:/tmp/pip-7i5jpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:14:35 INFO - Running setup.py (path:/tmp/pip-GKjUER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:14:35 INFO - Running setup.py (path:/tmp/pip-b4ty4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:14:35 INFO - Running setup.py (path:/tmp/pip-Av6NNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:14:35 INFO - Running setup.py (path:/tmp/pip-pDRZPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:14:35 INFO - Running setup.py (path:/tmp/pip-FqTIvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:14:35 INFO - Running setup.py (path:/tmp/pip-Mr1YyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:14:35 INFO - Running setup.py (path:/tmp/pip-7eqOIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:35 INFO - Running setup.py (path:/tmp/pip-T0Q4ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:14:35 INFO - Running setup.py (path:/tmp/pip-as5wRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:14:35 INFO - Running setup.py (path:/tmp/pip-RWEm0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:14:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:14:35 INFO - Running setup.py install for manifestparser 04:14:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozcrash 04:14:36 INFO - Running setup.py install for mozdebug 04:14:36 INFO - Running setup.py install for mozdevice 04:14:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozfile 04:14:36 INFO - Running setup.py install for mozhttpd 04:14:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozinfo 04:14:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozInstall 04:14:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for mozleak 04:14:36 INFO - Running setup.py install for mozlog 04:14:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:14:36 INFO - Running setup.py install for moznetwork 04:14:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Running setup.py install for mozprocess 04:14:37 INFO - Running setup.py install for mozprofile 04:14:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Running setup.py install for mozrunner 04:14:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Running setup.py install for mozscreenshot 04:14:37 INFO - Running setup.py install for moztest 04:14:37 INFO - Running setup.py install for mozversion 04:14:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:14:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:14:37 INFO - Cleaning up... 04:14:37 INFO - Return code: 0 04:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:37 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:14:37 INFO - Using env: {'DISPLAY': ':0', 04:14:37 INFO - 'HOME': '/home/cltbld', 04:14:37 INFO - 'LANG': 'en_US.UTF-8', 04:14:37 INFO - 'LANGUAGE': 'en_US:en', 04:14:37 INFO - 'LOGNAME': 'cltbld', 04:14:37 INFO - 'MAIL': '/var/mail/cltbld', 04:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:37 INFO - 'MOZ_NO_REMOTE': '1', 04:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:37 INFO - 'NO_EM_RESTART': '1', 04:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:37 INFO - 'PWD': '/builds/slave/test', 04:14:37 INFO - 'SHELL': '/bin/bash', 04:14:37 INFO - 'SHLVL': '1', 04:14:37 INFO - 'TERM': 'linux', 04:14:37 INFO - 'TMOUT': '86400', 04:14:37 INFO - 'USER': 'cltbld', 04:14:37 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:37 INFO - '_': '/tools/buildbot/bin/python'} 04:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:14:37 INFO - Running setup.py (path:/tmp/pip-TJzQ3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:14:38 INFO - Running setup.py (path:/tmp/pip-WS4tJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:14:38 INFO - Running setup.py (path:/tmp/pip-AwKBCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:14:38 INFO - Running setup.py (path:/tmp/pip-tZR4wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:14:38 INFO - Running setup.py (path:/tmp/pip-s8es8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:14:38 INFO - Running setup.py (path:/tmp/pip-JMcxAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:14:38 INFO - Running setup.py (path:/tmp/pip-6ZLJtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:14:38 INFO - Running setup.py (path:/tmp/pip-vmZKB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:14:38 INFO - Running setup.py (path:/tmp/pip-yrnAv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:14:38 INFO - Running setup.py (path:/tmp/pip-VHQfAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:14:38 INFO - Running setup.py (path:/tmp/pip-Fkj5tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:14:38 INFO - Running setup.py (path:/tmp/pip-jWchK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:14:38 INFO - Running setup.py (path:/tmp/pip-YDbVkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:14:38 INFO - Running setup.py (path:/tmp/pip-z0LXMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:14:38 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)) 04:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:38 INFO - Running setup.py (path:/tmp/pip-c4CMlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:14:39 INFO - Running setup.py (path:/tmp/pip-aaI8IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:14:39 INFO - Running setup.py (path:/tmp/pip-Em5Yey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:14:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:14:41 INFO - Downloading blessings-1.6.tar.gz 04:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:14:41 INFO - Installing collected packages: blessings 04:14:41 INFO - Running setup.py install for blessings 04:14:41 INFO - Successfully installed blessings 04:14:41 INFO - Cleaning up... 04:14:41 INFO - Return code: 0 04:14:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:14:41 INFO - Reading from file tmpfile_stdout 04:14:41 INFO - Current package versions: 04:14:41 INFO - argparse == 1.2.1 04:14:41 INFO - blessings == 1.6 04:14:41 INFO - blobuploader == 1.2.4 04:14:41 INFO - docopt == 0.6.1 04:14:41 INFO - functools32 == 3.2.3-2 04:14:41 INFO - jsonschema == 2.5.1 04:14:41 INFO - manifestparser == 1.1 04:14:41 INFO - mozInstall == 1.12 04:14:41 INFO - mozcrash == 0.17 04:14:41 INFO - mozdebug == 0.1 04:14:41 INFO - mozdevice == 0.48 04:14:41 INFO - mozfile == 1.2 04:14:41 INFO - mozhttpd == 0.7 04:14:41 INFO - mozinfo == 0.9 04:14:41 INFO - mozleak == 0.1 04:14:41 INFO - mozlog == 3.4 04:14:41 INFO - moznetwork == 0.27 04:14:41 INFO - mozprocess == 0.23 04:14:41 INFO - mozprofile == 0.28 04:14:41 INFO - mozrunner == 6.12 04:14:41 INFO - mozscreenshot == 0.1 04:14:41 INFO - mozsystemmonitor == 0.3 04:14:41 INFO - moztest == 0.7 04:14:41 INFO - mozversion == 1.4 04:14:41 INFO - psutil == 3.1.1 04:14:41 INFO - requests == 1.2.3 04:14:41 INFO - wsgiref == 0.1.2 04:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:14:41 INFO - Using env: {'DISPLAY': ':0', 04:14:41 INFO - 'HOME': '/home/cltbld', 04:14:41 INFO - 'LANG': 'en_US.UTF-8', 04:14:41 INFO - 'LANGUAGE': 'en_US:en', 04:14:41 INFO - 'LOGNAME': 'cltbld', 04:14:41 INFO - 'MAIL': '/var/mail/cltbld', 04:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:41 INFO - 'MOZ_NO_REMOTE': '1', 04:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:41 INFO - 'NO_EM_RESTART': '1', 04:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:41 INFO - 'PWD': '/builds/slave/test', 04:14:41 INFO - 'SHELL': '/bin/bash', 04:14:41 INFO - 'SHLVL': '1', 04:14:41 INFO - 'TERM': 'linux', 04:14:41 INFO - 'TMOUT': '86400', 04:14:41 INFO - 'USER': 'cltbld', 04:14:41 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:41 INFO - '_': '/tools/buildbot/bin/python'} 04:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:14:41 INFO - Cleaning up... 04:14:41 INFO - Return code: 0 04:14:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:14:41 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 0x7fbab7a26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', '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 04:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:14:41 INFO - Using env: {'DISPLAY': ':0', 04:14:41 INFO - 'HOME': '/home/cltbld', 04:14:41 INFO - 'LANG': 'en_US.UTF-8', 04:14:41 INFO - 'LANGUAGE': 'en_US:en', 04:14:41 INFO - 'LOGNAME': 'cltbld', 04:14:41 INFO - 'MAIL': '/var/mail/cltbld', 04:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:41 INFO - 'MOZ_NO_REMOTE': '1', 04:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:41 INFO - 'NO_EM_RESTART': '1', 04:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:41 INFO - 'PWD': '/builds/slave/test', 04:14:41 INFO - 'SHELL': '/bin/bash', 04:14:41 INFO - 'SHLVL': '1', 04:14:41 INFO - 'TERM': 'linux', 04:14:41 INFO - 'TMOUT': '86400', 04:14:41 INFO - 'USER': 'cltbld', 04:14:41 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:41 INFO - '_': '/tools/buildbot/bin/python'} 04:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:14:41 INFO - Cleaning up... 04:14:41 INFO - Return code: 0 04:14:41 INFO - Running post-action listener: _resource_record_post_action 04:14:41 INFO - Running post-action listener: _start_resource_monitoring 04:14:42 INFO - Starting resource monitoring. 04:14:42 INFO - [mozharness: 2016-09-18 11:14:42.021807Z] Finished create-virtualenv step (success) 04:14:42 INFO - [mozharness: 2016-09-18 11:14:42.022309Z] Running install step. 04:14:42 INFO - Running pre-action listener: _resource_record_pre_action 04:14:42 INFO - Running main action method: install 04:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:14:42 INFO - Reading from file tmpfile_stdout 04:14:42 INFO - Detecting whether we're running mozinstall >=1.0... 04:14:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:14:42 INFO - Reading from file tmpfile_stdout 04:14:42 INFO - Output received: 04:14:42 INFO - Usage: mozinstall [options] installer 04:14:42 INFO - Options: 04:14:42 INFO - -h, --help show this help message and exit 04:14:42 INFO - -d DEST, --destination=DEST 04:14:42 INFO - Directory to install application into. [default: 04:14:42 INFO - "/builds/slave/test"] 04:14:42 INFO - --app=APP Application being installed. [default: firefox] 04:14:42 INFO - mkdir: /builds/slave/test/build/application 04:14:42 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'] 04:14:42 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 04:14:59 INFO - Reading from file tmpfile_stdout 04:14:59 INFO - Output received: 04:14:59 INFO - /builds/slave/test/build/application/firefox/firefox 04:14:59 INFO - Running post-action listener: _resource_record_post_action 04:14:59 INFO - [mozharness: 2016-09-18 11:14:59.440560Z] Finished install step (success) 04:14:59 INFO - [mozharness: 2016-09-18 11:14:59.440902Z] Running run-tests step. 04:14:59 INFO - Running pre-action listener: _resource_record_pre_action 04:14:59 INFO - Running main action method: run_tests 04:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:14:59 INFO - Python 2.7.3 04:14:59 INFO - Return code: 0 04:14:59 INFO - grabbing minidump binary from tooltool 04:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:59 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 0x204b930>, '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 0x204c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204ccc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:14:59 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 04:14:59 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 04:14:59 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 04:14:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:14:59 INFO - Return code: 0 04:14:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:14:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:14:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:14:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:14:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:14:59 INFO - Using env: {'DISPLAY': ':0', 04:14:59 INFO - 'HOME': '/home/cltbld', 04:14:59 INFO - 'LANG': 'en_US.UTF-8', 04:14:59 INFO - 'LANGUAGE': 'en_US:en', 04:14:59 INFO - 'LOGNAME': 'cltbld', 04:14:59 INFO - 'MAIL': '/var/mail/cltbld', 04:14:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:59 INFO - 'MOZ_NO_REMOTE': '1', 04:14:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:14:59 INFO - 'NO_EM_RESTART': '1', 04:14:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:59 INFO - 'PWD': '/builds/slave/test', 04:14:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:59 INFO - 'SHELL': '/bin/bash', 04:14:59 INFO - 'SHLVL': '1', 04:14:59 INFO - 'TERM': 'linux', 04:14:59 INFO - 'TMOUT': '86400', 04:14:59 INFO - 'USER': 'cltbld', 04:14:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225', 04:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:59 INFO - '_': '/tools/buildbot/bin/python'} 04:14:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:14:59 INFO - mozversion application_buildid: 20160917163004 04:14:59 INFO - mozversion application_changeset: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 04:14:59 INFO - mozversion application_display_name: Nightly 04:14:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:14:59 INFO - mozversion application_name: Firefox 04:14:59 INFO - mozversion application_remotingname: firefox 04:14:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:14:59 INFO - mozversion application_vendor: Mozilla 04:14:59 INFO - mozversion application_version: 51.0a1 04:14:59 INFO - mozversion platform_buildid: 20160917163004 04:14:59 INFO - mozversion platform_changeset: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 04:14:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:14:59 INFO - mozversion platform_version: 51.0a1 04:14:59 INFO - using testdate: 1474197299 04:14:59 INFO - actual date: 1474197299 04:14:59 INFO - starting webserver on 'localhost:51833' 04:14:59 INFO - SUITE-START | Running 2 tests 04:14:59 INFO - TEST-START | dromaeo_css 04:14:59 INFO - operating with platform_type : linux_ 04:14:59 INFO - Initialising browser for dromaeo_css test... 04:14:59 INFO - TEST-INFO | started process 523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDkvjtA/profile http://localhost:51833/getInfo.html) 04:15:04 INFO - PROCESS | 523 | __metrics Screen width/height:1600/1200 04:15:04 INFO - PROCESS | 523 | colorDepth:24 04:15:04 INFO - PROCESS | 523 | Browser inner width/height: 1024/697 04:15:04 INFO - PROCESS | 523 | __metrics 04:15:05 INFO - PROCESS | 523 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:15:05 INFO - PROCESS | 523 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:15:05 INFO - PROCESS | 523 | console.error: PushService: 04:15:05 INFO - PROCESS | 523 | stateChangeProcessEnqueue: Error transitioning state 04:15:05 INFO - PROCESS | 523 | UnknownError 04:15:06 INFO - TEST-INFO | 523: exit 0 04:15:06 INFO - Browser initialized. 04:15:06 INFO - Running cycle 1/1 for dromaeo_css test... 04:15:06 INFO - TEST-INFO | started process 608 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDkvjtA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:18 INFO - PROCESS | 608 | RSS: Main: 173031424 04:15:18 INFO - PROCESS | 608 | 04:15:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:15:50 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:16:09 INFO - PROCESS | 608 | 1474197369570 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:16:13 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:16:19 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:16:21 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0667 seconds. 04:16:31 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:16:34 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:16:36 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:16:42 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0333 seconds. 04:16:46 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:16:54 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:16:57 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:17:08 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 04:17:10 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:17:20 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:17:25 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:17:43 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:18:00 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:18:02 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:18:04 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 04:18:18 INFO - PROCESS | 608 | Cycle 1(1): loaded http://localhost:51833/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51833/tests/dromaeo/cssquery-ext.html) 04:18:20 INFO - PROCESS | 608 | RSS: Main: 323014656 04:18:20 INFO - PROCESS | 608 | 04:18:23 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 04:18:28 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 04:18:40 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:18:55 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:18:57 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:19:29 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:19:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:19:43 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:19:57 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:19:59 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:20:11 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:20:19 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:20:21 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:20:23 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:20:27 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:20:40 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:20:48 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:20:58 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:21:05 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:21:12 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:21:19 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:21:22 INFO - PROCESS | 608 | Cycle 1(1): loaded http://localhost:51833/tests/dromaeo/cssquery-ext.html (next: http://localhost:51833/tests/dromaeo/cssquery-jquery.html) 04:21:23 INFO - PROCESS | 608 | RSS: Main: 271491072 04:21:23 INFO - PROCESS | 608 | 04:21:26 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:21:31 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:21:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:21:51 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:22:27 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:22:54 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 04:22:56 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 04:22:59 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:23:02 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:23:18 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:23:24 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:23:26 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:23:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 04:23:36 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:24:09 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:24:20 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 04:24:23 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:24:25 INFO - PROCESS | 608 | Cycle 1(1): loaded http://localhost:51833/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51833/tests/dromaeo/cssquery-mootools.html) 04:24:25 INFO - PROCESS | 608 | RSS: Main: 243908608 04:24:25 INFO - PROCESS | 608 | 04:24:31 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 04:24:32 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:24:34 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:24:46 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:24:50 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:25:06 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:25:16 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:25:40 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:25:41 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:25:49 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:25:57 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:26:09 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:26:19 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:26:29 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:26:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:26:39 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:26:47 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:26:49 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:27:11 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:27:15 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:27:22 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:27:27 INFO - PROCESS | 608 | Cycle 1(1): loaded http://localhost:51833/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51833/tests/dromaeo/cssquery-prototype.html) 04:27:28 INFO - PROCESS | 608 | RSS: Main: 215912448 04:27:28 INFO - PROCESS | 608 | 04:28:01 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:28:05 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 04:28:15 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:28:21 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:28:23 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:28:31 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:28:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 04:28:37 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:29:01 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 04:29:06 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:29:11 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:29:20 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:29:24 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:29:37 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:29:38 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:30:00 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:30:08 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:30:18 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 04:30:31 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:30:31 INFO - PROCESS | 608 | Cycle 1(1): loaded http://localhost:51833/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51833/tests/dromaeo/cssquery-yui.html) 04:30:31 INFO - PROCESS | 608 | RSS: Main: 213909504 04:30:31 INFO - PROCESS | 608 | 04:30:35 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 04:30:39 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:30:42 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:30:44 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:31:12 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:31:38 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 04:31:39 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 04:32:05 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:32:07 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:32:19 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:32:21 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:32:25 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:32:29 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:32:33 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:32:47 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:32:52 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 04:32:52 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 2.00003 seconds. 04:33:00 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:33:02 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:33:21 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:33:32 INFO - PROCESS | 608 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:33:34 INFO - PROCESS | 608 | Cycle 1(1): loaded http://localhost:51833/tests/dromaeo/cssquery-yui.html (next: http://localhost:51833/tests/dromaeo/cssquery-dojo.html) 04:33:35 INFO - PROCESS | 608 | RSS: Main: 307634176 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | __start_tp_report 04:33:35 INFO - PROCESS | 608 | _x_x_mozilla_page_load 04:33:35 INFO - PROCESS | 608 | _x_x_mozilla_page_load_details 04:33:35 INFO - PROCESS | 608 | |i|pagename|runs| 04:33:35 INFO - PROCESS | 608 | |0;dojo.html;3011.0816201058674;3033.2265272355544;3043.71084746949;3071.0940272619605;3082.1370016395417;5156.071907056728;5167.199084141959;5238.292830467885;5252.737363131842;5289.788408463657;5181.5854731621475;5183.481651834824;5260.473952604728;5260.92108618371;5266.104762190427;4711.90576188476;4716.174669432836;4764.047190561884;4774.880627984294;4849.054434385296;4842.443119041302;4875.366202393684;4892.119418504668;4897.608191344684;4924.4583095859425;4737.881552961169;4742.596879265267;4784.449788274358;4815.855524334275;4820.710757354553;4810.6873053251775;4816.084943860656;4869.975650121763;4871.2206047032305;4893.7063776173545;4753.762311884427;4788.401449818773;4789.4971028042255;4807.783649735736;4857.82997595086;5061.493850614946;5108.591312694975;5226;5229.346331708536;5283.788648454057;5577.832665020056;5621.522170615503;5642.576806739511;5644.322681278273;5707.885842283173;5667.093265077609;5673.26247587815;5681.715914204273;5684.431556844283;5684.744186511575;5126.871828204325;5289.735513224362;5292.179712144623;5296.152615581527;5351.03681337363;5637.013522633522;5637.577181711388;5687.829365119053;5694.658320500783;5761.222234998221;5402.864928376822;5459.426760190202;5468.507834294933;5469.097598896217;5469.726513674301;5516.113592852639;5560.527355174855;5564.666120032811;5566.387697353288;5635.042042852725;5442.972785136129;5498.945010549924;5514.09017512114;5555.444455554412;5571.303587051619;5628.831135065954;5635.436456354414;5692.14617807324;5700.857478563069;5702.944955183304;4368.847090351886;4369.322754972921;4370.104128653618;4416;4427.911441771146;3845.942310865339;3851.4030325299623;3888.8055597220027;3905.3556163232747;3925.0579860833195;5297.311349524537;5310.283111779938;5351.705656188947;5369.033573956647;5369.677819330853;5579.721013949286;5627.606067575231;5632.774689012475;5634.267545219177;5634.32388113429;5144.382674079135;5259.421463638997;5312.893742125136;5319.547838433777;5378.005069062236;4804.95195048045;4814.988852340977;4825.30033145199;4842.031593681278;4900.387451568554;5751.712414379264;5757.309122905278;5760.193572899798;5760.711964401847;5761.366249712612;5414.431484694051;5621.831345059655;5632.380438151882;5656.717164141859;5696;5600.159976003632;5719.799807006735;5786;5788.450097240755;5876.030454974882;5509.862253443696;5550.4449555044175;5555.055640541037;5557.41647127062;5561.777528898799;5639.971800140973;5654.038813401814;5657.2928383952;5657.3776884543495;5674.971625141848;5216.37403511581;5322.33470816148;5446.673199607878;5495.477929596682;5531.280933478622;5530.281063461724;5538.169274608841;5539.058360078877;5554.805581804618;5586.636868603528;5497.807576734955;5515.448455154453;5517.806876759294;5526.032944234823;5532.446755324436;5385.676859388449;5431.592630552804;5497.422770609189;5531;5557.249771280825;5443.156310771837;5445;5454.536364409069;5456.42707515721;5505.779768809202 04:33:35 INFO - PROCESS | 608 | |1;ext.html;562.8592851787054;596.5794115148818;602.4908951935614;611.7858749437695;617.4844005255612;33026.33947321052;43470.56529434705;43762;43965.12069758607;44026.91811573183;44603.77698111505;45517.31724024134;45540.31689524651;45587.316190257174;45598.31602525965;8926.375153739225;9077.818443631124;9083.364164508486;9088.227500662451;9088.409253398508;8914;9005.234555062794;9005.594748236344;9008;9008.819823603524;9004.594793234288;9022.54887255638;9040.819183616324;9047.638094476213;9052.275817934582;8988;9022.909770902272;9024.954875225647;9027.14242146995;9039.999999999935;8896.154865287788;8949.776255593597;8963.820723585557;8982.191602755784;8985.101489851028;42476.36285455721;42617.57382426198;42845.35731964023;42888.925552072506;42909.35635965463;3333.150046738066;3360.462326027824;3441.9633776684286;3587.443946188345;3636.345457817592;32458.837705811555;33094.17264568381;33166;34391.484127738106;34506.30987380263;4246.214450326699;4335.219660461115;4404.889877753082;4408.590947653563;4414.448193975753;354487;355476.22261888784;355928;356279;358366;61604.91543472558;61953;62031.379686203465;62051.689741551905;62098.37901620926;60392;63330.36669633337;64658.67670661616;64846;64886;16213.432529861399;17126.657466850844;17171.141442928052;17678.46964591064;17689.46931592054;13311.534096306583;13398.732025359439;13405;13407.262600557064;13408.664783380493;15107.924460377848;15565.688686226213;17178.74231886523;17224.397146099825;17301.221445035004;5722.399148089391;6015.608985416022;6161.476274516716;6174.8147555573405;6293.937060629427;903.5527413930147;986.329296078659;1023.2376879225025;1026.409814356733;1043.1029314789275;32795.72042795749;32808.83595582007;32841.3431731364;32875.83562082222;33630;1039.0804138337473;1084.766775143332;1113.8496302998992;1123.7523556920505;1138.3682056458579;16682.666346673243;17131.657366852844;17233.22450489731;17313.30746770115;17378.119781782647;539216;539672;540081;541993;542350;57894.710526447096;58050.12924806131;58235.12647310294;58368.708156458946;58369.41630583725;1286.3182513267982;1348.5482363408369;1356.348952502824;1358.7078778062764;1361.9387127579282;30757;31749.523757144125;32404.83797580997;32712.34575308528;33196.50205246875;52982.73508632432;52984.20523692225;53314.46685533095;53392.73303633457;53413.465865342405;462528;465571;465871;467175;468899;33691.83154084214;35177.07520947121;36543.63456365402;36553.81723091367;42125;37613.9609869229;49191.508084920126;49521;49798.75100624474;50926;38407.42388864225;40785.79607101945;42030;42788.358174628025;43164;40545.594544054184;40725.796371017954;41085.38371924364;41532.140148792714;41972.580274196866;40140.197196055335;41167.588324116376;41169.58830411657;41265;41588;33517;36144.638553614124;36319;37104.81447592745;37560.2487950234;27476.313092172855;28003.579946300415;28113.43773124567;29334.26664333409;29400.833960215325 04:33:35 INFO - PROCESS | 608 | |2;jquery.html;391.5125668542665;395.9148783011652;399.70222184472567;403.1111399364401;425.6658523059397;13897.235652039159;16899.746503802424;17920.641587168247;19409.22363105476;19435.708464373045;14608.707825843478;14992.625184370369;17777.555561110978;18307.35924242652;18472.815271847277;5155.81954631588;5629.746661400247;5653.434656534355;5660.858478538028;5663.235463212455;5420.83737487874;5534.612577119603;5547.195656629786;5613.635113717595;5633.014222511065;5529.364123125832;5560.666360018392;5562.332520097574;5564.33228012639;5571.693556854371;5369.62412631116;5504.862378440531;5526.889462210774;5542.224088627595;5549.639273447213;4897.118518666639;4935.062338155739;4936.679115857458;4937.259411088329;4946.752662366904;21846;21851.562968740694;22263.22078727253;22454.438639033993;22543.323700289016;2311.8959646815933;2351.1418332308754;2359.7168339799164;2965.4265912530927;3052.4810782166865;13619.727605447835;14137.505187318395;14472.131672099602;14971.775423368657;15024.924875375662;1859.7861245956753;1911.923523059076;1927.5855691026497;1945.9221631134737;1977.9703304450445;82644.58677706574;83752.99999999939;85397.57301213454;86838.5658071712;88302.55848720779;10129.949350253202;10197.796044079225;10239.795204095877;10425;10592.894071059345;15024.323905424282;15064.623384415478;15066.472673456376;15070.397184112513;15071.849281507264;7340.889886651705;7757.883631745528;7797.883031754528;8027.357811375076;8526.701565445179;7931.801704957307;8040;8225.095239523647;8539.786505337417;8548.871766923501;6417.454516366068;6459.644719540471;6516.315786842408;6551.836204094936;6563.113979612786;1868.2900497810745;1927.5373910261717;1938.1472152252963;1941.1458958058408;1963.0577322885094;898.9146031127033;933.7385532051037;973.9707808765748;1016.9796604067877;1034.9896501034893;17773.377931772327;18133.637327253644;18332.63334733298;19965;20039;966.2415004221652;984.2519685039422;997.9850302245472;1018.974525636865;1019.8368261078192;9896.653617123366;10396.844047339297;10826.566937322576;10879.401632910107;11053;174645.12677436785;386770;399345;521218;536037;88967.55516222506;94611;99859;104958;108480;495.7372592525967;505.26736232463077;505.7496539213113;505.9569936555434;511.2484647568069;10023.54894029785;10077.395356278646;10698.572057117628;10729.463526823783;10857.511411986638;41114;41898;42718.145637087706;42870.35694464523;43664.56335436732;41071;48607;53327.733361334496;59279.40720592739;60973.69513152406;8278.503289802631;8585.65657373698;8624.353173511887;8779.736607901517;8912.598933047899;10481.842772358574;12617.306048167438;12997.480100795863;13164.14433061848;13439.395227214603;10872.836907446554;11294.43527823622;11492.425378731197;11497.885021150016;11721;11399.430028498708;11572.942135289606;11583.884161158281;11699.122565807429;11728.354940478355;10964.06805421548;11019.724506887393;11082.778344433227;11396.658100257006;11739.823902641296;8207.548584827891;8410.411271211202;8562.48625082472;8669.956650216709;9037.186653201243;8852.557372131496;11187.72030699239;11272.436378181223;11428.628564572304;11519.596814111466 04:33:35 INFO - PROCESS | 608 | |3;mootools.html;1462.1154201707964;1484.2430360516134;1580.1309279896054;1720.5784582777221;1730.4376077774727;7794.571298578576;8077.151899050603;8152.3478121750195;8783.956080219597;8785.60464779085;8216.7945801355;8481.830363392728;8484.363672724556;8753.431026983242;8761.56192190391;2303.170858490946;2322.883855807211;2330.8135349172026;2336.6845475860796;2336.894839732208;2067.9276225332114;2153.967690484644;2263.4680850000236;2295.9540809183804;2315.5716192504356;2160.6326924422806;2174.0542863854275;2213.9889300553473;2293.0827668932475;2303.5047464795066;2203.2398822406244;2229.899654515543;2234.6759719840693;2234.8770817605105;2239.9664005039936;2174.5324755177553;2229.030371788277;2229.8216142708543;2230.119102954324;2233.530958498717;7926.405519585996;7940.325072368798;7942.444028917978;7951.04587449504;7954.681812727484;5013.398392192924;5168.638195326329;5236.188390799432;5239.056969745444;5258.973705131488;3220.484722444421;3317.61603164773;3383.139273399645;3387.6442973487674;3412.709919656832;1031.9071283584512;1096.117625311624;1120.3389999900098;1121.315997241682;1134.2060557609707;11123.221374503788;11482.48328825205;11568.53725850965;11909.94045029778;12054.939725301405;4332.090261045153;4463.152001119777;4467.329900514884;4605.470370907322;4639.280911458729;9375.953120234355;9464.52677366129;9470.289728270407;9483.146516813382;9487.715368538955;5067.822626208065;5071.061853557104;5075.847724568269;5077.644564880497;5077.644564880497;4615.630749540029;4661;4712.057588482317;4723.338732577442;4734.147853386355;3579.266250418658;3581.6955558777795;3658;3692.8153592320814;3697.8335974881193;1113.7271368514766;1132.841402203692;1145.541783286692;1150.3615493401242;1156.8206927926185;941.463365881441;963.3545524498603;976.5898322704481;978.2760757039739;992.503748125937;5543.390227075019;5668.319801623831;5727.828165155052;5751.166080918327;5756.827295181151;459.23078842937946;471.89340995365814;476.89508308172145;482.96886148074117;484.7673116903906;4000.6599439047427;4070.61329173728;4163.5836416358725;4166.6250037496775;4221.493420789464;9909.603615855429;10083.798324033478;10155.847662285072;10183.643572474928;10195.7451063724;10122.797544049077;10129.696109116738;10138.188944884392;10164.4917754112;10165.949170254102;7668.65491052904;7732.806679832936;7735.845283094307;7741.380689544935;7748.186440423787;5874.412558744262;6001.129836173817;6046.606970546901;6148;6309.4952403806665;9495.335326527076;9557.90442095598;9588.424694518351;9713.07725766051;9862.654807081717;9771.120599145826;9996.850047249443;10049.949750251202;10064.69805905824;10093.495325233562;7337;7535.8116047099265;7667;7678.731244406419;7721.266479684421;8464.153584641734;8466;8529.317654587494;8640.308775298086;8653.350998674998;7369.852602948018;7579.810504737426;7601.505902116345;7773.3004029637605;7779.92220077792;7349.390966207134;7406.777796666109;7407.074115735533;7448.851022979402;7690.538567685733;6942.930570694229;7060.3292687194635;7061.540999834994;7066.681999309941;7075.044868942627;5052.079713238796;5129.487051294841;5206.921896171636;5304.681719096867;5418.051840927901;5171.482851714949;5226.435880385597;5279.128943724396;5307.681539107666;5353.491418315254 04:33:35 INFO - PROCESS | 608 | |4;prototype.html;328.2696001396892;333.7180082830009;335.16878142207327;340.01226437199927;341.648102454472;5157.716325602095;5274.4461831507715;5280.3663560372715;5296.364436267649;5323.121684921988;5212.895742085156;5230.476952304768;5234.371875374951;5247.737613119348;5255.185446255827;3969.6625786808154;4047.5750046245016;4081.9183616327655;4109.445224894645;4127.4427952226515;4131.607497287753;4143.798298493449;4149.917001659965;4159.896002599929;4176.665866730655;4101.9179616407655;4102.8974275643195;4106.507219133693;4111.876643700693;4114.506259248894;3801.0877389426614;3876.3604005339225;4076.755394676299;4089.1821635672986;4134.875953721378;3621;3630.2013557017412;3631.527901372831;3635.1457407509215;3639.1266096137;5036.521530454601;5068.72122033288;5073.137566613685;5074.594032477393;5102.464241254689;4508.278675411918;4649.256119020941;4682.789274482657;4687.484376718558;4692.202325604655;5389.846939540374;5393.761588940585;5394.973025134888;5398.1902714593125;5430.185472179165;3989.4414781930554;3994.500687414073;3995.6403923647013;3999.580044095357;4003.5195776506716;5505.449455054502;5552.584091056836;5597.608167428241;5639.210510528529;5654.491095801398;4048.352263637804;4050.7772072536295;4055.1281474483126;4058.411530328086;4079.1841631673783;4106.3635136553885;4111.656355877121;4113.8971525712095;4116.958830411658;4119.938200926988;4498.595126438636;4655.95344046562;4666.013751199235;4668.626509879202;4673.135469938073;5412.510245287212;5434.565234781208;5442.040865288572;5455.608819750938;5464.26232458621;1414.8372937112158;1430.8354539227914;1432.9825823665105;1434.9238071446416;1437.5040610989192;860.6772460327377;900.3067637918897;906.4968942237122;917.5458148216676;919.5080631861922;4986.576141027981;5104.464031276737;5147.176451767774;5272.444628834503;5282.603804714662;5574.526165275997;5574.86062848432;5582.358028826656;5587.552995760329;5595.720213989284;5132.230165475134;5248.239005344204;5255.973720131375;5274.92087618686;5279.9208011879855;2848.928776780597;2899.4635992341487;2920.8101473404163;2920.8247505149757;2935.4422659695015;5637.68992705397;5640.858978525488;5644.209810626516;5647.463490968352;5653.077953495831;5496.890062198733;5681.602287839894;5686.459786320293;5690.2033715279895;5704.401037890962;5671.971640141773;5690.345610254791;5691.971540142273;5707.74315155819;5723.198752174699;5346.652467589594;5520.723963801875;5530.087535556588;5552.52803511706;5579.246801681721;5434.972825135849;5479.561635069264;5507.091329930517;5511.255980442589;5522.5581953442825;5669.971650141723;5694.014190066887;5713.485786279255;5735.942640573541;5747.597668163188;3768.3028639701743;3962.5839286875616;4087.610212527755;4089.9795501023495;4144.48193975753;5576.665400076008;5584.832455026356;5593;5637.9718101409235;5638.126090455986;5465.945340546544;5475.370332411823;5479.205515200353;5485.670859748428;5495.708508500579;5343;5410.729463526729;5431.755570999236;5436.7553460093595;5495.752691128829;5407.188921661782;5432.9185062223305;5448.918266225931;5455.863603409788;5457.645253058538;5270.262163297218;5336;5361.731913404236;5498.068880750283;5508.540236837322;5310.973445132904;5374.247605335178;5385.946140538545;5394.626364399652;5410.269613602113 04:33:35 INFO - PROCESS | 608 | |5;yui.html;740.1820987808475;790.7390561114829;797.1988151907334;813.6501304439092;832.4464231286191;15364.615884602876;16579.585510362245;16641;16648.91675541622;16708.665826683475;14984.700305993874;15094.924525377386;15101.773473397909;15110.697786044273;15124.319405626746;658.9242237142718;677.4884961853818;781.1875649324695;781.3046388714035;806.0931452116369;802;802.5666140284269;807.4186585658316;807.9030516338048;808.292743849132;859.230988265502;859.5444414460346;860.9053004169567;860.9784755381103;861.3023451004706;685.3626127701236;702.3783951203171;851.3487182305541;851.4891065360796;851.7104184577242;608.8477880529867;627.5889292513411;629.9905501417483;630.59956927351;630.7540059376847;14796.778048329285;14810.777838332433;14824.258787060604;14825.258737063104;14826.777598336033;13607.659808504768;13903.30483475822;14151.504697335544;14226.786598200932;14323.069000514934;12940;12995.350232488337;13012.60962171127;13091.607251782461;13171.341432928411;4155.501339839208;4202.768847713374;4239.47006624172;4273.487181538236;4410.713303635285;62249.68875155641;66035.66982165107;76339.61830190869;76956.61521692356;77366.74482903437;11114.610988615359;11144.387058711687;11175.665761139033;11341.036011939077;11404.118320477195;20524.692129618066;20600;20607.175712971613;20654.896725516275;20725.58548829015;8385.454945428528;8426.368022398223;8517.701880434155;8660.610272537751;8763.386562940661;4497.168023915586;4531.627604619001;4646.697964632288;4697.295405689106;4821.855344339676;3430.7598468106994;3549.5917969433845;3551.4140166872694;3553.893383198508;3555.4489054196642;562.7242651100931;579.4871538688291;580.0371383503364;586.3169407640137;587.8471597384626;666.7632990288378;705.220731092146;735.6248313360118;736.3262614707482;751.6730222353294;9508.667196648084;9564.66523671668;9785.608575657036;9883.209343252522;10066.245031622657;614.6373639552685;622.862970146567;717.125107369009;726.7565365602477;757.9583122928182;4368.7815609219415;4713.106622753027;4729.266963620645;4752.928706069411;4841.709497430165;64550.677246613464;73641;74717.25282747211;75207.24792752003;75214.24785752181;98866;104953.47523262334;107109;109599;111276.44361778139;58928.7053564738;60382;82501;85446.57276713701;93436.99999999865;8184.263416292562;8323;8437.620307086074;8519.233269005572;8718.912810872063;54852.62868428321;55293.44706552883;55341.44658553363;55617;55714;67589;75032.62483687546;83102.58448707718;83987.5800620993;84066.15933840828;12268.815967760669;12616.432260548114;13172.143810652276;13216.140950838164;13303.534376296782;12779.488820447077;12865.2924089176;12998.545050923172;13141.802872956721;13521.661958451117;12741.936290318488;12807.48770049225;13166.605001849959;13207.735845283232;13245.27151006704;12537;13150.562467406615;13577.272051167869;13604.795928061285;13791.379387927767;13255.337233138112;13375.531856385369;13582.728345432839;14009.08940918837;14324.14055156694;10451.592963146002;10765.730856728644;10888.61889833852;11001.339919604849;11261;10513.793293045694;11376.54493820238;11509.194356394972;11599.246049006788;11773.587924422603 04:33:35 INFO - PROCESS | 608 | __end_tp_report 04:33:35 INFO - PROCESS | 608 | __start_cc_report 04:33:35 INFO - PROCESS | 608 | _x_x_mozilla_cycle_collect,1161 04:33:35 INFO - PROCESS | 608 | __end_cc_report 04:33:35 INFO - PROCESS | 608 | __startTimestamp1474198415018__endTimestamp 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | ------- Summary: start ------- 04:33:35 INFO - PROCESS | 608 | Number of tests: 6 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | [#0] dojo.html Cycles:165 Average:5222.23 Median:5443.16 stddev:575.77 (10.6%) stddev-sans-first:550.78 04:33:35 INFO - PROCESS | 608 | Values: 3011.1 3033.2 3043.7 3071.1 3082.1 5156.1 5167.2 5238.3 5252.7 5289.8 5181.6 5183.5 5260.5 5260.9 5266.1 4711.9 4716.2 4764.0 4774.9 4849.1 4842.4 4875.4 4892.1 4897.6 4924.5 4737.9 4742.6 4784.4 4815.9 4820.7 4810.7 4816.1 4870.0 4871.2 4893.7 4753.8 4788.4 4789.5 4807.8 4857.8 5061.5 5108.6 5226.0 5229.3 5283.8 5577.8 5621.5 5642.6 5644.3 5707.9 5667.1 5673.3 5681.7 5684.4 5684.7 5126.9 5289.7 5292.2 5296.2 5351.0 5637.0 5637.6 5687.8 5694.7 5761.2 5402.9 5459.4 5468.5 5469.1 5469.7 5516.1 5560.5 5564.7 5566.4 5635.0 5443.0 5498.9 5514.1 5555.4 5571.3 5628.8 5635.4 5692.1 5700.9 5702.9 4368.8 4369.3 4370.1 4416.0 4427.9 3845.9 3851.4 3888.8 3905.4 3925.1 5297.3 5310.3 5351.7 5369.0 5369.7 5579.7 5627.6 5632.8 5634.3 5634.3 5144.4 5259.4 5312.9 5319.5 5378.0 4805.0 4815.0 4825.3 4842.0 4900.4 5751.7 5757.3 5760.2 5760.7 5761.4 5414.4 5621.8 5632.4 5656.7 5696.0 5600.2 5719.8 5786.0 5788.5 5876.0 5509.9 5550.4 5555.1 5557.4 5561.8 5640.0 5654.0 5657.3 5657.4 5675.0 5216.4 5322.3 5446.7 5495.5 5531.3 5530.3 5538.2 5539.1 5554.8 5586.6 5497.8 5515.4 5517.8 5526.0 5532.4 5385.7 5431.6 5497.4 5531.0 5557.2 5443.2 5445.0 5454.5 5456.4 5505.8 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | [#1] ext.html Cycles:180 Average:61885.35 Median:42702.97 stddev:122108.55 (285.9%) stddev-sans-first:122364.29 04:33:35 INFO - PROCESS | 608 | Values: 562.9 596.6 602.5 611.8 617.5 33026.3 43470.6 43762.0 43965.1 44026.9 44603.8 45517.3 45540.3 45587.3 45598.3 8926.4 9077.8 9083.4 9088.2 9088.4 8914.0 9005.2 9005.6 9008.0 9008.8 9004.6 9022.5 9040.8 9047.6 9052.3 8988.0 9022.9 9025.0 9027.1 9040.0 8896.2 8949.8 8963.8 8982.2 8985.1 42476.4 42617.6 42845.4 42888.9 42909.4 3333.2 3360.5 3442.0 3587.4 3636.3 32458.8 33094.2 33166.0 34391.5 34506.3 4246.2 4335.2 4404.9 4408.6 4414.4 354487.0 355476.2 355928.0 356279.0 358366.0 61604.9 61953.0 62031.4 62051.7 62098.4 60392.0 63330.4 64658.7 64846.0 64886.0 16213.4 17126.7 17171.1 17678.5 17689.5 13311.5 13398.7 13405.0 13407.3 13408.7 15107.9 15565.7 17178.7 17224.4 17301.2 5722.4 6015.6 6161.5 6174.8 6293.9 903.6 986.3 1023.2 1026.4 1043.1 32795.7 32808.8 32841.3 32875.8 33630.0 1039.1 1084.8 1113.8 1123.8 1138.4 16682.7 17131.7 17233.2 17313.3 17378.1 539216.0 539672.0 540081.0 541993.0 542350.0 57894.7 58050.1 58235.1 58368.7 58369.4 1286.3 1348.5 1356.3 1358.7 1361.9 30757.0 31749.5 32404.8 32712.3 33196.5 52982.7 52984.2 53314.5 53392.7 53413.5 462528.0 465571.0 465871.0 467175.0 468899.0 33691.8 35177.1 36543.6 36553.8 42125.0 37614.0 49191.5 49521.0 49798.8 50926.0 38407.4 40785.8 42030.0 42788.4 43164.0 40545.6 40725.8 41085.4 41532.1 41972.6 40140.2 41167.6 41169.6 41265.0 41588.0 33517.0 36144.6 36319.0 37104.8 37560.2 27476.3 28003.6 28113.4 29334.3 29400.8 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | [#2] jquery.html Cycles:180 Average:26490.54 Median:193580.76 stddev:70841.39 (36.6%) stddev-sans-first:71013.01 04:33:35 INFO - PROCESS | 608 | Values: 391.5 395.9 399.7 403.1 425.7 13897.2 16899.7 17920.6 19409.2 19435.7 14608.7 14992.6 17777.6 18307.4 18472.8 5155.8 5629.7 5653.4 5660.9 5663.2 5420.8 5534.6 5547.2 5613.6 5633.0 5529.4 5560.7 5562.3 5564.3 5571.7 5369.6 5504.9 5526.9 5542.2 5549.6 4897.1 4935.1 4936.7 4937.3 4946.8 21846.0 21851.6 22263.2 22454.4 22543.3 2311.9 2351.1 2359.7 2965.4 3052.5 13619.7 14137.5 14472.1 14971.8 15024.9 1859.8 1911.9 1927.6 1945.9 1978.0 82644.6 83753.0 85397.6 86838.6 88302.6 10129.9 10197.8 10239.8 10425.0 10592.9 15024.3 15064.6 15066.5 15070.4 15071.8 7340.9 7757.9 7797.9 8027.4 8526.7 7931.8 8040.0 8225.1 8539.8 8548.9 6417.5 6459.6 6516.3 6551.8 6563.1 1868.3 1927.5 1938.1 1941.1 1963.1 898.9 933.7 974.0 1017.0 1035.0 17773.4 18133.6 18332.6 19965.0 20039.0 966.2 984.3 998.0 1019.0 1019.8 9896.7 10396.8 10826.6 10879.4 11053.0 174645.1 386770.0 399345.0 521218.0 536037.0 88967.6 94611.0 99859.0 104958.0 108480.0 495.7 505.3 505.7 506.0 511.2 10023.5 10077.4 10698.6 10729.5 10857.5 41114.0 41898.0 42718.1 42870.4 43664.6 41071.0 48607.0 53327.7 59279.4 60973.7 8278.5 8585.7 8624.4 8779.7 8912.6 10481.8 12617.3 12997.5 13164.1 13439.4 10872.8 11294.4 11492.4 11497.9 11721.0 11399.4 11572.9 11583.9 11699.1 11728.4 10964.1 11019.7 11082.8 11396.7 11739.8 8207.5 8410.4 8562.5 8670.0 9037.2 8852.6 11187.7 11272.4 11428.6 11519.6 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | [#3] mootools.html Cycles:180 Average:5585.53 Median:5073.45 stddev:3130.84 (61.7%) stddev-sans-first:3124.29 04:33:35 INFO - PROCESS | 608 | Values: 1462.1 1484.2 1580.1 1720.6 1730.4 7794.6 8077.2 8152.3 8784.0 8785.6 8216.8 8481.8 8484.4 8753.4 8761.6 2303.2 2322.9 2330.8 2336.7 2336.9 2067.9 2154.0 2263.5 2296.0 2315.6 2160.6 2174.1 2214.0 2293.1 2303.5 2203.2 2229.9 2234.7 2234.9 2240.0 2174.5 2229.0 2229.8 2230.1 2233.5 7926.4 7940.3 7942.4 7951.0 7954.7 5013.4 5168.6 5236.2 5239.1 5259.0 3220.5 3317.6 3383.1 3387.6 3412.7 1031.9 1096.1 1120.3 1121.3 1134.2 11123.2 11482.5 11568.5 11909.9 12054.9 4332.1 4463.2 4467.3 4605.5 4639.3 9376.0 9464.5 9470.3 9483.1 9487.7 5067.8 5071.1 5075.8 5077.6 5077.6 4615.6 4661.0 4712.1 4723.3 4734.1 3579.3 3581.7 3658.0 3692.8 3697.8 1113.7 1132.8 1145.5 1150.4 1156.8 941.5 963.4 976.6 978.3 992.5 5543.4 5668.3 5727.8 5751.2 5756.8 459.2 471.9 476.9 483.0 484.8 4000.7 4070.6 4163.6 4166.6 4221.5 9909.6 10083.8 10155.8 10183.6 10195.7 10122.8 10129.7 10138.2 10164.5 10165.9 7668.7 7732.8 7735.8 7741.4 7748.2 5874.4 6001.1 6046.6 6148.0 6309.5 9495.3 9557.9 9588.4 9713.1 9862.7 9771.1 9996.9 10049.9 10064.7 10093.5 7337.0 7535.8 7667.0 7678.7 7721.3 8464.2 8466.0 8529.3 8640.3 8653.4 7369.9 7579.8 7601.5 7773.3 7779.9 7349.4 7406.8 7407.1 7448.9 7690.5 6942.9 7060.3 7061.5 7066.7 7075.0 5052.1 5129.5 5206.9 5304.7 5418.1 5171.5 5226.4 5279.1 5307.7 5353.5 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | [#4] prototype.html Cycles:180 Average:4594.22 Median:5277.42 stddev:1336.08 (25.3%) stddev-sans-first:1300.89 04:33:35 INFO - PROCESS | 608 | Values: 328.3 333.7 335.2 340.0 341.6 5157.7 5274.4 5280.4 5296.4 5323.1 5212.9 5230.5 5234.4 5247.7 5255.2 3969.7 4047.6 4081.9 4109.4 4127.4 4131.6 4143.8 4149.9 4159.9 4176.7 4101.9 4102.9 4106.5 4111.9 4114.5 3801.1 3876.4 4076.8 4089.2 4134.9 3621.0 3630.2 3631.5 3635.1 3639.1 5036.5 5068.7 5073.1 5074.6 5102.5 4508.3 4649.3 4682.8 4687.5 4692.2 5389.8 5393.8 5395.0 5398.2 5430.2 3989.4 3994.5 3995.6 3999.6 4003.5 5505.4 5552.6 5597.6 5639.2 5654.5 4048.4 4050.8 4055.1 4058.4 4079.2 4106.4 4111.7 4113.9 4117.0 4119.9 4498.6 4656.0 4666.0 4668.6 4673.1 5412.5 5434.6 5442.0 5455.6 5464.3 1414.8 1430.8 1433.0 1434.9 1437.5 860.7 900.3 906.5 917.5 919.5 4986.6 5104.5 5147.2 5272.4 5282.6 5574.5 5574.9 5582.4 5587.6 5595.7 5132.2 5248.2 5256.0 5274.9 5279.9 2848.9 2899.5 2920.8 2920.8 2935.4 5637.7 5640.9 5644.2 5647.5 5653.1 5496.9 5681.6 5686.5 5690.2 5704.4 5672.0 5690.3 5692.0 5707.7 5723.2 5346.7 5520.7 5530.1 5552.5 5579.2 5435.0 5479.6 5507.1 5511.3 5522.6 5670.0 5694.0 5713.5 5735.9 5747.6 3768.3 3962.6 4087.6 4090.0 4144.5 5576.7 5584.8 5593.0 5638.0 5638.1 5465.9 5475.4 5479.2 5485.7 5495.7 5343.0 5410.7 5431.8 5436.8 5495.8 5407.2 5432.9 5448.9 5455.9 5457.6 5270.3 5336.0 5361.7 5498.1 5508.5 5311.0 5374.2 5385.9 5394.6 5410.3 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - PROCESS | 608 | [#5] yui.html Cycles:180 Average:19584.52 Median:4721.19 stddev:27150.98 (575.1%) stddev-sans-first:27190.28 04:33:35 INFO - PROCESS | 608 | Values: 740.2 790.7 797.2 813.7 832.4 15364.6 16579.6 16641.0 16648.9 16708.7 14984.7 15094.9 15101.8 15110.7 15124.3 658.9 677.5 781.2 781.3 806.1 802.0 802.6 807.4 807.9 808.3 859.2 859.5 860.9 861.0 861.3 685.4 702.4 851.3 851.5 851.7 608.8 627.6 630.0 630.6 630.8 14796.8 14810.8 14824.3 14825.3 14826.8 13607.7 13903.3 14151.5 14226.8 14323.1 12940.0 12995.4 13012.6 13091.6 13171.3 4155.5 4202.8 4239.5 4273.5 4410.7 62249.7 66035.7 76339.6 76956.6 77366.7 11114.6 11144.4 11175.7 11341.0 11404.1 20524.7 20600.0 20607.2 20654.9 20725.6 8385.5 8426.4 8517.7 8660.6 8763.4 4497.2 4531.6 4646.7 4697.3 4821.9 3430.8 3549.6 3551.4 3553.9 3555.4 562.7 579.5 580.0 586.3 587.8 666.8 705.2 735.6 736.3 751.7 9508.7 9564.7 9785.6 9883.2 10066.2 614.6 622.9 717.1 726.8 758.0 4368.8 4713.1 4729.3 4752.9 4841.7 64550.7 73641.0 74717.3 75207.2 75214.2 98866.0 104953.5 107109.0 109599.0 111276.4 58928.7 60382.0 82501.0 85446.6 93437.0 8184.3 8323.0 8437.6 8519.2 8718.9 54852.6 55293.4 55341.4 55617.0 55714.0 67589.0 75032.6 83102.6 83987.6 84066.2 12268.8 12616.4 13172.1 13216.1 13303.5 12779.5 12865.3 12998.5 13141.8 13521.7 12741.9 12807.5 13166.6 13207.7 13245.3 12537.0 13150.6 13577.3 13604.8 13791.4 13255.3 13375.5 13582.7 14009.1 14324.1 10451.6 10765.7 10888.6 11001.3 11261.0 10513.8 11376.5 11509.2 11599.2 11773.6 04:33:35 INFO - PROCESS | 608 | -------- Summary: end -------- 04:33:35 INFO - PROCESS | 608 | 04:33:35 INFO - TEST-INFO | 608: exit 0 04:33:35 INFO - TEST-OK | dromaeo_css | took 1116248ms 04:33:35 INFO - TEST-START | kraken 04:33:35 INFO - operating with platform_type : linux_ 04:33:35 INFO - Initialising browser for kraken test... 04:33:35 INFO - TEST-INFO | started process 929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvJPUVR/profile http://localhost:51833/getInfo.html) 04:33:40 INFO - PROCESS | 929 | __metrics Screen width/height:1600/1200 04:33:40 INFO - PROCESS | 929 | colorDepth:24 04:33:40 INFO - PROCESS | 929 | Browser inner width/height: 1024/697 04:33:40 INFO - PROCESS | 929 | __metrics 04:33:40 INFO - PROCESS | 929 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:33:40 INFO - PROCESS | 929 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:33:41 INFO - PROCESS | 929 | console.error: PushService: 04:33:41 INFO - PROCESS | 929 | stateChangeProcessEnqueue: Error transitioning state 04:33:41 INFO - PROCESS | 929 | UnknownError 04:33:41 INFO - TEST-INFO | 929: exit 0 04:33:41 INFO - Browser initialized. 04:33:41 INFO - Running cycle 1/1 for kraken test... 04:33:41 INFO - TEST-INFO | started process 1010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvJPUVR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:33:54 INFO - PROCESS | 1010 | RSS: Main: 173109248 04:33:54 INFO - PROCESS | 1010 | 04:33:56 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51833/tests/kraken/driver.html?testName=audio-beat-detection) 04:33:57 INFO - PROCESS | 1010 | RSS: Main: 241115136 04:33:57 INFO - PROCESS | 1010 | 04:34:00 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51833/tests/kraken/driver.html?testName=audio-dft) 04:34:00 INFO - PROCESS | 1010 | RSS: Main: 217018368 04:34:00 INFO - PROCESS | 1010 | 04:34:03 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51833/tests/kraken/driver.html?testName=audio-fft) 04:34:04 INFO - PROCESS | 1010 | RSS: Main: 297979904 04:34:04 INFO - PROCESS | 1010 | 04:34:06 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51833/tests/kraken/driver.html?testName=audio-oscillator) 04:34:07 INFO - PROCESS | 1010 | RSS: Main: 208543744 04:34:07 INFO - PROCESS | 1010 | 04:34:09 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51833/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:34:09 INFO - PROCESS | 1010 | RSS: Main: 212992000 04:34:09 INFO - PROCESS | 1010 | 04:34:12 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51833/tests/kraken/driver.html?testName=imaging-darkroom) 04:34:13 INFO - PROCESS | 1010 | RSS: Main: 248897536 04:34:13 INFO - PROCESS | 1010 | 04:34:16 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51833/tests/kraken/driver.html?testName=imaging-desaturate) 04:34:17 INFO - PROCESS | 1010 | RSS: Main: 248381440 04:34:17 INFO - PROCESS | 1010 | 04:34:20 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51833/tests/kraken/driver.html?testName=json-parse-financial) 04:34:20 INFO - PROCESS | 1010 | RSS: Main: 247955456 04:34:20 INFO - PROCESS | 1010 | 04:34:22 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51833/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:34:23 INFO - PROCESS | 1010 | RSS: Main: 210198528 04:34:23 INFO - PROCESS | 1010 | 04:34:24 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:34:25 INFO - PROCESS | 1010 | RSS: Main: 217456640 04:34:25 INFO - PROCESS | 1010 | 04:34:27 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:34:28 INFO - PROCESS | 1010 | RSS: Main: 222257152 04:34:28 INFO - PROCESS | 1010 | 04:34:30 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:34:31 INFO - PROCESS | 1010 | RSS: Main: 222711808 04:34:31 INFO - PROCESS | 1010 | 04:34:34 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:34:34 INFO - PROCESS | 1010 | RSS: Main: 221577216 04:34:34 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | Cycle 1(1): loaded http://localhost:51833/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51833/tests/kraken/driver.html?testName=ai-astar) 04:34:36 INFO - PROCESS | 1010 | RSS: Main: 272003072 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | __start_tp_report 04:34:36 INFO - PROCESS | 1010 | _x_x_mozilla_page_load 04:34:36 INFO - PROCESS | 1010 | _x_x_mozilla_page_load_details 04:34:36 INFO - PROCESS | 1010 | |i|pagename|runs| 04:34:36 INFO - PROCESS | 1010 | |0;ai-astar;109;114;113;111;112;111;112;112;113;113 04:34:36 INFO - PROCESS | 1010 | |1;audio-beat-detection;143;116;118;168;118;118;116;120;152;118 04:34:36 INFO - PROCESS | 1010 | |2;audio-dft;160;162;162;161;163;161;161;161;163;164 04:34:36 INFO - PROCESS | 1010 | |3;audio-fft;84;74;74;111;75;76;75;75;98;76 04:34:36 INFO - PROCESS | 1010 | |4;audio-oscillator;82;81;123;82;82;82;107;83;81;112 04:34:36 INFO - PROCESS | 1010 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;117 04:34:36 INFO - PROCESS | 1010 | |6;imaging-darkroom;146;147;146;146;145;147;146;145;145;145 04:34:36 INFO - PROCESS | 1010 | |7;imaging-desaturate;95;95;94;96;95;95;95;95;94;95 04:34:36 INFO - PROCESS | 1010 | |8;json-parse-financial;53;61;51;51;53;52;52;51;52;52 04:34:36 INFO - PROCESS | 1010 | |9;json-stringify-tinderbox;61;62;61;62;62;62;62;61;61;62 04:34:36 INFO - PROCESS | 1010 | |10;stanford-crypto-aes;88;81;83;78;79;78;78;81;78;83 04:34:36 INFO - PROCESS | 1010 | |11;stanford-crypto-ccm;125;135;124;122;135;125;135;130;177;126 04:34:36 INFO - PROCESS | 1010 | |12;stanford-crypto-pbkdf2;162;162;165;166;160;166;162;166;162;164 04:34:36 INFO - PROCESS | 1010 | |13;stanford-crypto-sha256-iterative;65;65;65;66;68;64;65;66;64;63 04:34:36 INFO - PROCESS | 1010 | __end_tp_report 04:34:36 INFO - PROCESS | 1010 | __start_cc_report 04:34:36 INFO - PROCESS | 1010 | _x_x_mozilla_cycle_collect,1609 04:34:36 INFO - PROCESS | 1010 | __end_cc_report 04:34:36 INFO - PROCESS | 1010 | __startTimestamp1474198476572__endTimestamp 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | ------- Summary: start ------- 04:34:36 INFO - PROCESS | 1010 | Number of tests: 14 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.41 (1.3%) stddev-sans-first:1.00 04:34:36 INFO - PROCESS | 1010 | Values: 109.0 114.0 113.0 111.0 112.0 111.0 112.0 112.0 113.0 113.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:119.00 stddev:18.70 (15.7%) stddev-sans-first:19.11 04:34:36 INFO - PROCESS | 1010 | Values: 143.0 116.0 118.0 168.0 118.0 118.0 116.0 120.0 152.0 118.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.23 (0.8%) stddev-sans-first:1.12 04:34:36 INFO - PROCESS | 1010 | Values: 160.0 162.0 162.0 161.0 163.0 161.0 161.0 161.0 163.0 164.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.50 stddev:12.68 (16.8%) stddev-sans-first:13.43 04:34:36 INFO - PROCESS | 1010 | Values: 84.0 74.0 74.0 111.0 75.0 76.0 75.0 75.0 98.0 76.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:82.00 stddev:16.01 (19.5%) stddev-sans-first:16.61 04:34:36 INFO - PROCESS | 1010 | Values: 82.0 81.0 123.0 82.0 82.0 82.0 107.0 83.0 81.0 112.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:34:36 INFO - PROCESS | 1010 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 04:34:36 INFO - PROCESS | 1010 | Values: 146.0 147.0 146.0 146.0 145.0 147.0 146.0 145.0 145.0 145.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:34:36 INFO - PROCESS | 1010 | Values: 95.0 95.0 94.0 96.0 95.0 95.0 95.0 95.0 94.0 95.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:52.00 stddev:2.97 (5.7%) stddev-sans-first:3.15 04:34:36 INFO - PROCESS | 1010 | Values: 53.0 61.0 51.0 51.0 53.0 52.0 52.0 51.0 52.0 52.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 04:34:36 INFO - PROCESS | 1010 | Values: 61.0 62.0 61.0 62.0 62.0 62.0 62.0 61.0 61.0 62.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:3.27 (4.0%) stddev-sans-first:2.15 04:34:36 INFO - PROCESS | 1010 | Values: 88.0 81.0 83.0 78.0 79.0 78.0 78.0 81.0 78.0 83.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#11] stanford-crypto-ccm Cycles:10 Average:133.40 Median:132.50 stddev:16.11 (12.2%) stddev-sans-first:16.79 04:34:36 INFO - PROCESS | 1010 | Values: 125.0 135.0 124.0 122.0 135.0 125.0 135.0 130.0 177.0 126.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:164.50 stddev:2.17 (1.3%) stddev-sans-first:2.24 04:34:36 INFO - PROCESS | 1010 | Values: 162.0 162.0 165.0 166.0 160.0 166.0 162.0 166.0 162.0 164.0 04:34:36 INFO - PROCESS | 1010 | 04:34:36 INFO - PROCESS | 1010 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.37 (2.1%) stddev-sans-first:1.45 04:34:36 INFO - PROCESS | 1010 | Values: 65.0 65.0 65.0 66.0 68.0 64.0 65.0 66.0 64.0 63.0 04:34:36 INFO - PROCESS | 1010 | -------- Summary: end -------- 04:34:36 INFO - PROCESS | 1010 | 04:34:37 INFO - TEST-INFO | 1010: exit 0 04:34:37 INFO - TEST-OK | kraken | took 61745ms 04:34:37 INFO - SUITE-END | took 1177s 04:34:37 INFO - Completed test suite (00:19:38) 04:34:37 INFO - javascript benchmark 04:34:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.0816201058674, 3033.2265272355544, 3043.71084746949, 3071.0940272619605, 3082.1370016395417, 5156.071907056728, 5167.199084141959, 5238.292830467885, 5252.737363131842, 5289.788408463657, 5181.5854731621475, 5183.481651834824, 5260.473952604728, 5260.92108618371, 5266.104762190427, 4711.90576188476, 4716.174669432836, 4764.047190561884, 4774.880627984294, 4849.054434385296, 4842.443119041302, 4875.366202393684, 4892.119418504668, 4897.608191344684, 4924.4583095859425, 4737.881552961169, 4742.596879265267, 4784.449788274358, 4815.855524334275, 4820.710757354553, 4810.6873053251775, 4816.084943860656, 4869.975650121763, 4871.2206047032305, 4893.7063776173545, 4753.762311884427, 4788.401449818773, 4789.4971028042255, 4807.783649735736, 4857.82997595086, 5061.493850614946, 5108.591312694975, 5226.0, 5229.346331708536, 5283.788648454057, 5577.832665020056, 5621.522170615503, 5642.576806739511, 5644.322681278273, 5707.885842283173, 5667.093265077609, 5673.26247587815, 5681.715914204273, 5684.431556844283, 5684.744186511575, 5126.871828204325, 5289.735513224362, 5292.179712144623, 5296.152615581527, 5351.03681337363, 5637.013522633522, 5637.577181711388, 5687.829365119053, 5694.658320500783, 5761.222234998221, 5402.864928376822, 5459.426760190202, 5468.507834294933, 5469.097598896217, 5469.726513674301, 5516.113592852639, 5560.527355174855, 5564.666120032811, 5566.387697353288, 5635.042042852725, 5442.972785136129, 5498.945010549924, 5514.09017512114, 5555.444455554412, 5571.303587051619, 5628.831135065954, 5635.436456354414, 5692.14617807324, 5700.857478563069, 5702.944955183304, 4368.847090351886, 4369.322754972921, 4370.104128653618, 4416.0, 4427.911441771146, 3845.942310865339, 3851.4030325299623, 3888.8055597220027, 3905.3556163232747, 3925.0579860833195, 5297.311349524537, 5310.283111779938, 5351.705656188947, 5369.033573956647, 5369.677819330853, 5579.721013949286, 5627.606067575231, 5632.774689012475, 5634.267545219177, 5634.32388113429, 5144.382674079135, 5259.421463638997, 5312.893742125136, 5319.547838433777, 5378.005069062236, 4804.95195048045, 4814.988852340977, 4825.30033145199, 4842.031593681278, 4900.387451568554, 5751.712414379264, 5757.309122905278, 5760.193572899798, 5760.711964401847, 5761.366249712612, 5414.431484694051, 5621.831345059655, 5632.380438151882, 5656.717164141859, 5696.0, 5600.159976003632, 5719.799807006735, 5786.0, 5788.450097240755, 5876.030454974882, 5509.862253443696, 5550.4449555044175, 5555.055640541037, 5557.41647127062, 5561.777528898799, 5639.971800140973, 5654.038813401814, 5657.2928383952, 5657.3776884543495, 5674.971625141848, 5216.37403511581, 5322.33470816148, 5446.673199607878, 5495.477929596682, 5531.280933478622, 5530.281063461724, 5538.169274608841, 5539.058360078877, 5554.805581804618, 5586.636868603528, 5497.807576734955, 5515.448455154453, 5517.806876759294, 5526.032944234823, 5532.446755324436, 5385.676859388449, 5431.592630552804, 5497.422770609189, 5531.0, 5557.249771280825, 5443.156310771837, 5445.0, 5454.536364409069, 5456.42707515721, 5505.779768809202], "value": 5184.328009953108, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8592851787054, 596.5794115148818, 602.4908951935614, 611.7858749437695, 617.4844005255612, 33026.33947321052, 43470.56529434705, 43762.0, 43965.12069758607, 44026.91811573183, 44603.77698111505, 45517.31724024134, 45540.31689524651, 45587.316190257174, 45598.31602525965, 8926.375153739225, 9077.818443631124, 9083.364164508486, 9088.227500662451, 9088.409253398508, 8914.0, 9005.234555062794, 9005.594748236344, 9008.0, 9008.819823603524, 9004.594793234288, 9022.54887255638, 9040.819183616324, 9047.638094476213, 9052.275817934582, 8988.0, 9022.909770902272, 9024.954875225647, 9027.14242146995, 9039.999999999935, 8896.154865287788, 8949.776255593597, 8963.820723585557, 8982.191602755784, 8985.101489851028, 42476.36285455721, 42617.57382426198, 42845.35731964023, 42888.925552072506, 42909.35635965463, 3333.150046738066, 3360.462326027824, 3441.9633776684286, 3587.443946188345, 3636.345457817592, 32458.837705811555, 33094.17264568381, 33166.0, 34391.484127738106, 34506.30987380263, 4246.214450326699, 4335.219660461115, 4404.889877753082, 4408.590947653563, 4414.448193975753, 354487.0, 355476.22261888784, 355928.0, 356279.0, 358366.0, 61604.91543472558, 61953.0, 62031.379686203465, 62051.689741551905, 62098.37901620926, 60392.0, 63330.36669633337, 64658.67670661616, 64846.0, 64886.0, 16213.432529861399, 17126.657466850844, 17171.141442928052, 17678.46964591064, 17689.46931592054, 13311.534096306583, 13398.732025359439, 13405.0, 13407.262600557064, 13408.664783380493, 15107.924460377848, 15565.688686226213, 17178.74231886523, 17224.397146099825, 17301.221445035004, 5722.399148089391, 6015.608985416022, 6161.476274516716, 6174.8147555573405, 6293.937060629427, 903.5527413930147, 986.329296078659, 1023.2376879225025, 1026.409814356733, 1043.1029314789275, 32795.72042795749, 32808.83595582007, 32841.3431731364, 32875.83562082222, 33630.0, 1039.0804138337473, 1084.766775143332, 1113.8496302998992, 1123.7523556920505, 1138.3682056458579, 16682.666346673243, 17131.657366852844, 17233.22450489731, 17313.30746770115, 17378.119781782647, 539216.0, 539672.0, 540081.0, 541993.0, 542350.0, 57894.710526447096, 58050.12924806131, 58235.12647310294, 58368.708156458946, 58369.41630583725, 1286.3182513267982, 1348.5482363408369, 1356.348952502824, 1358.7078778062764, 1361.9387127579282, 30757.0, 31749.523757144125, 32404.83797580997, 32712.34575308528, 33196.50205246875, 52982.73508632432, 52984.20523692225, 53314.46685533095, 53392.73303633457, 53413.465865342405, 462528.0, 465571.0, 465871.0, 467175.0, 468899.0, 33691.83154084214, 35177.07520947121, 36543.63456365402, 36553.81723091367, 42125.0, 37613.9609869229, 49191.508084920126, 49521.0, 49798.75100624474, 50926.0, 38407.42388864225, 40785.79607101945, 42030.0, 42788.358174628025, 43164.0, 40545.594544054184, 40725.796371017954, 41085.38371924364, 41532.140148792714, 41972.580274196866, 40140.197196055335, 41167.588324116376, 41169.58830411657, 41265.0, 41588.0, 33517.0, 36144.638553614124, 36319.0, 37104.81447592745, 37560.2487950234, 27476.313092172855, 28003.579946300415, 28113.43773124567, 29334.26664333409, 29400.833960215325], "value": 20331.20563116003, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.5125668542665, 395.9148783011652, 399.70222184472567, 403.1111399364401, 425.6658523059397, 13897.235652039159, 16899.746503802424, 17920.641587168247, 19409.22363105476, 19435.708464373045, 14608.707825843478, 14992.625184370369, 17777.555561110978, 18307.35924242652, 18472.815271847277, 5155.81954631588, 5629.746661400247, 5653.434656534355, 5660.858478538028, 5663.235463212455, 5420.83737487874, 5534.612577119603, 5547.195656629786, 5613.635113717595, 5633.014222511065, 5529.364123125832, 5560.666360018392, 5562.332520097574, 5564.33228012639, 5571.693556854371, 5369.62412631116, 5504.862378440531, 5526.889462210774, 5542.224088627595, 5549.639273447213, 4897.118518666639, 4935.062338155739, 4936.679115857458, 4937.259411088329, 4946.752662366904, 21846.0, 21851.562968740694, 22263.22078727253, 22454.438639033993, 22543.323700289016, 2311.8959646815933, 2351.1418332308754, 2359.7168339799164, 2965.4265912530927, 3052.4810782166865, 13619.727605447835, 14137.505187318395, 14472.131672099602, 14971.775423368657, 15024.924875375662, 1859.7861245956753, 1911.923523059076, 1927.5855691026497, 1945.9221631134737, 1977.9703304450445, 82644.58677706574, 83752.99999999939, 85397.57301213454, 86838.5658071712, 88302.55848720779, 10129.949350253202, 10197.796044079225, 10239.795204095877, 10425.0, 10592.894071059345, 15024.323905424282, 15064.623384415478, 15066.472673456376, 15070.397184112513, 15071.849281507264, 7340.889886651705, 7757.883631745528, 7797.883031754528, 8027.357811375076, 8526.701565445179, 7931.801704957307, 8040.0, 8225.095239523647, 8539.786505337417, 8548.871766923501, 6417.454516366068, 6459.644719540471, 6516.315786842408, 6551.836204094936, 6563.113979612786, 1868.2900497810745, 1927.5373910261717, 1938.1472152252963, 1941.1458958058408, 1963.0577322885094, 898.9146031127033, 933.7385532051037, 973.9707808765748, 1016.9796604067877, 1034.9896501034893, 17773.377931772327, 18133.637327253644, 18332.63334733298, 19965.0, 20039.0, 966.2415004221652, 984.2519685039422, 997.9850302245472, 1018.974525636865, 1019.8368261078192, 9896.653617123366, 10396.844047339297, 10826.566937322576, 10879.401632910107, 11053.0, 174645.12677436785, 386770.0, 399345.0, 521218.0, 536037.0, 88967.55516222506, 94611.0, 99859.0, 104958.0, 108480.0, 495.7372592525967, 505.26736232463077, 505.7496539213113, 505.9569936555434, 511.2484647568069, 10023.54894029785, 10077.395356278646, 10698.572057117628, 10729.463526823783, 10857.511411986638, 41114.0, 41898.0, 42718.145637087706, 42870.35694464523, 43664.56335436732, 41071.0, 48607.0, 53327.733361334496, 59279.40720592739, 60973.69513152406, 8278.503289802631, 8585.65657373698, 8624.353173511887, 8779.736607901517, 8912.598933047899, 10481.842772358574, 12617.306048167438, 12997.480100795863, 13164.14433061848, 13439.395227214603, 10872.836907446554, 11294.43527823622, 11492.425378731197, 11497.885021150016, 11721.0, 11399.430028498708, 11572.942135289606, 11583.884161158281, 11699.122565807429, 11728.354940478355, 10964.06805421548, 11019.724506887393, 11082.778344433227, 11396.658100257006, 11739.823902641296, 8207.548584827891, 8410.411271211202, 8562.48625082472, 8669.956650216709, 9037.186653201243, 8852.557372131496, 11187.72030699239, 11272.436378181223, 11428.628564572304, 11519.596814111466], "value": 8869.256304023604, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.1154201707964, 1484.2430360516134, 1580.1309279896054, 1720.5784582777221, 1730.4376077774727, 7794.571298578576, 8077.151899050603, 8152.3478121750195, 8783.956080219597, 8785.60464779085, 8216.7945801355, 8481.830363392728, 8484.363672724556, 8753.431026983242, 8761.56192190391, 2303.170858490946, 2322.883855807211, 2330.8135349172026, 2336.6845475860796, 2336.894839732208, 2067.9276225332114, 2153.967690484644, 2263.4680850000236, 2295.9540809183804, 2315.5716192504356, 2160.6326924422806, 2174.0542863854275, 2213.9889300553473, 2293.0827668932475, 2303.5047464795066, 2203.2398822406244, 2229.899654515543, 2234.6759719840693, 2234.8770817605105, 2239.9664005039936, 2174.5324755177553, 2229.030371788277, 2229.8216142708543, 2230.119102954324, 2233.530958498717, 7926.405519585996, 7940.325072368798, 7942.444028917978, 7951.04587449504, 7954.681812727484, 5013.398392192924, 5168.638195326329, 5236.188390799432, 5239.056969745444, 5258.973705131488, 3220.484722444421, 3317.61603164773, 3383.139273399645, 3387.6442973487674, 3412.709919656832, 1031.9071283584512, 1096.117625311624, 1120.3389999900098, 1121.315997241682, 1134.2060557609707, 11123.221374503788, 11482.48328825205, 11568.53725850965, 11909.94045029778, 12054.939725301405, 4332.090261045153, 4463.152001119777, 4467.329900514884, 4605.470370907322, 4639.280911458729, 9375.953120234355, 9464.52677366129, 9470.289728270407, 9483.146516813382, 9487.715368538955, 5067.822626208065, 5071.061853557104, 5075.847724568269, 5077.644564880497, 5077.644564880497, 4615.630749540029, 4661.0, 4712.057588482317, 4723.338732577442, 4734.147853386355, 3579.266250418658, 3581.6955558777795, 3658.0, 3692.8153592320814, 3697.8335974881193, 1113.7271368514766, 1132.841402203692, 1145.541783286692, 1150.3615493401242, 1156.8206927926185, 941.463365881441, 963.3545524498603, 976.5898322704481, 978.2760757039739, 992.503748125937, 5543.390227075019, 5668.319801623831, 5727.828165155052, 5751.166080918327, 5756.827295181151, 459.23078842937946, 471.89340995365814, 476.89508308172145, 482.96886148074117, 484.7673116903906, 4000.6599439047427, 4070.61329173728, 4163.5836416358725, 4166.6250037496775, 4221.493420789464, 9909.603615855429, 10083.798324033478, 10155.847662285072, 10183.643572474928, 10195.7451063724, 10122.797544049077, 10129.696109116738, 10138.188944884392, 10164.4917754112, 10165.949170254102, 7668.65491052904, 7732.806679832936, 7735.845283094307, 7741.380689544935, 7748.186440423787, 5874.412558744262, 6001.129836173817, 6046.606970546901, 6148.0, 6309.4952403806665, 9495.335326527076, 9557.90442095598, 9588.424694518351, 9713.07725766051, 9862.654807081717, 9771.120599145826, 9996.850047249443, 10049.949750251202, 10064.69805905824, 10093.495325233562, 7337.0, 7535.8116047099265, 7667.0, 7678.731244406419, 7721.266479684421, 8464.153584641734, 8466.0, 8529.317654587494, 8640.308775298086, 8653.350998674998, 7369.852602948018, 7579.810504737426, 7601.505902116345, 7773.3004029637605, 7779.92220077792, 7349.390966207134, 7406.777796666109, 7407.074115735533, 7448.851022979402, 7690.538567685733, 6942.930570694229, 7060.3292687194635, 7061.540999834994, 7066.681999309941, 7075.044868942627, 5052.079713238796, 5129.487051294841, 5206.921896171636, 5304.681719096867, 5418.051840927901, 5171.482851714949, 5226.435880385597, 5279.128943724396, 5307.681539107666, 5353.491418315254], "value": 4420.133136289742, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.2696001396892, 333.7180082830009, 335.16878142207327, 340.01226437199927, 341.648102454472, 5157.716325602095, 5274.4461831507715, 5280.3663560372715, 5296.364436267649, 5323.121684921988, 5212.895742085156, 5230.476952304768, 5234.371875374951, 5247.737613119348, 5255.185446255827, 3969.6625786808154, 4047.5750046245016, 4081.9183616327655, 4109.445224894645, 4127.4427952226515, 4131.607497287753, 4143.798298493449, 4149.917001659965, 4159.896002599929, 4176.665866730655, 4101.9179616407655, 4102.8974275643195, 4106.507219133693, 4111.876643700693, 4114.506259248894, 3801.0877389426614, 3876.3604005339225, 4076.755394676299, 4089.1821635672986, 4134.875953721378, 3621.0, 3630.2013557017412, 3631.527901372831, 3635.1457407509215, 3639.1266096137, 5036.521530454601, 5068.72122033288, 5073.137566613685, 5074.594032477393, 5102.464241254689, 4508.278675411918, 4649.256119020941, 4682.789274482657, 4687.484376718558, 4692.202325604655, 5389.846939540374, 5393.761588940585, 5394.973025134888, 5398.1902714593125, 5430.185472179165, 3989.4414781930554, 3994.500687414073, 3995.6403923647013, 3999.580044095357, 4003.5195776506716, 5505.449455054502, 5552.584091056836, 5597.608167428241, 5639.210510528529, 5654.491095801398, 4048.352263637804, 4050.7772072536295, 4055.1281474483126, 4058.411530328086, 4079.1841631673783, 4106.3635136553885, 4111.656355877121, 4113.8971525712095, 4116.958830411658, 4119.938200926988, 4498.595126438636, 4655.95344046562, 4666.013751199235, 4668.626509879202, 4673.135469938073, 5412.510245287212, 5434.565234781208, 5442.040865288572, 5455.608819750938, 5464.26232458621, 1414.8372937112158, 1430.8354539227914, 1432.9825823665105, 1434.9238071446416, 1437.5040610989192, 860.6772460327377, 900.3067637918897, 906.4968942237122, 917.5458148216676, 919.5080631861922, 4986.576141027981, 5104.464031276737, 5147.176451767774, 5272.444628834503, 5282.603804714662, 5574.526165275997, 5574.86062848432, 5582.358028826656, 5587.552995760329, 5595.720213989284, 5132.230165475134, 5248.239005344204, 5255.973720131375, 5274.92087618686, 5279.9208011879855, 2848.928776780597, 2899.4635992341487, 2920.8101473404163, 2920.8247505149757, 2935.4422659695015, 5637.68992705397, 5640.858978525488, 5644.209810626516, 5647.463490968352, 5653.077953495831, 5496.890062198733, 5681.602287839894, 5686.459786320293, 5690.2033715279895, 5704.401037890962, 5671.971640141773, 5690.345610254791, 5691.971540142273, 5707.74315155819, 5723.198752174699, 5346.652467589594, 5520.723963801875, 5530.087535556588, 5552.52803511706, 5579.246801681721, 5434.972825135849, 5479.561635069264, 5507.091329930517, 5511.255980442589, 5522.5581953442825, 5669.971650141723, 5694.014190066887, 5713.485786279255, 5735.942640573541, 5747.597668163188, 3768.3028639701743, 3962.5839286875616, 4087.610212527755, 4089.9795501023495, 4144.48193975753, 5576.665400076008, 5584.832455026356, 5593.0, 5637.9718101409235, 5638.126090455986, 5465.945340546544, 5475.370332411823, 5479.205515200353, 5485.670859748428, 5495.708508500579, 5343.0, 5410.729463526729, 5431.755570999236, 5436.7553460093595, 5495.752691128829, 5407.188921661782, 5432.9185062223305, 5448.918266225931, 5455.863603409788, 5457.645253058538, 5270.262163297218, 5336.0, 5361.731913404236, 5498.068880750283, 5508.540236837322, 5310.973445132904, 5374.247605335178, 5385.946140538545, 5394.626364399652, 5410.269613602113], "value": 4168.701879461708, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.1820987808475, 790.7390561114829, 797.1988151907334, 813.6501304439092, 832.4464231286191, 15364.615884602876, 16579.585510362245, 16641.0, 16648.91675541622, 16708.665826683475, 14984.700305993874, 15094.924525377386, 15101.773473397909, 15110.697786044273, 15124.319405626746, 658.9242237142718, 677.4884961853818, 781.1875649324695, 781.3046388714035, 806.0931452116369, 802.0, 802.5666140284269, 807.4186585658316, 807.9030516338048, 808.292743849132, 859.230988265502, 859.5444414460346, 860.9053004169567, 860.9784755381103, 861.3023451004706, 685.3626127701236, 702.3783951203171, 851.3487182305541, 851.4891065360796, 851.7104184577242, 608.8477880529867, 627.5889292513411, 629.9905501417483, 630.59956927351, 630.7540059376847, 14796.778048329285, 14810.777838332433, 14824.258787060604, 14825.258737063104, 14826.777598336033, 13607.659808504768, 13903.30483475822, 14151.504697335544, 14226.786598200932, 14323.069000514934, 12940.0, 12995.350232488337, 13012.60962171127, 13091.607251782461, 13171.341432928411, 4155.501339839208, 4202.768847713374, 4239.47006624172, 4273.487181538236, 4410.713303635285, 62249.68875155641, 66035.66982165107, 76339.61830190869, 76956.61521692356, 77366.74482903437, 11114.610988615359, 11144.387058711687, 11175.665761139033, 11341.036011939077, 11404.118320477195, 20524.692129618066, 20600.0, 20607.175712971613, 20654.896725516275, 20725.58548829015, 8385.454945428528, 8426.368022398223, 8517.701880434155, 8660.610272537751, 8763.386562940661, 4497.168023915586, 4531.627604619001, 4646.697964632288, 4697.295405689106, 4821.855344339676, 3430.7598468106994, 3549.5917969433845, 3551.4140166872694, 3553.893383198508, 3555.4489054196642, 562.7242651100931, 579.4871538688291, 580.0371383503364, 586.3169407640137, 587.8471597384626, 666.7632990288378, 705.220731092146, 735.6248313360118, 736.3262614707482, 751.6730222353294, 9508.667196648084, 9564.66523671668, 9785.608575657036, 9883.209343252522, 10066.245031622657, 614.6373639552685, 622.862970146567, 717.125107369009, 726.7565365602477, 757.9583122928182, 4368.7815609219415, 4713.106622753027, 4729.266963620645, 4752.928706069411, 4841.709497430165, 64550.677246613464, 73641.0, 74717.25282747211, 75207.24792752003, 75214.24785752181, 98866.0, 104953.47523262334, 107109.0, 109599.0, 111276.44361778139, 58928.7053564738, 60382.0, 82501.0, 85446.57276713701, 93436.99999999865, 8184.263416292562, 8323.0, 8437.620307086074, 8519.233269005572, 8718.912810872063, 54852.62868428321, 55293.44706552883, 55341.44658553363, 55617.0, 55714.0, 67589.0, 75032.62483687546, 83102.58448707718, 83987.5800620993, 84066.15933840828, 12268.815967760669, 12616.432260548114, 13172.143810652276, 13216.140950838164, 13303.534376296782, 12779.488820447077, 12865.2924089176, 12998.545050923172, 13141.802872956721, 13521.661958451117, 12741.936290318488, 12807.48770049225, 13166.605001849959, 13207.735845283232, 13245.27151006704, 12537.0, 13150.562467406615, 13577.272051167869, 13604.795928061285, 13791.379387927767, 13255.337233138112, 13375.531856385369, 13582.728345432839, 14009.08940918837, 14324.14055156694, 10451.592963146002, 10765.730856728644, 10888.61889833852, 11001.339919604849, 11261.0, 10513.793293045694, 11376.54493820238, 11509.194356394972, 11599.246049006788, 11773.587924422603], "value": 7393.700396636378, "unit": "score"}], "name": "dromaeo_css", "value": 7093.296676176527, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 114.0, 113.0, 111.0, 112.0, 111.0, 112.0, 112.0, 113.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 118.0, 168.0, 118.0, 118.0, 116.0, 120.0, 152.0, 118.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 161.0, 163.0, 161.0, 161.0, 161.0, 163.0, 164.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 74.0, 74.0, 111.0, 75.0, 76.0, 75.0, 75.0, 98.0, 76.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 123.0, 82.0, 82.0, 82.0, 107.0, 83.0, 81.0, 112.0], "value": 91.5, "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, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 61.0, 51.0, 51.0, 53.0, 52.0, 52.0, 51.0, 52.0, 52.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 61.0, 62.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 83.0, 78.0, 79.0, 78.0, 78.0, 81.0, 78.0, 83.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 135.0, 124.0, 122.0, 135.0, 125.0, 135.0, 130.0, 177.0, 126.0], "value": 133.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 165.0, 166.0, 160.0, 166.0, 162.0, 166.0, 162.0, 164.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 68.0, 64.0, 65.0, 66.0, 64.0, 63.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1490.3999999999999, "alertThreshold": 2.0}]} 04:34:37 INFO - Return code: 0 04:34:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:34:37 INFO - # TBPL SUCCESS # 04:34:37 INFO - Running post-action listener: _resource_record_post_action 04:34:37 INFO - [mozharness: 2016-09-18 11:34:37.895500Z] Finished run-tests step (success) 04:34:37 INFO - Running post-run listener: _resource_record_post_run 04:34:37 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 12288; Write bytes: 395071488; Read time: 288; Write time: 240592 04:34:37 INFO - TinderboxPrint: CPU usage
13.1% 04:34:37 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 288 04:34:37 INFO - TinderboxPrint: I/O write bytes / time
395,071,488 / 240,592 04:34:37 INFO - TinderboxPrint: CPU idle
8,286.6 (86.9%) 04:34:37 INFO - TinderboxPrint: CPU user
1,194.0 (12.5%) 04:34:37 INFO - TinderboxPrint: Swap in / out
0 / 0 04:34:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 103424000; Read time: 216; Write time: 48556 04:34:37 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291033088; Read time: 64; Write time: 192036 04:34:38 INFO - Running post-run listener: _upload_blobber_files 04:34:38 INFO - Blob upload gear active. 04:34:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:34:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:34:38 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:34:38 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:34:39 INFO - (blobuploader) - INFO - Open directory for files ... 04:34:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:34:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:34:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:39 INFO - (blobuploader) - INFO - Done attempting. 04:34:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:34:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:34:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:40 INFO - (blobuploader) - INFO - Done attempting. 04:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:34:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:43 INFO - (blobuploader) - INFO - Done attempting. 04:34:43 INFO - (blobuploader) - INFO - Iteration through files over. 04:34:43 INFO - Return code: 0 04:34:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:34:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:34:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65a3964dede33999e148c8c4572f6475e571134990dc73dbc95b474d808bac1a73e44818eb555f29f24d72a1cbdd73c2dcc48c96b6034d6af5a991bec1db5270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afc6fd78fa6209aa0822af8f7e567a043bfd2fe5e3d5e26cd0acf9ce321c4af3bfcbefb3333dc9b021315356d27176cb925b013386ae44dbbe534de31f736e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de17dd0c48b8be0297cd827f91052a3c32f397ae7df4886a72ad0b57fc80311c6115f2ef9397700ef124ccb6c3b342629c9e05eeb4b92bbbb9524305ca5b4211"} 04:34:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:34:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:34:43 INFO - Contents: 04:34:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65a3964dede33999e148c8c4572f6475e571134990dc73dbc95b474d808bac1a73e44818eb555f29f24d72a1cbdd73c2dcc48c96b6034d6af5a991bec1db5270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afc6fd78fa6209aa0822af8f7e567a043bfd2fe5e3d5e26cd0acf9ce321c4af3bfcbefb3333dc9b021315356d27176cb925b013386ae44dbbe534de31f736e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de17dd0c48b8be0297cd827f91052a3c32f397ae7df4886a72ad0b57fc80311c6115f2ef9397700ef124ccb6c3b342629c9e05eeb4b92bbbb9524305ca5b4211"} 04:34:43 INFO - Running post-run listener: copy_logs_to_upload_dir 04:34:43 INFO - Copying logs to upload dir... 04:34:43 INFO - mkdir: /builds/slave/test/build/upload/logs 04:34:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.936645 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-18 04:34:43.870793) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 04:34:43.876666) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65a3964dede33999e148c8c4572f6475e571134990dc73dbc95b474d808bac1a73e44818eb555f29f24d72a1cbdd73c2dcc48c96b6034d6af5a991bec1db5270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afc6fd78fa6209aa0822af8f7e567a043bfd2fe5e3d5e26cd0acf9ce321c4af3bfcbefb3333dc9b021315356d27176cb925b013386ae44dbbe534de31f736e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de17dd0c48b8be0297cd827f91052a3c32f397ae7df4886a72ad0b57fc80311c6115f2ef9397700ef124ccb6c3b342629c9e05eeb4b92bbbb9524305ca5b4211"} build_url:https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011558 build_url: 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/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/Firefox/sha512/65a3964dede33999e148c8c4572f6475e571134990dc73dbc95b474d808bac1a73e44818eb555f29f24d72a1cbdd73c2dcc48c96b6034d6af5a991bec1db5270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/afc6fd78fa6209aa0822af8f7e567a043bfd2fe5e3d5e26cd0acf9ce321c4af3bfcbefb3333dc9b021315356d27176cb925b013386ae44dbbe534de31f736e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de17dd0c48b8be0297cd827f91052a3c32f397ae7df4886a72ad0b57fc80311c6115f2ef9397700ef124ccb6c3b342629c9e05eeb4b92bbbb9524305ca5b4211"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 04:34:43.908100) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:34:43.908600) ========= 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=c6927cf182745ad25b1d38b9000001fe-1474197244.284867-1310980225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:34:43.993926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 04:34:43.994246) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 04:34:43.994532) ========= ========= Total master_lag: 0.20 =========