builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1474197095.47 results: success (0) buildid: 20160917163004 builduid: 82db576dbede4acda10282dbf203727c revision: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.475225) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.475764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.476118) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.491259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.491572) ========= 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=8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 04:11:35-- 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% 226M=0s 2016-09-18 04:11:35 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087027 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.593398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.593764) ========= 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=8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015339 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:35.643482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 04:11:35.643806) ========= 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=8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 04:11:35,728 truncating revision to first 12 chars 2016-09-18 04:11:35,729 Setting DEBUG logging. 2016-09-18 04:11:35,729 attempt 1/10 2016-09-18 04:11:35,729 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:11:36,618 unpacking tar archive at: mozilla-central-eaf5eb6f8fa0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.238088 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-18 04:11:36.895281) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:36.895616) ========= script_repo_revision: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:36.896029) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:36.896311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 04:11:36.905531) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-18 04:11:36.905805) ========= /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=8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:11:37 INFO - MultiFileLogger online at 20160918 04:11:37 in /builds/slave/test 04:11:37 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:11:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:11:37 INFO - {'append_to_log': False, 04:11:37 INFO - 'base_work_dir': '/builds/slave/test', 04:11:37 INFO - 'blob_upload_branch': 'Firefox', 04:11:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:11:37 INFO - 'branch': 'Firefox', 04:11:37 INFO - 'buildbot_json_path': 'buildprops.json', 04:11:37 INFO - 'config_files': ('talos/linux_config.py',), 04:11:37 INFO - 'default_actions': ('clobber', 04:11:37 INFO - 'read-buildbot-config', 04:11:37 INFO - 'download-and-extract', 04:11:37 INFO - 'populate-webroot', 04:11:37 INFO - 'create-virtualenv', 04:11:37 INFO - 'install', 04:11:37 INFO - 'run-tests'), 04:11:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:11:37 INFO - 'download_minidump_stackwalk': True, 04:11:37 INFO - 'download_symbols': 'ondemand', 04:11:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:11:37 INFO - 'tooltool.py': '/tools/tooltool.py', 04:11:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:11:37 INFO - '/tools/misc-python/virtualenv.py')}, 04:11:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:11:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:11:37 INFO - 'installer_path': 'installer.exe', 04:11:37 INFO - 'log_level': 'info', 04:11:37 INFO - 'log_name': 'talos', 04:11:37 INFO - 'log_to_console': True, 04:11:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:11:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:11:37 INFO - 'opt_config_files': (), 04:11:37 INFO - 'pip_index': False, 04:11:37 INFO - 'sps_profile': False, 04:11:37 INFO - 'sps_profile_interval': 0, 04:11:37 INFO - 'suite': 'dromaeojs', 04:11:37 INFO - 'system_bits': '32', 04:11:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:11:37 INFO - 'title': 'talos-linux64-ix-038', 04:11:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:11:37 INFO - 'use_talos_json': True, 04:11:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:11:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:11:37 INFO - 'work_dir': 'build'} 04:11:37 INFO - [mozharness: 2016-09-18 11:11:37.080409Z] Running clobber step. 04:11:37 INFO - Running pre-action listener: _resource_record_pre_action 04:11:37 INFO - Running main action method: clobber 04:11:37 INFO - rmtree: /builds/slave/test/build 04:11:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:11:37 INFO - Running post-action listener: _resource_record_post_action 04:11:37 INFO - [mozharness: 2016-09-18 11:11:37.539776Z] Finished clobber step (success) 04:11:37 INFO - [mozharness: 2016-09-18 11:11:37.539886Z] Running read-buildbot-config step. 04:11:37 INFO - Running pre-action listener: _resource_record_pre_action 04:11:37 INFO - Running main action method: read_buildbot_config 04:11:37 INFO - Using buildbot properties: 04:11:37 INFO - { 04:11:37 INFO - "project": "", 04:11:37 INFO - "product": "firefox", 04:11:37 INFO - "script_repo_revision": "production", 04:11:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:11:37 INFO - "repository": "", 04:11:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:11:37 INFO - "buildid": "20160917163004", 04:11:37 INFO - "pgo_build": "True", 04:11:37 INFO - "basedir": "/builds/slave/test", 04:11:37 INFO - "buildnumber": 457, 04:11:37 INFO - "slavename": "talos-linux64-ix-038", 04:11:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:11:37 INFO - "platform": "ubuntu64_hw", 04:11:37 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:11:37 INFO - "branch": "mozilla-central", 04:11:37 INFO - "repo_path": "mozilla-central", 04:11:37 INFO - "revision": "eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7", 04:11:37 INFO - "stage_platform": "linux64-pgo", 04:11:37 INFO - "builduid": "82db576dbede4acda10282dbf203727c", 04:11:37 INFO - "slavebuilddir": "test" 04:11:37 INFO - } 04:11:37 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:11:37 INFO - Running post-action listener: _resource_record_post_action 04:11:37 INFO - [mozharness: 2016-09-18 11:11:37.542351Z] Finished read-buildbot-config step (success) 04:11:37 INFO - [mozharness: 2016-09-18 11:11:37.542442Z] Running download-and-extract step. 04:11:37 INFO - Running pre-action listener: _resource_record_pre_action 04:11:37 INFO - Running main action method: download_and_extract 04:11:37 INFO - mkdir: /builds/slave/test/build/tests 04:11: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:11:37 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:11:37 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:11:37 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:11:38 INFO - Downloaded 1361 bytes. 04:11:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:11:38 INFO - Using the following test package requirements: 04:11:38 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:11:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:11:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:11:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'jsshell-linux-x86_64.zip'], 04:11:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:11:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:11:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:11:38 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:11:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:11:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:11:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:11:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:11:38 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:11:38 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:11:38 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:11:38 INFO - Expected file size: 21936433 04:11:43 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:11:43 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:11:43 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:11:43 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:11:43 INFO - Expected file size: 21936433 04:11:48 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:11:48 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:11:48 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:11:48 INFO - Expected file size: 14414048 04:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:49 INFO - trying https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:11:49 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:11:49 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:11:50 INFO - Downloaded 59567506 bytes. 04:11:50 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:11:50 INFO - mkdir: /builds/slave/test/properties 04:11:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:11:50 INFO - Writing to file /builds/slave/test/properties/build_url 04:11:50 INFO - Contents: 04:11:50 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:11:51 INFO - Running post-action listener: _resource_record_post_action 04:11:51 INFO - Running post-action listener: set_extra_try_arguments 04:11:51 INFO - [mozharness: 2016-09-18 11:11:51.381999Z] Finished download-and-extract step (success) 04:11:51 INFO - [mozharness: 2016-09-18 11:11:51.382230Z] Running populate-webroot step. 04:11:51 INFO - Running pre-action listener: _resource_record_pre_action 04:11:51 INFO - Running main action method: populate_webroot 04:11:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:11:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:11:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:11:51 INFO - '--tppagecycles', 04:11:51 INFO - '7'], 04:11:51 INFO - 'tests': ['tsvgm']}}, 04:11:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:11:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:11:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:11:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:11:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:11:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:11:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['damp', 'tps']}, 04:11:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'tests': ['damp', 'tps']}, 04:11:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['dromaeo_dom']}, 04:11:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:11:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['basic_compositor_video']}, 04:11:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:11:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['a11yr', 04:11:51 INFO - 'ts_paint', 04:11:51 INFO - 'tpaint', 04:11:51 INFO - 'sessionrestore', 04:11:51 INFO - 'sessionrestore_no_auto_restore', 04:11:51 INFO - 'tabpaint']}, 04:11:51 INFO - 'other-e10s': {'tests': ['a11yr', 04:11:51 INFO - 'ts_paint', 04:11:51 INFO - 'tpaint', 04:11:51 INFO - 'sessionrestore', 04:11:51 INFO - 'sessionrestore_no_auto_restore', 04:11:51 INFO - 'tabpaint']}, 04:11:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:11:51 INFO - 'ts_paint', 04:11:51 INFO - 'tpaint', 04:11:51 INFO - 'sessionrestore', 04:11:51 INFO - 'sessionrestore_no_auto_restore', 04:11:51 INFO - 'tabpaint']}, 04:11:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:11:51 INFO - 'ts_paint', 04:11:51 INFO - 'tpaint', 04:11:51 INFO - 'sessionrestore', 04:11:51 INFO - 'sessionrestore_no_auto_restore', 04:11:51 INFO - 'tabpaint']}, 04:11:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['a11yr', 04:11:51 INFO - 'ts_paint', 04:11:51 INFO - 'tpaint', 04:11:51 INFO - 'sessionrestore', 04:11:51 INFO - 'sessionrestore_no_auto_restore', 04:11:51 INFO - 'tabpaint']}, 04:11:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['a11yr', 04:11:51 INFO - 'ts_paint', 04:11:51 INFO - 'tpaint', 04:11:51 INFO - 'sessionrestore', 04:11:51 INFO - 'sessionrestore_no_auto_restore', 04:11:51 INFO - 'tabpaint']}, 04:11:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['tsvgx', 04:11:51 INFO - 'tsvgr_opacity', 04:11:51 INFO - 'tart', 04:11:51 INFO - 'tscrollx', 04:11:51 INFO - 'cart']}, 04:11:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:11:51 INFO - 'tsvgr_opacity', 04:11:51 INFO - 'tart', 04:11:51 INFO - 'tscrollx', 04:11:51 INFO - 'cart']}, 04:11:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'talos_options': ['--disable-e10s'], 04:11:51 INFO - 'tests': ['tp5o']}, 04:11:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'tests': ['tp5o']}, 04:11:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'talos_options': ['--disable-e10s', 04:11:51 INFO - '--xperf_path', 04:11:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:11:51 INFO - 'tests': ['tp5n']}, 04:11:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:11:51 INFO - 'talos_options': ['--xperf_path', 04:11:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:11:51 INFO - 'tests': ['tp5n']}}, 04:11:51 INFO - 'talos.zip': {'path': '', 04:11:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:11:51 INFO - Running post-action listener: _resource_record_post_action 04:11:51 INFO - [mozharness: 2016-09-18 11:11:51.404297Z] Finished populate-webroot step (success) 04:11:51 INFO - [mozharness: 2016-09-18 11:11:51.404387Z] Running create-virtualenv step. 04:11:51 INFO - Running pre-action listener: _resource_record_pre_action 04:11:51 INFO - Running main action method: create_virtualenv 04:11:51 INFO - Creating virtualenv /builds/slave/test/build/venv 04:11:51 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:11:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:11:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:11:51 INFO - Using real prefix '/usr' 04:11:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:11:52 INFO - Installing distribute.............................................................................................................................................................................................done. 04:11:54 INFO - Installing pip.................done. 04:11:54 INFO - Return code: 0 04:11:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:11:54 INFO - Using env: {'DISPLAY': ':0', 04:11:54 INFO - 'HOME': '/home/cltbld', 04:11:54 INFO - 'LANG': 'en_US.UTF-8', 04:11:54 INFO - 'LANGUAGE': 'en_US:en', 04:11:54 INFO - 'LOGNAME': 'cltbld', 04:11:54 INFO - 'MAIL': '/var/mail/cltbld', 04:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:54 INFO - 'MOZ_NO_REMOTE': '1', 04:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:54 INFO - 'NO_EM_RESTART': '1', 04:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:54 INFO - 'PWD': '/builds/slave/test', 04:11:54 INFO - 'SHELL': '/bin/bash', 04:11:54 INFO - 'SHLVL': '1', 04:11:54 INFO - 'TERM': 'linux', 04:11:54 INFO - 'TMOUT': '86400', 04:11:54 INFO - 'USER': 'cltbld', 04:11:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:54 INFO - '_': '/tools/buildbot/bin/python'} 04:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:54 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:11:54 INFO - Cleaning up... 04:11:54 INFO - Return code: 0 04:11:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:11:54 INFO - Using env: {'DISPLAY': ':0', 04:11:54 INFO - 'HOME': '/home/cltbld', 04:11:54 INFO - 'LANG': 'en_US.UTF-8', 04:11:54 INFO - 'LANGUAGE': 'en_US:en', 04:11:54 INFO - 'LOGNAME': 'cltbld', 04:11:54 INFO - 'MAIL': '/var/mail/cltbld', 04:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:54 INFO - 'MOZ_NO_REMOTE': '1', 04:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:54 INFO - 'NO_EM_RESTART': '1', 04:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:54 INFO - 'PWD': '/builds/slave/test', 04:11:54 INFO - 'SHELL': '/bin/bash', 04:11:54 INFO - 'SHLVL': '1', 04:11:54 INFO - 'TERM': 'linux', 04:11:54 INFO - 'TMOUT': '86400', 04:11:54 INFO - 'USER': 'cltbld', 04:11:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:54 INFO - '_': '/tools/buildbot/bin/python'} 04:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:54 INFO - Downloading/unpacking psutil>=3.1.1 04:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:11:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:11:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:11:56 INFO - Installing collected packages: psutil 04:11:56 INFO - Running setup.py install for psutil 04:11:56 INFO - building 'psutil._psutil_linux' extension 04:11:56 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:11:56 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:11:56 INFO - building 'psutil._psutil_posix' extension 04:11:56 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:11:56 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:11:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:11:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:11:56 INFO - Successfully installed psutil 04:11:56 INFO - Cleaning up... 04:11:56 INFO - Return code: 0 04:11:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:11:56 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:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:56 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:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:56 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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:11:56 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:11:56 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:11:56 INFO - Using env: {'DISPLAY': ':0', 04:11:56 INFO - 'HOME': '/home/cltbld', 04:11:56 INFO - 'LANG': 'en_US.UTF-8', 04:11:56 INFO - 'LANGUAGE': 'en_US:en', 04:11:56 INFO - 'LOGNAME': 'cltbld', 04:11:56 INFO - 'MAIL': '/var/mail/cltbld', 04:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:57 INFO - 'MOZ_NO_REMOTE': '1', 04:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:57 INFO - 'NO_EM_RESTART': '1', 04:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:57 INFO - 'PWD': '/builds/slave/test', 04:11:57 INFO - 'SHELL': '/bin/bash', 04:11:57 INFO - 'SHLVL': '1', 04:11:57 INFO - 'TERM': 'linux', 04:11:57 INFO - 'TMOUT': '86400', 04:11:57 INFO - 'USER': 'cltbld', 04:11:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:57 INFO - '_': '/tools/buildbot/bin/python'} 04:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:11:59 INFO - Installing collected packages: mozsystemmonitor 04:11:59 INFO - Running setup.py install for mozsystemmonitor 04:11:59 INFO - Successfully installed mozsystemmonitor 04:11:59 INFO - Cleaning up... 04:11:59 INFO - Return code: 0 04:11:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:11: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:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11: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:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:59 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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:11:59 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:11:59 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:11:59 INFO - Using env: {'DISPLAY': ':0', 04:11:59 INFO - 'HOME': '/home/cltbld', 04:11:59 INFO - 'LANG': 'en_US.UTF-8', 04:11:59 INFO - 'LANGUAGE': 'en_US:en', 04:11:59 INFO - 'LOGNAME': 'cltbld', 04:11:59 INFO - 'MAIL': '/var/mail/cltbld', 04:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:59 INFO - 'MOZ_NO_REMOTE': '1', 04:11:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:59 INFO - 'NO_EM_RESTART': '1', 04:11:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:59 INFO - 'PWD': '/builds/slave/test', 04:11:59 INFO - 'SHELL': '/bin/bash', 04:11:59 INFO - 'SHLVL': '1', 04:11:59 INFO - 'TERM': 'linux', 04:11:59 INFO - 'TMOUT': '86400', 04:11:59 INFO - 'USER': 'cltbld', 04:11:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:59 INFO - '_': '/tools/buildbot/bin/python'} 04:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:59 INFO - Downloading/unpacking jsonschema==2.5.1 04:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:12:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:12:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:01 INFO - Downloading functools32-3.2.3-2.tar.gz 04:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:12:01 INFO - warning: no files found matching '*.txt' 04:12:01 INFO - no previously-included directories found matching 'build' 04:12:01 INFO - no previously-included directories found matching 'dist' 04:12:01 INFO - no previously-included directories found matching '.git*' 04:12:01 INFO - Installing collected packages: jsonschema, functools32 04:12:01 INFO - Running setup.py install for functools32 04:12:01 INFO - warning: no files found matching '*.txt' 04:12:01 INFO - no previously-included directories found matching 'build' 04:12:01 INFO - no previously-included directories found matching 'dist' 04:12:01 INFO - no previously-included directories found matching '.git*' 04:12:01 INFO - Successfully installed jsonschema functools32 04:12:01 INFO - Cleaning up... 04:12:01 INFO - Return code: 0 04:12:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:12:01 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:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:01 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:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:01 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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:12:01 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:12:01 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:12:01 INFO - Using env: {'DISPLAY': ':0', 04:12:01 INFO - 'HOME': '/home/cltbld', 04:12:01 INFO - 'LANG': 'en_US.UTF-8', 04:12:01 INFO - 'LANGUAGE': 'en_US:en', 04:12:01 INFO - 'LOGNAME': 'cltbld', 04:12:01 INFO - 'MAIL': '/var/mail/cltbld', 04:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:01 INFO - 'MOZ_NO_REMOTE': '1', 04:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:01 INFO - 'NO_EM_RESTART': '1', 04:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:01 INFO - 'PWD': '/builds/slave/test', 04:12:01 INFO - 'SHELL': '/bin/bash', 04:12:01 INFO - 'SHLVL': '1', 04:12:01 INFO - 'TERM': 'linux', 04:12:01 INFO - 'TMOUT': '86400', 04:12:01 INFO - 'USER': 'cltbld', 04:12:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:01 INFO - '_': '/tools/buildbot/bin/python'} 04:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:12:02 INFO - Cleaning up... 04:12:02 INFO - Return code: 0 04:12:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:12:02 INFO - Using env: {'DISPLAY': ':0', 04:12:02 INFO - 'HOME': '/home/cltbld', 04:12:02 INFO - 'LANG': 'en_US.UTF-8', 04:12:02 INFO - 'LANGUAGE': 'en_US:en', 04:12:02 INFO - 'LOGNAME': 'cltbld', 04:12:02 INFO - 'MAIL': '/var/mail/cltbld', 04:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:02 INFO - 'MOZ_NO_REMOTE': '1', 04:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:02 INFO - 'NO_EM_RESTART': '1', 04:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:02 INFO - 'PWD': '/builds/slave/test', 04:12:02 INFO - 'SHELL': '/bin/bash', 04:12:02 INFO - 'SHLVL': '1', 04:12:02 INFO - 'TERM': 'linux', 04:12:02 INFO - 'TMOUT': '86400', 04:12:02 INFO - 'USER': 'cltbld', 04:12:02 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:02 INFO - '_': '/tools/buildbot/bin/python'} 04:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:02 INFO - Downloading/unpacking blobuploader==1.2.4 04:12:02 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:12:02 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:12:02 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:12:02 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:12:02 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:12:02 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:12:04 INFO - Downloading blobuploader-1.2.4.tar.gz 04:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:12:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:12:04 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:12:04 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:12:04 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:12:04 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:12:04 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:12:04 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:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:12:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:12:04 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:12:04 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:12:04 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:12:04 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:12:04 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:12:04 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:12:04 INFO - Downloading docopt-0.6.1.tar.gz 04:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:12:05 INFO - Installing collected packages: blobuploader, requests, docopt 04:12:05 INFO - Running setup.py install for blobuploader 04:12:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:12:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:12:05 INFO - Running setup.py install for requests 04:12:05 INFO - Running setup.py install for docopt 04:12:05 INFO - Successfully installed blobuploader requests docopt 04:12:05 INFO - Cleaning up... 04:12:05 INFO - Return code: 0 04:12:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:05 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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:12:05 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:12:05 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:12:05 INFO - Using env: {'DISPLAY': ':0', 04:12:05 INFO - 'HOME': '/home/cltbld', 04:12:05 INFO - 'LANG': 'en_US.UTF-8', 04:12:05 INFO - 'LANGUAGE': 'en_US:en', 04:12:05 INFO - 'LOGNAME': 'cltbld', 04:12:05 INFO - 'MAIL': '/var/mail/cltbld', 04:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:05 INFO - 'MOZ_NO_REMOTE': '1', 04:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:05 INFO - 'NO_EM_RESTART': '1', 04:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:05 INFO - 'PWD': '/builds/slave/test', 04:12:05 INFO - 'SHELL': '/bin/bash', 04:12:05 INFO - 'SHLVL': '1', 04:12:05 INFO - 'TERM': 'linux', 04:12:05 INFO - 'TMOUT': '86400', 04:12:05 INFO - 'USER': 'cltbld', 04:12:05 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:05 INFO - '_': '/tools/buildbot/bin/python'} 04:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:12:05 INFO - Running setup.py (path:/tmp/pip-tGW_No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:12:05 INFO - Running setup.py (path:/tmp/pip-_nLZw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:12:05 INFO - Running setup.py (path:/tmp/pip-nqBFGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:12:06 INFO - Running setup.py (path:/tmp/pip-S3FR38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:12:06 INFO - Running setup.py (path:/tmp/pip-pGQkRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:12:06 INFO - Running setup.py (path:/tmp/pip-VPCFjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:12:06 INFO - Running setup.py (path:/tmp/pip-V26CP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:12:06 INFO - Running setup.py (path:/tmp/pip-Q2bwpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:12:06 INFO - Running setup.py (path:/tmp/pip-iSgSXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:12:06 INFO - Running setup.py (path:/tmp/pip-OxbiRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:12:06 INFO - Running setup.py (path:/tmp/pip-a3yhN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:12:06 INFO - Running setup.py (path:/tmp/pip-y2gGEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:12:06 INFO - Running setup.py (path:/tmp/pip-wxKH3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:12:06 INFO - Running setup.py (path:/tmp/pip-hC3A2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:06 INFO - Running setup.py (path:/tmp/pip-QU3J86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:12:06 INFO - Running setup.py (path:/tmp/pip-15GGhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:12:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:12:07 INFO - Running setup.py (path:/tmp/pip-SHkvjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:12:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:12:07 INFO - Running setup.py install for manifestparser 04:12:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Running setup.py install for mozcrash 04:12:07 INFO - Running setup.py install for mozdebug 04:12:07 INFO - Running setup.py install for mozdevice 04:12:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Running setup.py install for mozfile 04:12:07 INFO - Running setup.py install for mozhttpd 04:12:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Running setup.py install for mozinfo 04:12:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Running setup.py install for mozInstall 04:12:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:12:07 INFO - Running setup.py install for mozleak 04:12:07 INFO - Running setup.py install for mozlog 04:12:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Running setup.py install for moznetwork 04:12:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Running setup.py install for mozprocess 04:12:08 INFO - Running setup.py install for mozprofile 04:12:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Running setup.py install for mozrunner 04:12:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Running setup.py install for mozscreenshot 04:12:08 INFO - Running setup.py install for moztest 04:12:08 INFO - Running setup.py install for mozversion 04:12:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:12:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:12:08 INFO - Cleaning up... 04:12:08 INFO - Return code: 0 04:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:08 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 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:12:08 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:12:08 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:12:08 INFO - Using env: {'DISPLAY': ':0', 04:12:08 INFO - 'HOME': '/home/cltbld', 04:12:08 INFO - 'LANG': 'en_US.UTF-8', 04:12:08 INFO - 'LANGUAGE': 'en_US:en', 04:12:08 INFO - 'LOGNAME': 'cltbld', 04:12:08 INFO - 'MAIL': '/var/mail/cltbld', 04:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:08 INFO - 'MOZ_NO_REMOTE': '1', 04:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:08 INFO - 'NO_EM_RESTART': '1', 04:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:08 INFO - 'PWD': '/builds/slave/test', 04:12:08 INFO - 'SHELL': '/bin/bash', 04:12:08 INFO - 'SHLVL': '1', 04:12:08 INFO - 'TERM': 'linux', 04:12:08 INFO - 'TMOUT': '86400', 04:12:08 INFO - 'USER': 'cltbld', 04:12:08 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:08 INFO - '_': '/tools/buildbot/bin/python'} 04:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:12:09 INFO - Running setup.py (path:/tmp/pip-d9gp5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:12:09 INFO - Running setup.py (path:/tmp/pip-2jtlaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:12:09 INFO - Running setup.py (path:/tmp/pip-w8DLw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:12:09 INFO - Running setup.py (path:/tmp/pip-89QZWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:12:09 INFO - Running setup.py (path:/tmp/pip-_ASXjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:12:09 INFO - Running setup.py (path:/tmp/pip-1JvOm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:12:09 INFO - Running setup.py (path:/tmp/pip-0n6VUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:12:09 INFO - Running setup.py (path:/tmp/pip-nw7pHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:12:09 INFO - Running setup.py (path:/tmp/pip-qbkRfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:12:09 INFO - Running setup.py (path:/tmp/pip-vWNFTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:12:09 INFO - Running setup.py (path:/tmp/pip-yLsIUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:12:09 INFO - Running setup.py (path:/tmp/pip-S1sYCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:12:09 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:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:12:09 INFO - Running setup.py (path:/tmp/pip-fOYNBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:12:10 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:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:12:10 INFO - Running setup.py (path:/tmp/pip-jP0zsL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:12:10 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:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:10 INFO - Running setup.py (path:/tmp/pip-_ARpT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:10 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:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:12:10 INFO - Running setup.py (path:/tmp/pip-FhXPxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:12:10 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:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:12:10 INFO - Running setup.py (path:/tmp/pip-6q0gPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:10 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:12:12 INFO - Downloading blessings-1.6.tar.gz 04:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:12:12 INFO - Installing collected packages: blessings 04:12:12 INFO - Running setup.py install for blessings 04:12:12 INFO - Successfully installed blessings 04:12:12 INFO - Cleaning up... 04:12:12 INFO - Return code: 0 04:12:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:12:12 INFO - Reading from file tmpfile_stdout 04:12:12 INFO - Current package versions: 04:12:12 INFO - argparse == 1.2.1 04:12:12 INFO - blessings == 1.6 04:12:12 INFO - blobuploader == 1.2.4 04:12:12 INFO - docopt == 0.6.1 04:12:12 INFO - functools32 == 3.2.3-2 04:12:12 INFO - jsonschema == 2.5.1 04:12:12 INFO - manifestparser == 1.1 04:12:12 INFO - mozInstall == 1.12 04:12:12 INFO - mozcrash == 0.17 04:12:12 INFO - mozdebug == 0.1 04:12:12 INFO - mozdevice == 0.48 04:12:12 INFO - mozfile == 1.2 04:12:12 INFO - mozhttpd == 0.7 04:12:12 INFO - mozinfo == 0.9 04:12:12 INFO - mozleak == 0.1 04:12:12 INFO - mozlog == 3.4 04:12:12 INFO - moznetwork == 0.27 04:12:12 INFO - mozprocess == 0.23 04:12:12 INFO - mozprofile == 0.28 04:12:12 INFO - mozrunner == 6.12 04:12:12 INFO - mozscreenshot == 0.1 04:12:12 INFO - mozsystemmonitor == 0.3 04:12:12 INFO - moztest == 0.7 04:12:12 INFO - mozversion == 1.4 04:12:12 INFO - psutil == 3.1.1 04:12:12 INFO - requests == 1.2.3 04:12:12 INFO - wsgiref == 0.1.2 04:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:12:12 INFO - Using env: {'DISPLAY': ':0', 04:12:12 INFO - 'HOME': '/home/cltbld', 04:12:12 INFO - 'LANG': 'en_US.UTF-8', 04:12:12 INFO - 'LANGUAGE': 'en_US:en', 04:12:12 INFO - 'LOGNAME': 'cltbld', 04:12:12 INFO - 'MAIL': '/var/mail/cltbld', 04:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:12 INFO - 'MOZ_NO_REMOTE': '1', 04:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:12 INFO - 'NO_EM_RESTART': '1', 04:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:12 INFO - 'PWD': '/builds/slave/test', 04:12:12 INFO - 'SHELL': '/bin/bash', 04:12:12 INFO - 'SHLVL': '1', 04:12:12 INFO - 'TERM': 'linux', 04:12:12 INFO - 'TMOUT': '86400', 04:12:12 INFO - 'USER': 'cltbld', 04:12:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:12 INFO - '_': '/tools/buildbot/bin/python'} 04:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:12:12 INFO - Cleaning up... 04:12:12 INFO - Return code: 0 04:12:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada18e1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5e7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, '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': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', '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:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:12:12 INFO - Using env: {'DISPLAY': ':0', 04:12:12 INFO - 'HOME': '/home/cltbld', 04:12:12 INFO - 'LANG': 'en_US.UTF-8', 04:12:12 INFO - 'LANGUAGE': 'en_US:en', 04:12:12 INFO - 'LOGNAME': 'cltbld', 04:12:12 INFO - 'MAIL': '/var/mail/cltbld', 04:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:12 INFO - 'MOZ_NO_REMOTE': '1', 04:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:12 INFO - 'NO_EM_RESTART': '1', 04:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:12 INFO - 'PWD': '/builds/slave/test', 04:12:12 INFO - 'SHELL': '/bin/bash', 04:12:12 INFO - 'SHLVL': '1', 04:12:12 INFO - 'TERM': 'linux', 04:12:12 INFO - 'TMOUT': '86400', 04:12:12 INFO - 'USER': 'cltbld', 04:12:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:12 INFO - '_': '/tools/buildbot/bin/python'} 04:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:12:13 INFO - Cleaning up... 04:12:13 INFO - Return code: 0 04:12:13 INFO - Running post-action listener: _resource_record_post_action 04:12:13 INFO - Running post-action listener: _start_resource_monitoring 04:12:13 INFO - Starting resource monitoring. 04:12:13 INFO - [mozharness: 2016-09-18 11:12:13.178849Z] Finished create-virtualenv step (success) 04:12:13 INFO - [mozharness: 2016-09-18 11:12:13.179296Z] Running install step. 04:12:13 INFO - Running pre-action listener: _resource_record_pre_action 04:12:13 INFO - Running main action method: install 04:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:12:13 INFO - Reading from file tmpfile_stdout 04:12:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:12:13 INFO - Reading from file tmpfile_stdout 04:12:13 INFO - Output received: 04:12:13 INFO - Usage: mozinstall [options] installer 04:12:13 INFO - Options: 04:12:13 INFO - -h, --help show this help message and exit 04:12:13 INFO - -d DEST, --destination=DEST 04:12:13 INFO - Directory to install application into. [default: 04:12:13 INFO - "/builds/slave/test"] 04:12:13 INFO - --app=APP Application being installed. [default: firefox] 04:12:13 INFO - mkdir: /builds/slave/test/build/application 04:12:13 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:12:13 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:12:30 INFO - Reading from file tmpfile_stdout 04:12:30 INFO - Output received: 04:12:30 INFO - /builds/slave/test/build/application/firefox/firefox 04:12:30 INFO - Running post-action listener: _resource_record_post_action 04:12:30 INFO - [mozharness: 2016-09-18 11:12:30.418987Z] Finished install step (success) 04:12:30 INFO - [mozharness: 2016-09-18 11:12:30.419232Z] Running run-tests step. 04:12:30 INFO - Running pre-action listener: _resource_record_pre_action 04:12:30 INFO - Running main action method: run_tests 04:12:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:12:30 INFO - Python 2.7.3 04:12:30 INFO - Return code: 0 04:12:30 INFO - grabbing minidump binary from tooltool 04:12: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:12:30 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 0x1ced790>, '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 0x1cee690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ceeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:12:30 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:12:30 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:12:30 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:12:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:12:30 INFO - Return code: 0 04:12:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:12:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:12:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:12:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:12:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:12:30 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-038', '--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:12:30 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-038 --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:12:30 INFO - Using env: {'DISPLAY': ':0', 04:12:30 INFO - 'HOME': '/home/cltbld', 04:12:30 INFO - 'LANG': 'en_US.UTF-8', 04:12:30 INFO - 'LANGUAGE': 'en_US:en', 04:12:30 INFO - 'LOGNAME': 'cltbld', 04:12:30 INFO - 'MAIL': '/var/mail/cltbld', 04:12:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:12:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:30 INFO - 'MOZ_NO_REMOTE': '1', 04:12:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:30 INFO - 'NO_EM_RESTART': '1', 04:12:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:30 INFO - 'PWD': '/builds/slave/test', 04:12:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:12:30 INFO - 'SHELL': '/bin/bash', 04:12:30 INFO - 'SHLVL': '1', 04:12:30 INFO - 'TERM': 'linux', 04:12:30 INFO - 'TMOUT': '86400', 04:12:30 INFO - 'USER': 'cltbld', 04:12:30 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331', 04:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:30 INFO - '_': '/tools/buildbot/bin/python'} 04:12:30 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-038', '--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:12:30 INFO - mozversion application_buildid: 20160917163004 04:12:30 INFO - mozversion application_changeset: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 04:12:30 INFO - mozversion application_display_name: Nightly 04:12:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:12:30 INFO - mozversion application_name: Firefox 04:12:30 INFO - mozversion application_remotingname: firefox 04:12:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:12:30 INFO - mozversion application_vendor: Mozilla 04:12:30 INFO - mozversion application_version: 51.0a1 04:12:30 INFO - mozversion platform_buildid: 20160917163004 04:12:30 INFO - mozversion platform_changeset: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 04:12:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:12:30 INFO - mozversion platform_version: 51.0a1 04:12:30 INFO - using testdate: 1474197150 04:12:30 INFO - actual date: 1474197150 04:12:30 INFO - starting webserver on 'localhost:46733' 04:12:30 INFO - SUITE-START | Running 2 tests 04:12:30 INFO - TEST-START | dromaeo_css 04:12:30 INFO - operating with platform_type : linux_ 04:12:30 INFO - Initialising browser for dromaeo_css test... 04:12:30 INFO - TEST-INFO | started process 9278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW0rz6t/profile http://localhost:46733/getInfo.html) 04:12:35 INFO - PROCESS | 9278 | __metrics Screen width/height:1600/1200 04:12:35 INFO - PROCESS | 9278 | colorDepth:24 04:12:35 INFO - PROCESS | 9278 | Browser inner width/height: 1024/697 04:12:35 INFO - PROCESS | 9278 | __metrics 04:12:36 INFO - PROCESS | 9278 | 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:12:36 INFO - PROCESS | 9278 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:12:37 INFO - PROCESS | 9278 | console.error: PushService: 04:12:37 INFO - PROCESS | 9278 | stateChangeProcessEnqueue: Error transitioning state 04:12:37 INFO - PROCESS | 9278 | UnknownError 04:12:37 INFO - TEST-INFO | 9278: exit 0 04:12:37 INFO - Browser initialized. 04:12:37 INFO - Running cycle 1/1 for dromaeo_css test... 04:12:37 INFO - TEST-INFO | started process 9360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW0rz6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:49 INFO - PROCESS | 9360 | RSS: Main: 173187072 04:12:49 INFO - PROCESS | 9360 | 04:13:08 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:13:38 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:13:41 INFO - PROCESS | 9360 | 1474197221089 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:13:51 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.16667 seconds. 04:13:53 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.33337 seconds. 04:14:01 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:14:07 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 04:14:22 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:14:25 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0667 seconds. 04:14:30 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 04:14:37 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:14:41 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:14:52 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:14:54 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 04:15:00 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.16668 seconds. 04:15:11 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 04:15:22 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:15:28 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:15:30 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:15:38 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 04:15:47 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:15:50 INFO - PROCESS | 9360 | Cycle 1(1): loaded http://localhost:46733/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46733/tests/dromaeo/cssquery-ext.html) 04:15:51 INFO - PROCESS | 9360 | RSS: Main: 274944000 04:15:51 INFO - PROCESS | 9360 | 04:15:55 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:15:57 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 04:16:08 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:16:24 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:16:32 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:16:38 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:16:48 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:16:52 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:16:58 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:17:04 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:17:16 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:17:22 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:17:41 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:17:43 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:18:18 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:18:27 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 04:18:32 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:18:41 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:18:51 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:18:54 INFO - PROCESS | 9360 | Cycle 1(1): loaded http://localhost:46733/tests/dromaeo/cssquery-ext.html (next: http://localhost:46733/tests/dromaeo/cssquery-jquery.html) 04:18:54 INFO - PROCESS | 9360 | RSS: Main: 275206144 04:18:54 INFO - PROCESS | 9360 | 04:18:57 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:18:58 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:19:09 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:19:11 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:19:19 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:19:37 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:19:51 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:19:59 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:20:09 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:20:19 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:20:21 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:20:23 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:20:30 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:20:31 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:20:35 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:20:38 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:21:20 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:21:22 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:21:30 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:21:32 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:21:44 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:21:54 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:21:56 INFO - PROCESS | 9360 | Cycle 1(1): loaded http://localhost:46733/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46733/tests/dromaeo/cssquery-mootools.html) 04:21:57 INFO - PROCESS | 9360 | RSS: Main: 215085056 04:21:57 INFO - PROCESS | 9360 | 04:22:00 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:22:04 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:22:17 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:22:31 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:22:44 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:22:46 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:22:48 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:22:50 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:23:14 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:23:30 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:23:40 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:24:05 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:24:14 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:24:22 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:24:32 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:24:43 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:24:45 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:24:51 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 04:24:55 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:24:59 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:24:59 INFO - PROCESS | 9360 | Cycle 1(1): loaded http://localhost:46733/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46733/tests/dromaeo/cssquery-prototype.html) 04:24:59 INFO - PROCESS | 9360 | RSS: Main: 249368576 04:24:59 INFO - PROCESS | 9360 | 04:25:07 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:25:08 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:25:12 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 04:25:15 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:25:25 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:25:26 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 04:25:28 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:25:48 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:26:34 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:26:45 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:26:54 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:26:57 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:27:03 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 04:27:05 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:27:08 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 04:27:14 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:27:16 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:27:22 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:27:26 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:27:50 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:27:55 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 04:27:56 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:28:02 INFO - PROCESS | 9360 | Cycle 1(1): loaded http://localhost:46733/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46733/tests/dromaeo/cssquery-yui.html) 04:28:03 INFO - PROCESS | 9360 | RSS: Main: 217460736 04:28:03 INFO - PROCESS | 9360 | 04:28:08 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:28:15 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:28:21 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:28:24 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:28:39 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 04:28:48 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 04:28:50 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:28:56 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:29:18 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:29:32 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 04:29:54 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 04:30:06 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:30:23 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:30:31 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 04:30:41 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 04:31:03 INFO - PROCESS | 9360 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 04:31:05 INFO - PROCESS | 9360 | Cycle 1(1): loaded http://localhost:46733/tests/dromaeo/cssquery-yui.html (next: http://localhost:46733/tests/dromaeo/cssquery-dojo.html) 04:31:05 INFO - PROCESS | 9360 | RSS: Main: 304570368 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | __start_tp_report 04:31:05 INFO - PROCESS | 9360 | _x_x_mozilla_page_load 04:31:05 INFO - PROCESS | 9360 | _x_x_mozilla_page_load_details 04:31:05 INFO - PROCESS | 9360 | |i|pagename|runs| 04:31:05 INFO - PROCESS | 9360 | |0;dojo.html;3026.4854974654313;3051.694830516947;3056.648485424176;3083.660797312297;3102.627684677839;5106.387233531972;5179.430262671103;5209.322788037554;5288.49759272869;5315.176147697109;5205.479452054793;5228.503292187236;5247.553957913582;5257.342832145991;5258.158694608864;4727.314539391786;4774.713517188962;4787.71273723576;4800.807967681289;4902.534259245366;4846.369971903665;4874.122657921573;4892.241702536113;4892.510748925115;4906.460289368167;4734.573888350048;4778.80884764609;4782.784774685148;4817.470078291385;4872.123017856784;4855.441624213226;4856.757162141878;4872.756362181912;4904.4605093439695;4917.950820491821;4782.30656554801;4797.856064318076;4803;4815.0610630926985;4876.634252431082;5108.259302401131;5142.820001299974;5144.151215049513;5233.555147812441;5240.659357141773;5598;5602.215689803431;5602.859928501779;5621.4659607337235;5669.773209071632;5609.439056094398;5617.0731829248125;5623.100303951389;5630.493255607015;5635;5109.41241757199;5155.123628983082;5176.197689358156;5243.580513558906;5312.893742125175;5644.8306550803545;5669;5676.943230567724;5739.62692424991;5740.942590574125;5484.314460692413;5493.670379777226;5498.505134537831;5498.890022199534;5501.807436739695;5388.191771234347;5501.779928802883;5503.9174412383845;5512.3660779010925;5556.77772889088;5446.237526746179;5542.224088627595;5548.86127846799;5577.888442231133;5617.9719101404235;5523.502884740393;5527.50252477279;5536.335639723259;5549.556035517149;5624.831255062355;4231.259529582311;4306.978465107654;4315.611594956406;4316.633086187647;4362.956370436255;3811.1805961718364;3825.407061905409;3865.7100717446306;3866.845326186921;3954.367301231847;5249.107651699221;5253.868653283698;5263.6578622389425;5264.263003179558;5339.652922560099;5581.860453488614;5583.972080139654;5585.469380408855;5589.077802162679;5592.5246354059545;5189.766460509286;5199.688018718889;5210.687358758487;5259.552938000313;5262.184361423985;4788.377510923628;4818.903621927542;4820.734859582757;4865.416150062015;4879.536444037811;5720.856978575569;5726.169705392695;5737.88524229513;5738.225339579103;5740.282464691913;5434.483724046209;5477.041517734301;5512.173174023928;5570.6657600544095;5602.635828671123;5769.884602307847;5775.942240577709;5780.624259423124;5822.417758224144;5839.153322768259;5524.392316845224;5531.197976293495;5533.5849811265125;5534.529564987021;5535.861603459945;5388.515033646991;5487.75305111263;5580.832575022756;5610.13042978339;5627.268455100811;5485.945140548543;5524.944750552443;5526.668399896019;5545.6296725124275;5595.776168953196;5486.972565137149;5490.780368785204;5492.670439773627;5503.504684578407;5505.532029777513;5345.9465405347;5401.351837779492;5473.972630136824;5482.533984611353;5553.805616803393;5441.945580544302;5480.369757477941;5491.28613280287;5508.063629183129;5547.362053363915;5405.432429594836;5408.161734931092;5408.45915408456;5413.7834486620095;5415.323084614423 04:31:05 INFO - PROCESS | 9360 | |1;ext.html;568.4031766645022;602.367514110184;604.9848753781155;605.5791225098558;614.4500671898651;42221.57778422215;43433.56566434335;43637.56362436375;43684.34473482896;47889.76055119719;45709.314360284625;46356;46425.1968503937;46476.302855457194;46532.30201546971;8961.417507862001;9091.499967501784;9101.453912765222;9104.544772761335;9108.453492790453;8940.19538241558;9043;9043.457392556435;9043.773905652346;9048.321375896801;9098.226650734663;9123.543822808866;9142.817143657123;9150.588223529923;9154.084591540826;9003.774905627346;9032.8645070324;9041.909580904172;9047.095290470934;9051.954740226323;9016.684416045407;9085.727428177166;9086.273098152198;9097.090290970917;9098.727038188865;42603.57396426027;43134.78432607848;43149.35275970863;43206.13587728228;43293.35059974103;3280.1471617379652;3428.8114153721535;3894.337962546346;4028.67770578353;4070.5115386153557;32612.6738732612;32789;33198.33603327944;33228;33639.49540756891;3944.5266568011734;3999.7600143991162;4061.9187616247805;4113.670906327487;4165.271077561445;263274;263614;264321;264357;264590.9999999981;54924.17613735797;59996.40003599908;60194;60234;60343.39656603466;64130.67934660297;64274.82213656097;64276.678616607554;64285.67857160684;64325.67837160784;17462.912685436746;17737.201825917607;17759.822401776077;17761.289548418175;17796.199171037333;13585.660358491117;13636.931815341059;13645.181289122684;13654;13655.112417692819;16017.917670702735;17932.282708691506;18010.90994545019;18018.729719054223;18143.455696329132;5869.413058694182;5964.7315870785915;5977.6114552553945;6201;6227.159333490012;776.2625505769541;839.0686338164633;903.814717982812;908.3667703998996;1128.8588926384202;30769.538456923165;32632;32633.18417039593;32799.50800737991;33286.66713332836;950.6530116507425;1098.4123493930708;1116.8050186300743;1120.0535547462382;1122.6183097746805;16991.83008169927;17056.147192640565;17194.828051719574;17213.397531086353;17215.655686886443;503433;505388;506567;506908;507100;56701.43298567044;56785.14822277669;56925.71537142288;56973.14540281899;56994.43005569891;1224.7428040111677;1277.3485522383467;1309.9738005240033;1366.549038817188;1371.081375478412;31162.688373116885;32317.35365292634;33351.49972750362;33406;33791;50199.74900125476;50634.24048639347;50837.49162508475;50850;50892.491075090256;406205;409532.9513753425;409779;410447;411088;27782;28841.56737648895;28971.710282897744;29675.406491870475;29748.553771693874;47225.527744723484;47799;48568.271475927184;48584;49671.50328496813;39476.605233948445;40152.598474014885;40529.59470405258;40591.3911291325;42574.1485170301;40593.69313523476;40680.186396272504;40720;41639.7918010408;42067.158656827305;40732.1853562933;41117.58882411138;41582.58417415787;41610.58389416067;41681.79159104185;34519.4822077674;36684.20318447238;36716;37446.81276593709;37759;28034.113273562278;28178.581046709758;28402.715972840007;28453.715462845106;28560.285992850346 04:31:05 INFO - PROCESS | 9360 | |2;jquery.html;382.47409811509175;395.4147861165473;406.10656555577725;410.28815005964657;427.314160771961;13001.804972925414;14969.401223951043;18569.535761605966;18631.534211644717;19460.026998650083;18180.818191818078;18453.35413260536;18707.625847483043;18769.436916892515;19216.71174932377;5614.382417934024;5698.0911394682835;5901;5911.408859114097;5911.645301281937;5708.914366284509;5726.828195154131;5739.713014349287;5750.568707346944;5781.277340332458;5590.696793424345;5713.342965558952;5751.367349591542;5755.50356907203;5756.769729210827;5709.400512946143;5768.278965129359;5776.59563830532;5777.884442311151;5779.653220806765;5001.674891132065;5176.197689358156;5179.533841954242;5207.218917162418;5225.372955245357;23105.19131830378;23122.19072332477;23128;23192.18827341052;23321.18375856854;2190.5728382965326;2228.2423975848287;2268.534950335177;2311.5413091115133;2931.5309550472034;13826.30868456573;14118.13660411749;14551.490697825631;14606.19665918374;14777.926110369486;1897.8956157411337;1912.4931893048354;1929.9324523641747;1946.6106778644328;1961.2939341836927;74181.62909185473;85937;86612.13387866104;86804;88202.55898720465;10108.595656173818;10223.693289201336;10370.429626370471;11335.319880807177;11544.538218471334;15834.287457064229;15928.92035539838;15943.521694349189;15965.281562329723;16024.359025639076;8002.679892804339;8508.276796472246;8564.357673174536;8793.340499462565;8795.47227166372;8176.1006289308125;8289.8342033159;8342.830863975289;8444.704435344856;8537.097386888488;4677.929831052537;4831.637627177975;4853.587445067138;4875.829345972874;5016.0971025215085;2018.8586798924227;2059.8970051497367;2067.0904801887123;2096.465401322653;2128.70198172256;933.1042199488431;939.3847030195233;943.3208090175063;961.6201600367815;968.8304546704298;18293.268269269345;18419.447416577525;19766.308179213946;19782.30761923326;20655.17379304841;933.5752232734089;951;1003.3979612232748;1014.2747935226348;1043.082087762779;10397.272190946713;10879.184061195441;10901.037813193543;10961.342319460859;11082.722931926604;492994;495097;496171;519921;521514;56928.71535642295;88425;96180;99829;101931;977.3158788847835;1010.6513252927729;1029.063552167539;1033.788073444942;1036.3160314192596;16542.66914661724;16659.833401665826;17129.14354282256;17313.134343283036;17658.64682706313;42267.7886610565;43056.56943430525;43218.35172472353;43735.78132109319;44936;59831.70084149551;59913.400865990785;60869;62243;62476.375236247055;5595.272614560081;7702.844573313823;8583.484990900566;8710.738677839676;8788.2529984952;11952.521899123938;12980.572137065104;13370.264635445144;13446.579615669274;13814.37835297394;11444.198906076495;11663.708407289885;11775.470103845177;12030.578929737418;12067.698307542381;11305.491252893804;11430.371329576954;11645.76708465843;11696.473658685209;11918.344491053076;11622.012128969132;11686.18196726221;11701.707457313636;11985.940070299594;12054;7566.1298950621385;8662.653493860174;8838.248748856418;8874.689385871721;8926.642934282556;11398.316101033965;11926.463309150935;11944.820827687765;12035.277883327028;12416.503339866304 04:31:05 INFO - PROCESS | 9360 | |3;mootools.html;1353.3436283402555;1453.2443129572625;1464.5606318104567;1617.1590772798138;1755.8595312375012;7721.38228941684;8474.915250847489;8808.823823523533;8852.159044890741;9085.818283634324;8835.425697329669;8892.8666070009;8899.599518021689;8909.73270801877;8912.420692654972;2271.3867255840914;2294.242899843048;2300.493891343902;2300.735415427222;2302.0561569756405;2245.3264020793777;2291.9885400573057;2292.197730794225;2308.7575804540534;2309.099451214028;2266.546690661866;2269.7730226977337;2285.80570651494;2292.7248730152405;2300.3559003478967;2210.16013914713;2220.744614369352;2226.899789509476;2226.9665955010687;2229.4092065602626;2178.498945242587;2204.041242059709;2204.4929666176868;2204.7795220477983;2209.2819833554;8798.032216456184;8849.424787388863;8856.380053396264;8859.734207973706;8865.157810008039;5101.362329708786;5248.606354523387;5303.337082864642;5341.092014357569;5351.09031464652;3282.950755738666;3408.199073217792;3475.061733331986;3477.009052420048;3509.491123787062;1067.1942683274078;1121.495327102799;1121.876593574706;1123.5000424810962;1124.7975364434399;12219.511219551207;12255.448504817216;12257.632271031884;12287.508499660004;12315.568955086528;4284.293091639907;4452.510223875371;4578.748168850679;4581.587657110876;4589.954100458953;9643.807123857483;9650.469224192597;9658.661946831828;9669.323147379617;9718.75703107428;5241;5246.63273570854;5249.790008399621;5286.894262114736;5288.524032837063;4351.9564804352185;4505.5719706627815;4778.211595086772;4889.535494128052;4899.118158731462;3739.420389839579;3796.9430458543143;3797.050737315671;3839.251345987506;3893.396523538856;1129.88701129888;1149.8329195866202;1151.240181480219;1159.2522822779429;1160.5821904114512;973.4013581647378;976.5947131940165;988.9357191782511;991.6182269826024;992.9106380425796;5997.610155339889;6076.936536106252;6098.390160983955;6186.505079593621;6250.093736408196;456.51837311635876;468.09890959902054;468.29755366949576;468.6368064749778;471.9528047195253;3986.342253528135;4046.979765101156;4106.917861642751;4197.76912269822;4201.579842015774;10529.526171322308;11028.779424411467;11032.503537340679;11034.558617655364;11035.39305338198;10898.291611045257;10939.28894621847;10996.835047474295;11006.449677516091;11006.559737610565;7871.842563148705;8058.99262592176;8181.877271840927;8292.751217463485;8338.62476188573;5987.401259873977;6026.517878569792;6081.179040829431;6148.846278843064;6285.2457705075685;9676.177524910461;10119.494025298853;10127;10132.645357412455;10169.745756356151;10261.384316941007;10275.640352587623;10314.690559283234;10373.896261037293;10382.3251488653;7020.122484689414;7245.710171593288;7538.736144234926;7727.613619319124;7897.131315555399;8402;8779.078196789245;8791.736247912573;8816.426932249384;8820.691275805573;7631.8855217172895;7716.884246736191;7792.1428642848105;7876;7985.960070199844;7511.849763004819;7598.658060387185;7760.417968652487;7766.145723970471;7782.49413788102;6852.862942741018;6965.268646792014;6967.8606427870145;6970.616616086164;7013.368796808108;5123.410807757006;5162.845114646567;5214.087534681492;5237.580993520433;5473.124300111963;5094.1849304111165;5219.686818790885;5294.179402192666;5318.095923692904;5605.439456054362 04:31:05 INFO - PROCESS | 9360 | |4;prototype.html;318.59856580708316;333.55470446953314;336.22500137183795;341.50140794440114;342.40934388180386;5097.643164978444;5243.31836861208;5243.86890327742;5345.465453454653;5376.731163441832;5060.595152387809;5160.277561141434;5179.2749015137815;5206.895862082756;5249;4186.162767446508;4189.350650649139;4199.307114326132;4199.811008504609;4229.873103806875;4000.339858958543;4128.566500517448;4182.184474027566;4184.225918205142;4199.908023913774;4080.6327430531246;4089.7137200395982;4093.2018256440006;4098.299205829577;4105.2815757242515;4065.4105154752397;4082.6527246008895;4088.080181959065;4106.67146628272;4137.648299894513;3630.5280313559333;3638.21778317663;3650.5984341722387;3651.707863370924;3656.8720094796554;5046.596272298244;5086.694798312114;5094.847154585368;5108.7445627718835;5127;4676.433394812741;4683.508231635663;4693.272542755878;4699.624030077585;4700.388949436551;5442.8911421771345;5449.209864569615;5450.836474905759;5470.589705772083;5540.113581826889;3829.5404551454003;3954.2486927483687;3964.940525892114;3967.444557761916;3973.6821054315874;5659.773609055674;5678.886422271573;5680.602357834953;5681.772729090872;5688.260526131618;4045.554988951213;4050.6354428101613;4052.65552428041;4053.1893621275863;4093.7134400592267;4002.2595819772855;4137.606927341898;4151.647109995623;4167.1457351242925;4191.958080419156;4661.370714953506;4706.270528068087;4708.505606911293;4736.242201247784;4743.549362810528;5496.285482887199;5524.535997960544;5529.585281103933;5579.135234038649;5593.8321850344555;1325.8210141630757;1361.9591412257648;1382.8755412012968;1389.4650559534653;1409.2883094037445;959.2182371367313;968.3415277611151;982.5480279071708;984.7247814080782;987.9753006174759;5194.4026436959475;5224.164133738584;5241.868953276198;5254.710990895461;5261.184516399964;5523.420040895729;5527.309086364205;5554.2233679036;5557.722113894289;5609.887802243932;5110.310108135354;5175.301334319819;5245.554127899171;5277.129273669878;5320.377284928057;2577.4716183182404;2578.8781879882313;2616.6336712860216;2626.606009098612;2633.565461698798;5024.924626130611;5025.949740502621;5032.421271553818;5046.34397528319;5053.7220452875445;4986.678530189502;5053.36832895888;5466.644668096561;5777.133429985452;5803.622764520293;5813.418658134237;5816.371416003526;5816.76732930678;5818.389069147678;5852.151438041375;5525.613207075467;5642;5651.378348381592;5662.688552129673;5701.344345400332;5586.078297080936;5600.719964001865;5607.41122182165;5612.719364031864;5635.830925072254;5712.485876270989;5724.341700704473;5807.506361959281;5837.8540536485225;5870.911936320873;3925.803709814555;4041.1109555897656;4046.1503084352616;4049.696272779612;4086.836526539024;5355.196720491957;5621.5221706155435;5641.12562552805;5660.6037577370835;5687.857803554779;5339.813106541253;5398.514133727984;5513.917291240555;5528.087865502147;5535.335759708861;5445.863853403538;5450.400455949763;5453.7818487260065;5460.153676180198;5517.806876759455;5262.86842828917;5410.3507579090765;5444.210589464585;5453.236546883519;5466.508014278734;5488.835334939959;5490.901819636008;5498.422665620213;5498.642588231752;5502.58730595199;5380.946190538045;5387.81142660005;5391.784328626811;5394.435613671991;5448.166953184243 04:31:05 INFO - PROCESS | 9360 | |5;yui.html;657.8947368421053;664.0272001517777;713.2617740638437;733.9889901651476;749.5053264845196;13863.58409247723;14207.57377278682;14275.928620356884;14306.928465357685;14333.283335833192;12818.359082045907;12867;13515.32423378832;13636.795448068262;13638.45446182154;778.1557010643445;802.1537278171527;804.505229283989;804.9879251811199;806.7095845495617;646.8285904235381;668.5420486966419;774.8915151878741;794.4240425691356;800.9839803203932;843.0178841649471;844.1263292492272;845.4377838737232;845.6110189312924;848.1730312944891;831.5052543736527;833.7457075591942;833.9249467547919;835.8035861572525;835.9247667709905;498.8902441462872;509.65088914093593;512.9102407078784;512.9897402051938;597.7608956417424;13253.933730331382;13780.793288100687;13799.379027943767;13804.240766757925;13808.447662093504;12281.324527151002;12396.318202498858;12399.13206075575;12607.873921260762;13046.739065218737;11495.885041149566;11646;11725.355105469196;12023.699407514794;12235.449404776806;4247.086876321606;4301.225779359714;4338.501072376654;4365.9126817463475;4366.519682834885;80369;82465.58767206185;82724.58637706834;82797.58601207015;82895.17104828995;10720.46397680113;10727.517261723086;10880.401577913133;10973.725656858483;11054.392008439452;22690;22710.886445567667;22729.545409091726;22742.772572274396;22853.542929141324;8115;8200.876986845202;8300.792480188044;8344.541050242173;8665.436746611576;4466.151431228056;4491.416115904912;4525.298578720303;4580.267157254823;4852.223644216909;3248.7238584720562;3348.765586408928;3352.664733526628;3353.7652364334263;3358.1604598850286;543.2855794630049;546.6446809573729;547.8657728856456;548.0737553534514;550.7796881247533;625.3137752946446;664.4419578757597;688.5180373738403;689.0904006711194;710.7263703474097;8410.663573457115;9105.453672779522;9192.908070919339;9201.401908876036;9384.296177786693;549.4340828946192;686.5543184167693;689.6103701408688;691.882379995402;724.5507785173227;4115.855945041909;4343.391925130484;4380.62764665007;4492.191405546968;4511.233090374644;122293.38853305677;126048.36975815063;126162;126613.7338626602;130439;100880.49559752154;101150;104574.47712761388;112127;112708;56070.439295607335;74746.62626686832;89758.55120724485;90368.09631903729;91727;8893.510856902933;9338.579763910504;9901.306908516335;9940.502974851373;9951.751206219902;48907.75546122246;52440.73779631077;53030;53293;53644.195337070756;67928.6603566979;76271;76418;77527;79753;11808.822867657163;11840;11878.762424751283;11904.583339583072;11981.700457488632;12970.80543791825;12985.740285194432;12992.935035324763;13021.934890325489;13478.393472293574;12580.622581322576;13036.283004434847;13090.345482726016;13226.669333266746;13302.677283966354;12476.500939962663;12907.741845162856;12928.676783080498;13053.869461305645;13288.136271142732;12053.75892482163;12585.685357866127;12680.619581412573;12686.422718632755;13085.280309582684;10081.294309398272;10265.58937642486;10524.210684198562;10539.314944528582;10670.330924117156;9166.815769070028;9247.953760231156;9389.81220375575;10070.19438444908;10150.746231344276 04:31:05 INFO - PROCESS | 9360 | __end_tp_report 04:31:05 INFO - PROCESS | 9360 | __start_cc_report 04:31:05 INFO - PROCESS | 9360 | _x_x_mozilla_cycle_collect,1126 04:31:05 INFO - PROCESS | 9360 | __end_cc_report 04:31:05 INFO - PROCESS | 9360 | __startTimestamp1474198265970__endTimestamp 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | ------- Summary: start ------- 04:31:05 INFO - PROCESS | 9360 | Number of tests: 6 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | [#0] dojo.html Cycles:165 Average:5202.83 Median:5413.78 stddev:567.03 (10.5%) stddev-sans-first:542.46 04:31:05 INFO - PROCESS | 9360 | Values: 3026.5 3051.7 3056.6 3083.7 3102.6 5106.4 5179.4 5209.3 5288.5 5315.2 5205.5 5228.5 5247.6 5257.3 5258.2 4727.3 4774.7 4787.7 4800.8 4902.5 4846.4 4874.1 4892.2 4892.5 4906.5 4734.6 4778.8 4782.8 4817.5 4872.1 4855.4 4856.8 4872.8 4904.5 4918.0 4782.3 4797.9 4803.0 4815.1 4876.6 5108.3 5142.8 5144.2 5233.6 5240.7 5598.0 5602.2 5602.9 5621.5 5669.8 5609.4 5617.1 5623.1 5630.5 5635.0 5109.4 5155.1 5176.2 5243.6 5312.9 5644.8 5669.0 5676.9 5739.6 5740.9 5484.3 5493.7 5498.5 5498.9 5501.8 5388.2 5501.8 5503.9 5512.4 5556.8 5446.2 5542.2 5548.9 5577.9 5618.0 5523.5 5527.5 5536.3 5549.6 5624.8 4231.3 4307.0 4315.6 4316.6 4363.0 3811.2 3825.4 3865.7 3866.8 3954.4 5249.1 5253.9 5263.7 5264.3 5339.7 5581.9 5584.0 5585.5 5589.1 5592.5 5189.8 5199.7 5210.7 5259.6 5262.2 4788.4 4818.9 4820.7 4865.4 4879.5 5720.9 5726.2 5737.9 5738.2 5740.3 5434.5 5477.0 5512.2 5570.7 5602.6 5769.9 5775.9 5780.6 5822.4 5839.2 5524.4 5531.2 5533.6 5534.5 5535.9 5388.5 5487.8 5580.8 5610.1 5627.3 5485.9 5524.9 5526.7 5545.6 5595.8 5487.0 5490.8 5492.7 5503.5 5505.5 5345.9 5401.4 5474.0 5482.5 5553.8 5441.9 5480.4 5491.3 5508.1 5547.4 5405.4 5408.2 5408.5 5413.8 5415.3 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | [#1] ext.html Cycles:180 Average:56578.18 Median:22902.53 stddev:107354.37 (468.7%) stddev-sans-first:107573.16 04:31:05 INFO - PROCESS | 9360 | Values: 568.4 602.4 605.0 605.6 614.5 42221.6 43433.6 43637.6 43684.3 47889.8 45709.3 46356.0 46425.2 46476.3 46532.3 8961.4 9091.5 9101.5 9104.5 9108.5 8940.2 9043.0 9043.5 9043.8 9048.3 9098.2 9123.5 9142.8 9150.6 9154.1 9003.8 9032.9 9041.9 9047.1 9052.0 9016.7 9085.7 9086.3 9097.1 9098.7 42603.6 43134.8 43149.4 43206.1 43293.4 3280.1 3428.8 3894.3 4028.7 4070.5 32612.7 32789.0 33198.3 33228.0 33639.5 3944.5 3999.8 4061.9 4113.7 4165.3 263274.0 263614.0 264321.0 264357.0 264591.0 54924.2 59996.4 60194.0 60234.0 60343.4 64130.7 64274.8 64276.7 64285.7 64325.7 17462.9 17737.2 17759.8 17761.3 17796.2 13585.7 13636.9 13645.2 13654.0 13655.1 16017.9 17932.3 18010.9 18018.7 18143.5 5869.4 5964.7 5977.6 6201.0 6227.2 776.3 839.1 903.8 908.4 1128.9 30769.5 32632.0 32633.2 32799.5 33286.7 950.7 1098.4 1116.8 1120.1 1122.6 16991.8 17056.1 17194.8 17213.4 17215.7 503433.0 505388.0 506567.0 506908.0 507100.0 56701.4 56785.1 56925.7 56973.1 56994.4 1224.7 1277.3 1310.0 1366.5 1371.1 31162.7 32317.4 33351.5 33406.0 33791.0 50199.7 50634.2 50837.5 50850.0 50892.5 406205.0 409533.0 409779.0 410447.0 411088.0 27782.0 28841.6 28971.7 29675.4 29748.6 47225.5 47799.0 48568.3 48584.0 49671.5 39476.6 40152.6 40529.6 40591.4 42574.1 40593.7 40680.2 40720.0 41639.8 42067.2 40732.2 41117.6 41582.6 41610.6 41681.8 34519.5 36684.2 36716.0 37446.8 37759.0 28034.1 28178.6 28402.7 28453.7 28560.3 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | [#2] jquery.html Cycles:180 Average:29614.67 Median:23256.69 stddev:83297.32 (358.2%) stddev-sans-first:83502.07 04:31:05 INFO - PROCESS | 9360 | Values: 382.5 395.4 406.1 410.3 427.3 13001.8 14969.4 18569.5 18631.5 19460.0 18180.8 18453.4 18707.6 18769.4 19216.7 5614.4 5698.1 5901.0 5911.4 5911.6 5708.9 5726.8 5739.7 5750.6 5781.3 5590.7 5713.3 5751.4 5755.5 5756.8 5709.4 5768.3 5776.6 5777.9 5779.7 5001.7 5176.2 5179.5 5207.2 5225.4 23105.2 23122.2 23128.0 23192.2 23321.2 2190.6 2228.2 2268.5 2311.5 2931.5 13826.3 14118.1 14551.5 14606.2 14777.9 1897.9 1912.5 1929.9 1946.6 1961.3 74181.6 85937.0 86612.1 86804.0 88202.6 10108.6 10223.7 10370.4 11335.3 11544.5 15834.3 15928.9 15943.5 15965.3 16024.4 8002.7 8508.3 8564.4 8793.3 8795.5 8176.1 8289.8 8342.8 8444.7 8537.1 4677.9 4831.6 4853.6 4875.8 5016.1 2018.9 2059.9 2067.1 2096.5 2128.7 933.1 939.4 943.3 961.6 968.8 18293.3 18419.4 19766.3 19782.3 20655.2 933.6 951.0 1003.4 1014.3 1043.1 10397.3 10879.2 10901.0 10961.3 11082.7 492994.0 495097.0 496171.0 519921.0 521514.0 56928.7 88425.0 96180.0 99829.0 101931.0 977.3 1010.7 1029.1 1033.8 1036.3 16542.7 16659.8 17129.1 17313.1 17658.6 42267.8 43056.6 43218.4 43735.8 44936.0 59831.7 59913.4 60869.0 62243.0 62476.4 5595.3 7702.8 8583.5 8710.7 8788.3 11952.5 12980.6 13370.3 13446.6 13814.4 11444.2 11663.7 11775.5 12030.6 12067.7 11305.5 11430.4 11645.8 11696.5 11918.3 11622.0 11686.2 11701.7 11985.9 12054.0 7566.1 8662.7 8838.2 8874.7 8926.6 11398.3 11926.5 11944.8 12035.3 12416.5 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | [#3] mootools.html Cycles:180 Average:5769.35 Median:2625.08 stddev:3318.43 (126.4%) stddev-sans-first:3311.15 04:31:05 INFO - PROCESS | 9360 | Values: 1353.3 1453.2 1464.6 1617.2 1755.9 7721.4 8474.9 8808.8 8852.2 9085.8 8835.4 8892.9 8899.6 8909.7 8912.4 2271.4 2294.2 2300.5 2300.7 2302.1 2245.3 2292.0 2292.2 2308.8 2309.1 2266.5 2269.8 2285.8 2292.7 2300.4 2210.2 2220.7 2226.9 2227.0 2229.4 2178.5 2204.0 2204.5 2204.8 2209.3 8798.0 8849.4 8856.4 8859.7 8865.2 5101.4 5248.6 5303.3 5341.1 5351.1 3283.0 3408.2 3475.1 3477.0 3509.5 1067.2 1121.5 1121.9 1123.5 1124.8 12219.5 12255.4 12257.6 12287.5 12315.6 4284.3 4452.5 4578.7 4581.6 4590.0 9643.8 9650.5 9658.7 9669.3 9718.8 5241.0 5246.6 5249.8 5286.9 5288.5 4352.0 4505.6 4778.2 4889.5 4899.1 3739.4 3796.9 3797.1 3839.3 3893.4 1129.9 1149.8 1151.2 1159.3 1160.6 973.4 976.6 988.9 991.6 992.9 5997.6 6076.9 6098.4 6186.5 6250.1 456.5 468.1 468.3 468.6 472.0 3986.3 4047.0 4106.9 4197.8 4201.6 10529.5 11028.8 11032.5 11034.6 11035.4 10898.3 10939.3 10996.8 11006.4 11006.6 7871.8 8059.0 8181.9 8292.8 8338.6 5987.4 6026.5 6081.2 6148.8 6285.2 9676.2 10119.5 10127.0 10132.6 10169.7 10261.4 10275.6 10314.7 10373.9 10382.3 7020.1 7245.7 7538.7 7727.6 7897.1 8402.0 8779.1 8791.7 8816.4 8820.7 7631.9 7716.9 7792.1 7876.0 7986.0 7511.8 7598.7 7760.4 7766.1 7782.5 6852.9 6965.3 6967.9 6970.6 7013.4 5123.4 5162.8 5214.1 5237.6 5473.1 5094.2 5219.7 5294.2 5318.1 5605.4 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | [#4] prototype.html Cycles:180 Average:4594.42 Median:5233.02 stddev:1347.05 (25.7%) stddev-sans-first:1312.04 04:31:05 INFO - PROCESS | 9360 | Values: 318.6 333.6 336.2 341.5 342.4 5097.6 5243.3 5243.9 5345.5 5376.7 5060.6 5160.3 5179.3 5206.9 5249.0 4186.2 4189.4 4199.3 4199.8 4229.9 4000.3 4128.6 4182.2 4184.2 4199.9 4080.6 4089.7 4093.2 4098.3 4105.3 4065.4 4082.7 4088.1 4106.7 4137.6 3630.5 3638.2 3650.6 3651.7 3656.9 5046.6 5086.7 5094.8 5108.7 5127.0 4676.4 4683.5 4693.3 4699.6 4700.4 5442.9 5449.2 5450.8 5470.6 5540.1 3829.5 3954.2 3964.9 3967.4 3973.7 5659.8 5678.9 5680.6 5681.8 5688.3 4045.6 4050.6 4052.7 4053.2 4093.7 4002.3 4137.6 4151.6 4167.1 4192.0 4661.4 4706.3 4708.5 4736.2 4743.5 5496.3 5524.5 5529.6 5579.1 5593.8 1325.8 1362.0 1382.9 1389.5 1409.3 959.2 968.3 982.5 984.7 988.0 5194.4 5224.2 5241.9 5254.7 5261.2 5523.4 5527.3 5554.2 5557.7 5609.9 5110.3 5175.3 5245.6 5277.1 5320.4 2577.5 2578.9 2616.6 2626.6 2633.6 5024.9 5025.9 5032.4 5046.3 5053.7 4986.7 5053.4 5466.6 5777.1 5803.6 5813.4 5816.4 5816.8 5818.4 5852.2 5525.6 5642.0 5651.4 5662.7 5701.3 5586.1 5600.7 5607.4 5612.7 5635.8 5712.5 5724.3 5807.5 5837.9 5870.9 3925.8 4041.1 4046.2 4049.7 4086.8 5355.2 5621.5 5641.1 5660.6 5687.9 5339.8 5398.5 5513.9 5528.1 5535.3 5445.9 5450.4 5453.8 5460.2 5517.8 5262.9 5410.4 5444.2 5453.2 5466.5 5488.8 5490.9 5498.4 5498.6 5502.6 5380.9 5387.8 5391.8 5394.4 5448.2 04:31:05 INFO - PROCESS | 9360 | 04:31:05 INFO - PROCESS | 9360 | [#5] yui.html Cycles:180 Average:20997.84 Median:4478.78 stddev:31773.77 (709.4%) stddev-sans-first:31826.20 04:31:05 INFO - PROCESS | 9360 | Values: 657.9 664.0 713.3 734.0 749.5 13863.6 14207.6 14275.9 14306.9 14333.3 12818.4 12867.0 13515.3 13636.8 13638.5 778.2 802.2 804.5 805.0 806.7 646.8 668.5 774.9 794.4 801.0 843.0 844.1 845.4 845.6 848.2 831.5 833.7 833.9 835.8 835.9 498.9 509.7 512.9 513.0 597.8 13253.9 13780.8 13799.4 13804.2 13808.4 12281.3 12396.3 12399.1 12607.9 13046.7 11495.9 11646.0 11725.4 12023.7 12235.4 4247.1 4301.2 4338.5 4365.9 4366.5 80369.0 82465.6 82724.6 82797.6 82895.2 10720.5 10727.5 10880.4 10973.7 11054.4 22690.0 22710.9 22729.5 22742.8 22853.5 8115.0 8200.9 8300.8 8344.5 8665.4 4466.2 4491.4 4525.3 4580.3 4852.2 3248.7 3348.8 3352.7 3353.8 3358.2 543.3 546.6 547.9 548.1 550.8 625.3 664.4 688.5 689.1 710.7 8410.7 9105.5 9192.9 9201.4 9384.3 549.4 686.6 689.6 691.9 724.6 4115.9 4343.4 4380.6 4492.2 4511.2 122293.4 126048.4 126162.0 126613.7 130439.0 100880.5 101150.0 104574.5 112127.0 112708.0 56070.4 74746.6 89758.6 90368.1 91727.0 8893.5 9338.6 9901.3 9940.5 9951.8 48907.8 52440.7 53030.0 53293.0 53644.2 67928.7 76271.0 76418.0 77527.0 79753.0 11808.8 11840.0 11878.8 11904.6 11981.7 12970.8 12985.7 12992.9 13021.9 13478.4 12580.6 13036.3 13090.3 13226.7 13302.7 12476.5 12907.7 12928.7 13053.9 13288.1 12053.8 12585.7 12680.6 12686.4 13085.3 10081.3 10265.6 10524.2 10539.3 10670.3 9166.8 9248.0 9389.8 10070.2 10150.7 04:31:05 INFO - PROCESS | 9360 | -------- Summary: end -------- 04:31:05 INFO - PROCESS | 9360 | 04:31:06 INFO - TEST-INFO | 9360: exit 0 04:31:06 INFO - TEST-OK | dromaeo_css | took 1116248ms 04:31:06 INFO - TEST-START | kraken 04:31:06 INFO - operating with platform_type : linux_ 04:31:06 INFO - Initialising browser for kraken test... 04:31:06 INFO - TEST-INFO | started process 9673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD3yn4Q/profile http://localhost:46733/getInfo.html) 04:31:11 INFO - PROCESS | 9673 | __metrics Screen width/height:1600/1200 04:31:11 INFO - PROCESS | 9673 | colorDepth:24 04:31:11 INFO - PROCESS | 9673 | Browser inner width/height: 1024/697 04:31:11 INFO - PROCESS | 9673 | __metrics 04:31:12 INFO - PROCESS | 9673 | 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:31:12 INFO - PROCESS | 9673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:31:13 INFO - PROCESS | 9673 | console.error: PushService: 04:31:13 INFO - PROCESS | 9673 | stateChangeProcessEnqueue: Error transitioning state 04:31:13 INFO - PROCESS | 9673 | UnknownError 04:31:13 INFO - TEST-INFO | 9673: exit 0 04:31:13 INFO - Browser initialized. 04:31:13 INFO - Running cycle 1/1 for kraken test... 04:31:13 INFO - TEST-INFO | started process 9761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD3yn4Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:26 INFO - PROCESS | 9761 | RSS: Main: 173424640 04:31:26 INFO - PROCESS | 9761 | 04:31:28 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46733/tests/kraken/driver.html?testName=audio-beat-detection) 04:31:29 INFO - PROCESS | 9761 | RSS: Main: 241176576 04:31:29 INFO - PROCESS | 9761 | 04:31:32 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46733/tests/kraken/driver.html?testName=audio-dft) 04:31:32 INFO - PROCESS | 9761 | RSS: Main: 216776704 04:31:32 INFO - PROCESS | 9761 | 04:31:35 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46733/tests/kraken/driver.html?testName=audio-fft) 04:31:36 INFO - PROCESS | 9761 | RSS: Main: 298291200 04:31:36 INFO - PROCESS | 9761 | 04:31:38 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46733/tests/kraken/driver.html?testName=audio-oscillator) 04:31:39 INFO - PROCESS | 9761 | RSS: Main: 207806464 04:31:39 INFO - PROCESS | 9761 | 04:31:41 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46733/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:31:41 INFO - PROCESS | 9761 | RSS: Main: 211808256 04:31:41 INFO - PROCESS | 9761 | 04:31:44 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46733/tests/kraken/driver.html?testName=imaging-darkroom) 04:31:45 INFO - PROCESS | 9761 | RSS: Main: 248840192 04:31:45 INFO - PROCESS | 9761 | 04:31:48 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46733/tests/kraken/driver.html?testName=imaging-desaturate) 04:31:49 INFO - PROCESS | 9761 | RSS: Main: 248311808 04:31:49 INFO - PROCESS | 9761 | 04:31:52 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46733/tests/kraken/driver.html?testName=json-parse-financial) 04:31:52 INFO - PROCESS | 9761 | RSS: Main: 248385536 04:31:52 INFO - PROCESS | 9761 | 04:31:54 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46733/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:31:54 INFO - PROCESS | 9761 | RSS: Main: 210079744 04:31:54 INFO - PROCESS | 9761 | 04:31:56 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:31:57 INFO - PROCESS | 9761 | RSS: Main: 217116672 04:31:57 INFO - PROCESS | 9761 | 04:31:59 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:32:00 INFO - PROCESS | 9761 | RSS: Main: 221990912 04:32:00 INFO - PROCESS | 9761 | 04:32:02 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:32:03 INFO - PROCESS | 9761 | RSS: Main: 223199232 04:32:03 INFO - PROCESS | 9761 | 04:32:06 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:32:06 INFO - PROCESS | 9761 | RSS: Main: 220844032 04:32:06 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | Cycle 1(1): loaded http://localhost:46733/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46733/tests/kraken/driver.html?testName=ai-astar) 04:32:08 INFO - PROCESS | 9761 | RSS: Main: 272048128 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | __start_tp_report 04:32:08 INFO - PROCESS | 9761 | _x_x_mozilla_page_load 04:32:08 INFO - PROCESS | 9761 | _x_x_mozilla_page_load_details 04:32:08 INFO - PROCESS | 9761 | |i|pagename|runs| 04:32:08 INFO - PROCESS | 9761 | |0;ai-astar;110;111;111;111;112;112;111;111;112;112 04:32:08 INFO - PROCESS | 9761 | |1;audio-beat-detection;144;118;117;155;120;119;120;119;144;123 04:32:08 INFO - PROCESS | 9761 | |2;audio-dft;163;162;162;161;161;161;162;162;160;163 04:32:08 INFO - PROCESS | 9761 | |3;audio-fft;77;76;76;119;76;75;76;76;111;75 04:32:08 INFO - PROCESS | 9761 | |4;audio-oscillator;82;82;113;82;82;83;107;83;82;112 04:32:08 INFO - PROCESS | 9761 | |5;imaging-gaussian-blur;116;116;116;117;117;117;117;117;117;117 04:32:08 INFO - PROCESS | 9761 | |6;imaging-darkroom;146;145;145;147;145;145;147;147;147;146 04:32:08 INFO - PROCESS | 9761 | |7;imaging-desaturate;95;96;95;96;95;95;95;95;96;95 04:32:08 INFO - PROCESS | 9761 | |8;json-parse-financial;51;50;50;50;51;52;50;52;52;50 04:32:08 INFO - PROCESS | 9761 | |9;json-stringify-tinderbox;62;63;62;63;65;64;64;64;61;65 04:32:08 INFO - PROCESS | 9761 | |10;stanford-crypto-aes;79;78;78;78;79;78;81;78;77;77 04:32:08 INFO - PROCESS | 9761 | |11;stanford-crypto-ccm;128;123;135;134;132;125;124;124;178;127 04:32:08 INFO - PROCESS | 9761 | |12;stanford-crypto-pbkdf2;161;165;160;170;166;159;161;167;166;160 04:32:08 INFO - PROCESS | 9761 | |13;stanford-crypto-sha256-iterative;66;66;65;67;65;64;64;70;67;64 04:32:08 INFO - PROCESS | 9761 | __end_tp_report 04:32:08 INFO - PROCESS | 9761 | __start_cc_report 04:32:08 INFO - PROCESS | 9761 | _x_x_mozilla_cycle_collect,1650 04:32:08 INFO - PROCESS | 9761 | __end_cc_report 04:32:08 INFO - PROCESS | 9761 | __startTimestamp1474198328541__endTimestamp 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | ------- Summary: start ------- 04:32:08 INFO - PROCESS | 9761 | Number of tests: 14 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:0.67 (0.6%) stddev-sans-first:0.53 04:32:08 INFO - PROCESS | 9761 | Values: 110.0 111.0 111.0 111.0 112.0 112.0 111.0 111.0 112.0 112.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:121.50 stddev:14.05 (11.6%) stddev-sans-first:13.64 04:32:08 INFO - PROCESS | 9761 | Values: 144.0 118.0 117.0 155.0 120.0 119.0 120.0 119.0 144.0 123.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:0.88 04:32:08 INFO - PROCESS | 9761 | Values: 163.0 162.0 162.0 161.0 161.0 161.0 162.0 162.0 160.0 163.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#3] audio-fft Cycles:10 Average:83.70 Median:76.00 stddev:16.61 (21.9%) stddev-sans-first:17.44 04:32:08 INFO - PROCESS | 9761 | Values: 77.0 76.0 76.0 119.0 76.0 75.0 76.0 76.0 111.0 75.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:82.00 stddev:13.80 (16.8%) stddev-sans-first:14.26 04:32:08 INFO - PROCESS | 9761 | Values: 82.0 82.0 113.0 82.0 82.0 83.0 107.0 83.0 82.0 112.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:32:08 INFO - PROCESS | 9761 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.50 stddev:0.94 (0.6%) stddev-sans-first:1.00 04:32:08 INFO - PROCESS | 9761 | Values: 146.0 145.0 145.0 147.0 145.0 145.0 147.0 147.0 147.0 146.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:32:08 INFO - PROCESS | 9761 | Values: 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.92 (1.8%) stddev-sans-first:0.97 04:32:08 INFO - PROCESS | 9761 | Values: 51.0 50.0 50.0 50.0 51.0 52.0 50.0 52.0 52.0 50.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:64.00 stddev:1.34 (2.1%) stddev-sans-first:1.33 04:32:08 INFO - PROCESS | 9761 | Values: 62.0 63.0 62.0 63.0 65.0 64.0 64.0 64.0 61.0 65.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:1.16 (1.5%) stddev-sans-first:1.20 04:32:08 INFO - PROCESS | 9761 | Values: 79.0 78.0 78.0 78.0 79.0 78.0 81.0 78.0 77.0 77.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:130.00 stddev:16.39 (12.6%) stddev-sans-first:17.29 04:32:08 INFO - PROCESS | 9761 | Values: 128.0 123.0 135.0 134.0 132.0 125.0 124.0 124.0 178.0 127.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:165.50 stddev:3.75 (2.3%) stddev-sans-first:3.87 04:32:08 INFO - PROCESS | 9761 | Values: 161.0 165.0 160.0 170.0 166.0 159.0 161.0 167.0 166.0 160.0 04:32:08 INFO - PROCESS | 9761 | 04:32:08 INFO - PROCESS | 9761 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.87 (2.8%) stddev-sans-first:1.99 04:32:08 INFO - PROCESS | 9761 | Values: 66.0 66.0 65.0 67.0 65.0 64.0 64.0 70.0 67.0 64.0 04:32:08 INFO - PROCESS | 9761 | -------- Summary: end -------- 04:32:08 INFO - PROCESS | 9761 | 04:32:09 INFO - TEST-INFO | 9761: exit 0 04:32:09 INFO - TEST-OK | kraken | took 62877ms 04:32:09 INFO - SUITE-END | took 1179s 04:32:10 INFO - Completed test suite (00:19:39) 04:32:10 INFO - javascript benchmark 04:32:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3026.4854974654313, 3051.694830516947, 3056.648485424176, 3083.660797312297, 3102.627684677839, 5106.387233531972, 5179.430262671103, 5209.322788037554, 5288.49759272869, 5315.176147697109, 5205.479452054793, 5228.503292187236, 5247.553957913582, 5257.342832145991, 5258.158694608864, 4727.314539391786, 4774.713517188962, 4787.71273723576, 4800.807967681289, 4902.534259245366, 4846.369971903665, 4874.122657921573, 4892.241702536113, 4892.510748925115, 4906.460289368167, 4734.573888350048, 4778.80884764609, 4782.784774685148, 4817.470078291385, 4872.123017856784, 4855.441624213226, 4856.757162141878, 4872.756362181912, 4904.4605093439695, 4917.950820491821, 4782.30656554801, 4797.856064318076, 4803.0, 4815.0610630926985, 4876.634252431082, 5108.259302401131, 5142.820001299974, 5144.151215049513, 5233.555147812441, 5240.659357141773, 5598.0, 5602.215689803431, 5602.859928501779, 5621.4659607337235, 5669.773209071632, 5609.439056094398, 5617.0731829248125, 5623.100303951389, 5630.493255607015, 5635.0, 5109.41241757199, 5155.123628983082, 5176.197689358156, 5243.580513558906, 5312.893742125175, 5644.8306550803545, 5669.0, 5676.943230567724, 5739.62692424991, 5740.942590574125, 5484.314460692413, 5493.670379777226, 5498.505134537831, 5498.890022199534, 5501.807436739695, 5388.191771234347, 5501.779928802883, 5503.9174412383845, 5512.3660779010925, 5556.77772889088, 5446.237526746179, 5542.224088627595, 5548.86127846799, 5577.888442231133, 5617.9719101404235, 5523.502884740393, 5527.50252477279, 5536.335639723259, 5549.556035517149, 5624.831255062355, 4231.259529582311, 4306.978465107654, 4315.611594956406, 4316.633086187647, 4362.956370436255, 3811.1805961718364, 3825.407061905409, 3865.7100717446306, 3866.845326186921, 3954.367301231847, 5249.107651699221, 5253.868653283698, 5263.6578622389425, 5264.263003179558, 5339.652922560099, 5581.860453488614, 5583.972080139654, 5585.469380408855, 5589.077802162679, 5592.5246354059545, 5189.766460509286, 5199.688018718889, 5210.687358758487, 5259.552938000313, 5262.184361423985, 4788.377510923628, 4818.903621927542, 4820.734859582757, 4865.416150062015, 4879.536444037811, 5720.856978575569, 5726.169705392695, 5737.88524229513, 5738.225339579103, 5740.282464691913, 5434.483724046209, 5477.041517734301, 5512.173174023928, 5570.6657600544095, 5602.635828671123, 5769.884602307847, 5775.942240577709, 5780.624259423124, 5822.417758224144, 5839.153322768259, 5524.392316845224, 5531.197976293495, 5533.5849811265125, 5534.529564987021, 5535.861603459945, 5388.515033646991, 5487.75305111263, 5580.832575022756, 5610.13042978339, 5627.268455100811, 5485.945140548543, 5524.944750552443, 5526.668399896019, 5545.6296725124275, 5595.776168953196, 5486.972565137149, 5490.780368785204, 5492.670439773627, 5503.504684578407, 5505.532029777513, 5345.9465405347, 5401.351837779492, 5473.972630136824, 5482.533984611353, 5553.805616803393, 5441.945580544302, 5480.369757477941, 5491.28613280287, 5508.063629183129, 5547.362053363915, 5405.432429594836, 5408.161734931092, 5408.45915408456, 5413.7834486620095, 5415.323084614423], "value": 5165.907453086002, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.4031766645022, 602.367514110184, 604.9848753781155, 605.5791225098558, 614.4500671898651, 42221.57778422215, 43433.56566434335, 43637.56362436375, 43684.34473482896, 47889.76055119719, 45709.314360284625, 46356.0, 46425.1968503937, 46476.302855457194, 46532.30201546971, 8961.417507862001, 9091.499967501784, 9101.453912765222, 9104.544772761335, 9108.453492790453, 8940.19538241558, 9043.0, 9043.457392556435, 9043.773905652346, 9048.321375896801, 9098.226650734663, 9123.543822808866, 9142.817143657123, 9150.588223529923, 9154.084591540826, 9003.774905627346, 9032.8645070324, 9041.909580904172, 9047.095290470934, 9051.954740226323, 9016.684416045407, 9085.727428177166, 9086.273098152198, 9097.090290970917, 9098.727038188865, 42603.57396426027, 43134.78432607848, 43149.35275970863, 43206.13587728228, 43293.35059974103, 3280.1471617379652, 3428.8114153721535, 3894.337962546346, 4028.67770578353, 4070.5115386153557, 32612.6738732612, 32789.0, 33198.33603327944, 33228.0, 33639.49540756891, 3944.5266568011734, 3999.7600143991162, 4061.9187616247805, 4113.670906327487, 4165.271077561445, 263274.0, 263614.0, 264321.0, 264357.0, 264590.9999999981, 54924.17613735797, 59996.40003599908, 60194.0, 60234.0, 60343.39656603466, 64130.67934660297, 64274.82213656097, 64276.678616607554, 64285.67857160684, 64325.67837160784, 17462.912685436746, 17737.201825917607, 17759.822401776077, 17761.289548418175, 17796.199171037333, 13585.660358491117, 13636.931815341059, 13645.181289122684, 13654.0, 13655.112417692819, 16017.917670702735, 17932.282708691506, 18010.90994545019, 18018.729719054223, 18143.455696329132, 5869.413058694182, 5964.7315870785915, 5977.6114552553945, 6201.0, 6227.159333490012, 776.2625505769541, 839.0686338164633, 903.814717982812, 908.3667703998996, 1128.8588926384202, 30769.538456923165, 32632.0, 32633.18417039593, 32799.50800737991, 33286.66713332836, 950.6530116507425, 1098.4123493930708, 1116.8050186300743, 1120.0535547462382, 1122.6183097746805, 16991.83008169927, 17056.147192640565, 17194.828051719574, 17213.397531086353, 17215.655686886443, 503433.0, 505388.0, 506567.0, 506908.0, 507100.0, 56701.43298567044, 56785.14822277669, 56925.71537142288, 56973.14540281899, 56994.43005569891, 1224.7428040111677, 1277.3485522383467, 1309.9738005240033, 1366.549038817188, 1371.081375478412, 31162.688373116885, 32317.35365292634, 33351.49972750362, 33406.0, 33791.0, 50199.74900125476, 50634.24048639347, 50837.49162508475, 50850.0, 50892.491075090256, 406205.0, 409532.9513753425, 409779.0, 410447.0, 411088.0, 27782.0, 28841.56737648895, 28971.710282897744, 29675.406491870475, 29748.553771693874, 47225.527744723484, 47799.0, 48568.271475927184, 48584.0, 49671.50328496813, 39476.605233948445, 40152.598474014885, 40529.59470405258, 40591.3911291325, 42574.1485170301, 40593.69313523476, 40680.186396272504, 40720.0, 41639.7918010408, 42067.158656827305, 40732.1853562933, 41117.58882411138, 41582.58417415787, 41610.58389416067, 41681.79159104185, 34519.4822077674, 36684.20318447238, 36716.0, 37446.81276593709, 37759.0, 28034.113273562278, 28178.581046709758, 28402.715972840007, 28453.715462845106, 28560.285992850346], "value": 19921.503498297032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.47409811509175, 395.4147861165473, 406.10656555577725, 410.28815005964657, 427.314160771961, 13001.804972925414, 14969.401223951043, 18569.535761605966, 18631.534211644717, 19460.026998650083, 18180.818191818078, 18453.35413260536, 18707.625847483043, 18769.436916892515, 19216.71174932377, 5614.382417934024, 5698.0911394682835, 5901.0, 5911.408859114097, 5911.645301281937, 5708.914366284509, 5726.828195154131, 5739.713014349287, 5750.568707346944, 5781.277340332458, 5590.696793424345, 5713.342965558952, 5751.367349591542, 5755.50356907203, 5756.769729210827, 5709.400512946143, 5768.278965129359, 5776.59563830532, 5777.884442311151, 5779.653220806765, 5001.674891132065, 5176.197689358156, 5179.533841954242, 5207.218917162418, 5225.372955245357, 23105.19131830378, 23122.19072332477, 23128.0, 23192.18827341052, 23321.18375856854, 2190.5728382965326, 2228.2423975848287, 2268.534950335177, 2311.5413091115133, 2931.5309550472034, 13826.30868456573, 14118.13660411749, 14551.490697825631, 14606.19665918374, 14777.926110369486, 1897.8956157411337, 1912.4931893048354, 1929.9324523641747, 1946.6106778644328, 1961.2939341836927, 74181.62909185473, 85937.0, 86612.13387866104, 86804.0, 88202.55898720465, 10108.595656173818, 10223.693289201336, 10370.429626370471, 11335.319880807177, 11544.538218471334, 15834.287457064229, 15928.92035539838, 15943.521694349189, 15965.281562329723, 16024.359025639076, 8002.679892804339, 8508.276796472246, 8564.357673174536, 8793.340499462565, 8795.47227166372, 8176.1006289308125, 8289.8342033159, 8342.830863975289, 8444.704435344856, 8537.097386888488, 4677.929831052537, 4831.637627177975, 4853.587445067138, 4875.829345972874, 5016.0971025215085, 2018.8586798924227, 2059.8970051497367, 2067.0904801887123, 2096.465401322653, 2128.70198172256, 933.1042199488431, 939.3847030195233, 943.3208090175063, 961.6201600367815, 968.8304546704298, 18293.268269269345, 18419.447416577525, 19766.308179213946, 19782.30761923326, 20655.17379304841, 933.5752232734089, 951.0, 1003.3979612232748, 1014.2747935226348, 1043.082087762779, 10397.272190946713, 10879.184061195441, 10901.037813193543, 10961.342319460859, 11082.722931926604, 492994.0, 495097.0, 496171.0, 519921.0, 521514.0, 56928.71535642295, 88425.0, 96180.0, 99829.0, 101931.0, 977.3158788847835, 1010.6513252927729, 1029.063552167539, 1033.788073444942, 1036.3160314192596, 16542.66914661724, 16659.833401665826, 17129.14354282256, 17313.134343283036, 17658.64682706313, 42267.7886610565, 43056.56943430525, 43218.35172472353, 43735.78132109319, 44936.0, 59831.70084149551, 59913.400865990785, 60869.0, 62243.0, 62476.375236247055, 5595.272614560081, 7702.844573313823, 8583.484990900566, 8710.738677839676, 8788.2529984952, 11952.521899123938, 12980.572137065104, 13370.264635445144, 13446.579615669274, 13814.37835297394, 11444.198906076495, 11663.708407289885, 11775.470103845177, 12030.578929737418, 12067.698307542381, 11305.491252893804, 11430.371329576954, 11645.76708465843, 11696.473658685209, 11918.344491053076, 11622.012128969132, 11686.18196726221, 11701.707457313636, 11985.940070299594, 12054.0, 7566.1298950621385, 8662.653493860174, 8838.248748856418, 8874.689385871721, 8926.642934282556, 11398.316101033965, 11926.463309150935, 11944.820827687765, 12035.277883327028, 12416.503339866304], "value": 9323.254207378808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.3436283402555, 1453.2443129572625, 1464.5606318104567, 1617.1590772798138, 1755.8595312375012, 7721.38228941684, 8474.915250847489, 8808.823823523533, 8852.159044890741, 9085.818283634324, 8835.425697329669, 8892.8666070009, 8899.599518021689, 8909.73270801877, 8912.420692654972, 2271.3867255840914, 2294.242899843048, 2300.493891343902, 2300.735415427222, 2302.0561569756405, 2245.3264020793777, 2291.9885400573057, 2292.197730794225, 2308.7575804540534, 2309.099451214028, 2266.546690661866, 2269.7730226977337, 2285.80570651494, 2292.7248730152405, 2300.3559003478967, 2210.16013914713, 2220.744614369352, 2226.899789509476, 2226.9665955010687, 2229.4092065602626, 2178.498945242587, 2204.041242059709, 2204.4929666176868, 2204.7795220477983, 2209.2819833554, 8798.032216456184, 8849.424787388863, 8856.380053396264, 8859.734207973706, 8865.157810008039, 5101.362329708786, 5248.606354523387, 5303.337082864642, 5341.092014357569, 5351.09031464652, 3282.950755738666, 3408.199073217792, 3475.061733331986, 3477.009052420048, 3509.491123787062, 1067.1942683274078, 1121.495327102799, 1121.876593574706, 1123.5000424810962, 1124.7975364434399, 12219.511219551207, 12255.448504817216, 12257.632271031884, 12287.508499660004, 12315.568955086528, 4284.293091639907, 4452.510223875371, 4578.748168850679, 4581.587657110876, 4589.954100458953, 9643.807123857483, 9650.469224192597, 9658.661946831828, 9669.323147379617, 9718.75703107428, 5241.0, 5246.63273570854, 5249.790008399621, 5286.894262114736, 5288.524032837063, 4351.9564804352185, 4505.5719706627815, 4778.211595086772, 4889.535494128052, 4899.118158731462, 3739.420389839579, 3796.9430458543143, 3797.050737315671, 3839.251345987506, 3893.396523538856, 1129.88701129888, 1149.8329195866202, 1151.240181480219, 1159.2522822779429, 1160.5821904114512, 973.4013581647378, 976.5947131940165, 988.9357191782511, 991.6182269826024, 992.9106380425796, 5997.610155339889, 6076.936536106252, 6098.390160983955, 6186.505079593621, 6250.093736408196, 456.51837311635876, 468.09890959902054, 468.29755366949576, 468.6368064749778, 471.9528047195253, 3986.342253528135, 4046.979765101156, 4106.917861642751, 4197.76912269822, 4201.579842015774, 10529.526171322308, 11028.779424411467, 11032.503537340679, 11034.558617655364, 11035.39305338198, 10898.291611045257, 10939.28894621847, 10996.835047474295, 11006.449677516091, 11006.559737610565, 7871.842563148705, 8058.99262592176, 8181.877271840927, 8292.751217463485, 8338.62476188573, 5987.401259873977, 6026.517878569792, 6081.179040829431, 6148.846278843064, 6285.2457705075685, 9676.177524910461, 10119.494025298853, 10127.0, 10132.645357412455, 10169.745756356151, 10261.384316941007, 10275.640352587623, 10314.690559283234, 10373.896261037293, 10382.3251488653, 7020.122484689414, 7245.710171593288, 7538.736144234926, 7727.613619319124, 7897.131315555399, 8402.0, 8779.078196789245, 8791.736247912573, 8816.426932249384, 8820.691275805573, 7631.8855217172895, 7716.884246736191, 7792.1428642848105, 7876.0, 7985.960070199844, 7511.849763004819, 7598.658060387185, 7760.417968652487, 7766.145723970471, 7782.49413788102, 6852.862942741018, 6965.268646792014, 6967.8606427870145, 6970.616616086164, 7013.368796808108, 5123.410807757006, 5162.845114646567, 5214.087534681492, 5237.580993520433, 5473.124300111963, 5094.1849304111165, 5219.686818790885, 5294.179402192666, 5318.095923692904, 5605.439456054362], "value": 4518.921614225792, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.59856580708316, 333.55470446953314, 336.22500137183795, 341.50140794440114, 342.40934388180386, 5097.643164978444, 5243.31836861208, 5243.86890327742, 5345.465453454653, 5376.731163441832, 5060.595152387809, 5160.277561141434, 5179.2749015137815, 5206.895862082756, 5249.0, 4186.162767446508, 4189.350650649139, 4199.307114326132, 4199.811008504609, 4229.873103806875, 4000.339858958543, 4128.566500517448, 4182.184474027566, 4184.225918205142, 4199.908023913774, 4080.6327430531246, 4089.7137200395982, 4093.2018256440006, 4098.299205829577, 4105.2815757242515, 4065.4105154752397, 4082.6527246008895, 4088.080181959065, 4106.67146628272, 4137.648299894513, 3630.5280313559333, 3638.21778317663, 3650.5984341722387, 3651.707863370924, 3656.8720094796554, 5046.596272298244, 5086.694798312114, 5094.847154585368, 5108.7445627718835, 5127.0, 4676.433394812741, 4683.508231635663, 4693.272542755878, 4699.624030077585, 4700.388949436551, 5442.8911421771345, 5449.209864569615, 5450.836474905759, 5470.589705772083, 5540.113581826889, 3829.5404551454003, 3954.2486927483687, 3964.940525892114, 3967.444557761916, 3973.6821054315874, 5659.773609055674, 5678.886422271573, 5680.602357834953, 5681.772729090872, 5688.260526131618, 4045.554988951213, 4050.6354428101613, 4052.65552428041, 4053.1893621275863, 4093.7134400592267, 4002.2595819772855, 4137.606927341898, 4151.647109995623, 4167.1457351242925, 4191.958080419156, 4661.370714953506, 4706.270528068087, 4708.505606911293, 4736.242201247784, 4743.549362810528, 5496.285482887199, 5524.535997960544, 5529.585281103933, 5579.135234038649, 5593.8321850344555, 1325.8210141630757, 1361.9591412257648, 1382.8755412012968, 1389.4650559534653, 1409.2883094037445, 959.2182371367313, 968.3415277611151, 982.5480279071708, 984.7247814080782, 987.9753006174759, 5194.4026436959475, 5224.164133738584, 5241.868953276198, 5254.710990895461, 5261.184516399964, 5523.420040895729, 5527.309086364205, 5554.2233679036, 5557.722113894289, 5609.887802243932, 5110.310108135354, 5175.301334319819, 5245.554127899171, 5277.129273669878, 5320.377284928057, 2577.4716183182404, 2578.8781879882313, 2616.6336712860216, 2626.606009098612, 2633.565461698798, 5024.924626130611, 5025.949740502621, 5032.421271553818, 5046.34397528319, 5053.7220452875445, 4986.678530189502, 5053.36832895888, 5466.644668096561, 5777.133429985452, 5803.622764520293, 5813.418658134237, 5816.371416003526, 5816.76732930678, 5818.389069147678, 5852.151438041375, 5525.613207075467, 5642.0, 5651.378348381592, 5662.688552129673, 5701.344345400332, 5586.078297080936, 5600.719964001865, 5607.41122182165, 5612.719364031864, 5635.830925072254, 5712.485876270989, 5724.341700704473, 5807.506361959281, 5837.8540536485225, 5870.911936320873, 3925.803709814555, 4041.1109555897656, 4046.1503084352616, 4049.696272779612, 4086.836526539024, 5355.196720491957, 5621.5221706155435, 5641.12562552805, 5660.6037577370835, 5687.857803554779, 5339.813106541253, 5398.514133727984, 5513.917291240555, 5528.087865502147, 5535.335759708861, 5445.863853403538, 5450.400455949763, 5453.7818487260065, 5460.153676180198, 5517.806876759455, 5262.86842828917, 5410.3507579090765, 5444.210589464585, 5453.236546883519, 5466.508014278734, 5488.835334939959, 5490.901819636008, 5498.422665620213, 5498.642588231752, 5502.58730595199, 5380.946190538045, 5387.81142660005, 5391.784328626811, 5394.435613671991, 5448.166953184243], "value": 4167.784100630677, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.8947368421053, 664.0272001517777, 713.2617740638437, 733.9889901651476, 749.5053264845196, 13863.58409247723, 14207.57377278682, 14275.928620356884, 14306.928465357685, 14333.283335833192, 12818.359082045907, 12867.0, 13515.32423378832, 13636.795448068262, 13638.45446182154, 778.1557010643445, 802.1537278171527, 804.505229283989, 804.9879251811199, 806.7095845495617, 646.8285904235381, 668.5420486966419, 774.8915151878741, 794.4240425691356, 800.9839803203932, 843.0178841649471, 844.1263292492272, 845.4377838737232, 845.6110189312924, 848.1730312944891, 831.5052543736527, 833.7457075591942, 833.9249467547919, 835.8035861572525, 835.9247667709905, 498.8902441462872, 509.65088914093593, 512.9102407078784, 512.9897402051938, 597.7608956417424, 13253.933730331382, 13780.793288100687, 13799.379027943767, 13804.240766757925, 13808.447662093504, 12281.324527151002, 12396.318202498858, 12399.13206075575, 12607.873921260762, 13046.739065218737, 11495.885041149566, 11646.0, 11725.355105469196, 12023.699407514794, 12235.449404776806, 4247.086876321606, 4301.225779359714, 4338.501072376654, 4365.9126817463475, 4366.519682834885, 80369.0, 82465.58767206185, 82724.58637706834, 82797.58601207015, 82895.17104828995, 10720.46397680113, 10727.517261723086, 10880.401577913133, 10973.725656858483, 11054.392008439452, 22690.0, 22710.886445567667, 22729.545409091726, 22742.772572274396, 22853.542929141324, 8115.0, 8200.876986845202, 8300.792480188044, 8344.541050242173, 8665.436746611576, 4466.151431228056, 4491.416115904912, 4525.298578720303, 4580.267157254823, 4852.223644216909, 3248.7238584720562, 3348.765586408928, 3352.664733526628, 3353.7652364334263, 3358.1604598850286, 543.2855794630049, 546.6446809573729, 547.8657728856456, 548.0737553534514, 550.7796881247533, 625.3137752946446, 664.4419578757597, 688.5180373738403, 689.0904006711194, 710.7263703474097, 8410.663573457115, 9105.453672779522, 9192.908070919339, 9201.401908876036, 9384.296177786693, 549.4340828946192, 686.5543184167693, 689.6103701408688, 691.882379995402, 724.5507785173227, 4115.855945041909, 4343.391925130484, 4380.62764665007, 4492.191405546968, 4511.233090374644, 122293.38853305677, 126048.36975815063, 126162.0, 126613.7338626602, 130439.0, 100880.49559752154, 101150.0, 104574.47712761388, 112127.0, 112708.0, 56070.439295607335, 74746.62626686832, 89758.55120724485, 90368.09631903729, 91727.0, 8893.510856902933, 9338.579763910504, 9901.306908516335, 9940.502974851373, 9951.751206219902, 48907.75546122246, 52440.73779631077, 53030.0, 53293.0, 53644.195337070756, 67928.6603566979, 76271.0, 76418.0, 77527.0, 79753.0, 11808.822867657163, 11840.0, 11878.762424751283, 11904.583339583072, 11981.700457488632, 12970.80543791825, 12985.740285194432, 12992.935035324763, 13021.934890325489, 13478.393472293574, 12580.622581322576, 13036.283004434847, 13090.345482726016, 13226.669333266746, 13302.677283966354, 12476.500939962663, 12907.741845162856, 12928.676783080498, 13053.869461305645, 13288.136271142732, 12053.75892482163, 12585.685357866127, 12680.619581412573, 12686.422718632755, 13085.280309582684, 10081.294309398272, 10265.58937642486, 10524.210684198562, 10539.314944528582, 10670.330924117156, 9166.815769070028, 9247.953760231156, 9389.81220375575, 10070.19438444908, 10150.746231344276], "value": 7238.640381349388, "unit": "score"}], "name": "dromaeo_css", "value": 7124.920946786415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 111.0, 111.0, 112.0, 112.0, 111.0, 111.0, 112.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 117.0, 155.0, 120.0, 119.0, 120.0, 119.0, 144.0, 123.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 161.0, 161.0, 161.0, 162.0, 162.0, 160.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 119.0, 76.0, 75.0, 76.0, 76.0, 111.0, 75.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 113.0, 82.0, 82.0, 83.0, 107.0, 83.0, 82.0, 112.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 147.0, 145.0, 145.0, 147.0, 147.0, 147.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 51.0, 52.0, 50.0, 52.0, 52.0, 50.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 65.0, 64.0, 64.0, 64.0, 61.0, 65.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 79.0, 78.0, 81.0, 78.0, 77.0, 77.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 123.0, 135.0, 134.0, 132.0, 125.0, 124.0, 124.0, 178.0, 127.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 160.0, 170.0, 166.0, 159.0, 161.0, 167.0, 166.0, 160.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 67.0, 65.0, 64.0, 64.0, 70.0, 67.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1488.1, "alertThreshold": 2.0}]} 04:32:10 INFO - Return code: 0 04:32:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:32:10 INFO - # TBPL SUCCESS # 04:32:10 INFO - Running post-action listener: _resource_record_post_action 04:32:10 INFO - [mozharness: 2016-09-18 11:32:10.359536Z] Finished run-tests step (success) 04:32:10 INFO - Running post-run listener: _resource_record_post_run 04:32:10 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 3559424; Write bytes: 394678272; Read time: 820; Write time: 643956 04:32:10 INFO - TinderboxPrint: CPU usage
13.1% 04:32:10 INFO - TinderboxPrint: I/O read bytes / time
3,559,424 / 820 04:32:10 INFO - TinderboxPrint: I/O write bytes / time
394,678,272 / 643,956 04:32:10 INFO - TinderboxPrint: CPU idle
8,297.3 (86.9%) 04:32:10 INFO - TinderboxPrint: CPU user
1,194.6 (12.5%) 04:32:10 INFO - TinderboxPrint: Swap in / out
0 / 0 04:32:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103153664; Read time: 0; Write time: 114384 04:32:10 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 3555328; Write bytes: 289124352; Read time: 788; Write time: 528956 04:32:10 INFO - Running post-run listener: _upload_blobber_files 04:32:10 INFO - Blob upload gear active. 04:32:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:32:10 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:32:10 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:32:10 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:32:11 INFO - (blobuploader) - INFO - Open directory for files ... 04:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:12 INFO - (blobuploader) - INFO - Done attempting. 04:32:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:32:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:32:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:15 INFO - (blobuploader) - INFO - Done attempting. 04:32:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:32:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:32:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:16 INFO - (blobuploader) - INFO - Done attempting. 04:32:16 INFO - (blobuploader) - INFO - Iteration through files over. 04:32:16 INFO - Return code: 0 04:32:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:32:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:32:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b85d7829b1bb8ff5f6599e9d4424601e3746368c999382aa49c93896e5da45ea6c5db036b9c0c0d353471615d74f2a06b18ae214d04f9c14d97d4e5b0acee02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d5e726ff6d005604fa366996c45129a7e2eb5df8dbe9942ab4eb0f8011882c310b09a443ab738630952c7f92d826b40e28ea175dde5181ee5d781e85587fec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f89d156a6b391011a269c396320c70e45023aad8b307ea8a93e6dd90e467400a480ebfec50eaade24fb99aa0a3b78955091c9b181611701850cfe765f54e20ec"} 04:32:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:32:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:32:16 INFO - Contents: 04:32:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b85d7829b1bb8ff5f6599e9d4424601e3746368c999382aa49c93896e5da45ea6c5db036b9c0c0d353471615d74f2a06b18ae214d04f9c14d97d4e5b0acee02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d5e726ff6d005604fa366996c45129a7e2eb5df8dbe9942ab4eb0f8011882c310b09a443ab738630952c7f92d826b40e28ea175dde5181ee5d781e85587fec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f89d156a6b391011a269c396320c70e45023aad8b307ea8a93e6dd90e467400a480ebfec50eaade24fb99aa0a3b78955091c9b181611701850cfe765f54e20ec"} 04:32:16 INFO - Running post-run listener: copy_logs_to_upload_dir 04:32:16 INFO - Copying logs to upload dir... 04:32:16 INFO - mkdir: /builds/slave/test/build/upload/logs 04:32:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.188873 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-18 04:32:16.114279) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 04:32:16.117408) ========= 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=8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331 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/5b85d7829b1bb8ff5f6599e9d4424601e3746368c999382aa49c93896e5da45ea6c5db036b9c0c0d353471615d74f2a06b18ae214d04f9c14d97d4e5b0acee02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d5e726ff6d005604fa366996c45129a7e2eb5df8dbe9942ab4eb0f8011882c310b09a443ab738630952c7f92d826b40e28ea175dde5181ee5d781e85587fec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f89d156a6b391011a269c396320c70e45023aad8b307ea8a93e6dd90e467400a480ebfec50eaade24fb99aa0a3b78955091c9b181611701850cfe765f54e20ec"} 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.011290 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/5b85d7829b1bb8ff5f6599e9d4424601e3746368c999382aa49c93896e5da45ea6c5db036b9c0c0d353471615d74f2a06b18ae214d04f9c14d97d4e5b0acee02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d5e726ff6d005604fa366996c45129a7e2eb5df8dbe9942ab4eb0f8011882c310b09a443ab738630952c7f92d826b40e28ea175dde5181ee5d781e85587fec2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f89d156a6b391011a269c396320c70e45023aad8b307ea8a93e6dd90e467400a480ebfec50eaade24fb99aa0a3b78955091c9b181611701850cfe765f54e20ec"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 04:32:16.142274) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:32:16.142569) ========= 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=8783db0840cc980e80bb0bd400000236-1474197094.631594-1278520331 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004418 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 04:32:16.227875) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 04:32:16.228224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 04:32:16.228534) ========= ========= Total master_lag: 0.18 =========