builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1474384198.99 results: failure (2) buildid: 20160920033006 builduid: a5599cf195674b56a88a9123e36a5f6e revision: 14705f779a46da3dbbc41af098209911479cff01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:58.995143) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:58.995667) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:58.995955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:59.010494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:59.010793) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 08:09:59-- 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% 70.0M=0s 2016-09-20 08:09:59 (70.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108779 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:59.133533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:59.133898) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015342 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 08:09:59.186744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 08:09:59.187456) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 14705f779a46da3dbbc41af098209911479cff01 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 14705f779a46da3dbbc41af098209911479cff01 --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=17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 08:09:59,273 truncating revision to first 12 chars 2016-09-20 08:09:59,273 Setting DEBUG logging. 2016-09-20 08:09:59,273 attempt 1/10 2016-09-20 08:09:59,273 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/14705f779a46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 08:10:00,516 unpacking tar archive at: fx-team-14705f779a46/testing/mozharness/ program finished with exit code 0 elapsedTime=1.580934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 08:10:00.786993) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 08:10:00.787323) ========= script_repo_revision: 14705f779a46da3dbbc41af098209911479cff01 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 08:10:00.787747) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 08:10:00.788036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 08:10:00.799186) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 36 secs) (at 2016-09-20 08:10:00.799477) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:10:00 INFO - MultiFileLogger online at 20160920 08:10:00 in /builds/slave/test 08:10:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:10:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:10:00 INFO - {'append_to_log': False, 08:10:00 INFO - 'base_work_dir': '/builds/slave/test', 08:10:00 INFO - 'blob_upload_branch': 'Fx-Team', 08:10:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:10:00 INFO - 'branch': 'Fx-Team', 08:10:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:10:00 INFO - 'config_files': ('talos/linux_config.py',), 08:10:00 INFO - 'default_actions': ('clobber', 08:10:00 INFO - 'read-buildbot-config', 08:10:00 INFO - 'download-and-extract', 08:10:00 INFO - 'populate-webroot', 08:10:00 INFO - 'create-virtualenv', 08:10:00 INFO - 'install', 08:10:00 INFO - 'run-tests'), 08:10:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:10:00 INFO - 'download_minidump_stackwalk': True, 08:10:00 INFO - 'download_symbols': 'ondemand', 08:10:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:10:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:10:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:10:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:10:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:10:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:10:00 INFO - 'installer_path': 'installer.exe', 08:10:00 INFO - 'log_level': 'info', 08:10:00 INFO - 'log_name': 'talos', 08:10:00 INFO - 'log_to_console': True, 08:10:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:10:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:10:00 INFO - 'opt_config_files': (), 08:10:00 INFO - 'pip_index': False, 08:10:00 INFO - 'sps_profile': False, 08:10:00 INFO - 'sps_profile_interval': 0, 08:10:00 INFO - 'suite': 'dromaeojs', 08:10:00 INFO - 'system_bits': '32', 08:10:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:10:00 INFO - 'title': 'talos-linux64-ix-009', 08:10:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:10:00 INFO - 'use_talos_json': True, 08:10:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:10:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:10:00 INFO - 'work_dir': 'build'} 08:10:00 INFO - [mozharness: 2016-09-20 15:10:00.979354Z] Running clobber step. 08:10:00 INFO - Running pre-action listener: _resource_record_pre_action 08:10:00 INFO - Running main action method: clobber 08:10:00 INFO - rmtree: /builds/slave/test/build 08:10:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:10:01 INFO - Running post-action listener: _resource_record_post_action 08:10:01 INFO - [mozharness: 2016-09-20 15:10:01.436676Z] Finished clobber step (success) 08:10:01 INFO - [mozharness: 2016-09-20 15:10:01.436777Z] Running read-buildbot-config step. 08:10:01 INFO - Running pre-action listener: _resource_record_pre_action 08:10:01 INFO - Running main action method: read_buildbot_config 08:10:01 INFO - Using buildbot properties: 08:10:01 INFO - { 08:10:01 INFO - "project": "", 08:10:01 INFO - "product": "firefox", 08:10:01 INFO - "script_repo_revision": "production", 08:10:01 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:10:01 INFO - "repository": "", 08:10:01 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:10:01 INFO - "buildid": "20160920033006", 08:10:01 INFO - "pgo_build": "True", 08:10:01 INFO - "basedir": "/builds/slave/test", 08:10:01 INFO - "buildnumber": 1583, 08:10:01 INFO - "slavename": "talos-linux64-ix-009", 08:10:01 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:10:01 INFO - "platform": "ubuntu64_hw", 08:10:01 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:10:01 INFO - "branch": "fx-team", 08:10:01 INFO - "repo_path": "integration/fx-team", 08:10:01 INFO - "revision": "14705f779a46da3dbbc41af098209911479cff01", 08:10:01 INFO - "stage_platform": "linux64-pgo", 08:10:01 INFO - "builduid": "a5599cf195674b56a88a9123e36a5f6e", 08:10:01 INFO - "slavebuilddir": "test" 08:10:01 INFO - } 08:10:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 08:10:01 INFO - Running post-action listener: _resource_record_post_action 08:10:01 INFO - [mozharness: 2016-09-20 15:10:01.439246Z] Finished read-buildbot-config step (success) 08:10:01 INFO - [mozharness: 2016-09-20 15:10:01.439336Z] Running download-and-extract step. 08:10:01 INFO - Running pre-action listener: _resource_record_pre_action 08:10:01 INFO - Running main action method: download_and_extract 08:10:01 INFO - mkdir: /builds/slave/test/build/tests 08:10: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')]} 08:10:01 INFO - trying https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 08:10:01 INFO - Downloading https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 08:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:10:02 INFO - Downloaded 1361 bytes. 08:10:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 08:10:02 INFO - Using the following test package requirements: 08:10:02 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:10:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:10:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'jsshell-linux-x86_64.zip'], 08:10:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:10:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:10:02 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:10:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:10:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:02 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:10:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:10:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 08:10:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:10:02 INFO - Fetch https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:10:02 INFO - Expected file size: 21941042 08:10:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:10:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 08:10:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:10:06 INFO - Fetch https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:10:07 INFO - Expected file size: 21941042 08:10:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:10:12 INFO - Fetch https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:10:12 INFO - Expected file size: 14414059 08:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:13 INFO - trying https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:10:13 INFO - Downloading https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:10:14 INFO - Downloaded 59557485 bytes. 08:10:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:10:14 INFO - mkdir: /builds/slave/test/properties 08:10:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:10:14 INFO - Writing to file /builds/slave/test/properties/build_url 08:10:14 INFO - Contents: 08:10:14 INFO - build_url:https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:10:15 INFO - Running post-action listener: _resource_record_post_action 08:10:15 INFO - Running post-action listener: set_extra_try_arguments 08:10:15 INFO - [mozharness: 2016-09-20 15:10:15.212882Z] Finished download-and-extract step (success) 08:10:15 INFO - [mozharness: 2016-09-20 15:10:15.213111Z] Running populate-webroot step. 08:10:15 INFO - Running pre-action listener: _resource_record_pre_action 08:10:15 INFO - Running main action method: populate_webroot 08:10:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:15 INFO - '--tppagecycles', 08:10:15 INFO - '7'], 08:10:15 INFO - 'tests': ['tsvgm']}}, 08:10:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['damp', 'tps']}, 08:10:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'tests': ['damp', 'tps']}, 08:10:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['dromaeo_dom']}, 08:10:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['basic_compositor_video']}, 08:10:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:10:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['a11yr', 08:10:15 INFO - 'ts_paint', 08:10:15 INFO - 'tpaint', 08:10:15 INFO - 'sessionrestore', 08:10:15 INFO - 'sessionrestore_no_auto_restore', 08:10:15 INFO - 'tabpaint']}, 08:10:15 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:15 INFO - 'ts_paint', 08:10:15 INFO - 'tpaint', 08:10:15 INFO - 'sessionrestore', 08:10:15 INFO - 'sessionrestore_no_auto_restore', 08:10:15 INFO - 'tabpaint']}, 08:10:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:15 INFO - 'ts_paint', 08:10:15 INFO - 'tpaint', 08:10:15 INFO - 'sessionrestore', 08:10:15 INFO - 'sessionrestore_no_auto_restore', 08:10:15 INFO - 'tabpaint']}, 08:10:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:15 INFO - 'ts_paint', 08:10:15 INFO - 'tpaint', 08:10:15 INFO - 'sessionrestore', 08:10:15 INFO - 'sessionrestore_no_auto_restore', 08:10:15 INFO - 'tabpaint']}, 08:10:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['a11yr', 08:10:15 INFO - 'ts_paint', 08:10:15 INFO - 'tpaint', 08:10:15 INFO - 'sessionrestore', 08:10:15 INFO - 'sessionrestore_no_auto_restore', 08:10:15 INFO - 'tabpaint']}, 08:10:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['a11yr', 08:10:15 INFO - 'ts_paint', 08:10:15 INFO - 'tpaint', 08:10:15 INFO - 'sessionrestore', 08:10:15 INFO - 'sessionrestore_no_auto_restore', 08:10:15 INFO - 'tabpaint']}, 08:10:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['tsvgx', 08:10:15 INFO - 'tsvgr_opacity', 08:10:15 INFO - 'tart', 08:10:15 INFO - 'tscrollx', 08:10:15 INFO - 'cart']}, 08:10:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:15 INFO - 'tsvgr_opacity', 08:10:15 INFO - 'tart', 08:10:15 INFO - 'tscrollx', 08:10:15 INFO - 'cart']}, 08:10:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'talos_options': ['--disable-e10s'], 08:10:15 INFO - 'tests': ['tp5o']}, 08:10:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'tests': ['tp5o']}, 08:10:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'talos_options': ['--disable-e10s', 08:10:15 INFO - '--xperf_path', 08:10:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:15 INFO - 'tests': ['tp5n']}, 08:10:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:15 INFO - 'talos_options': ['--xperf_path', 08:10:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:15 INFO - 'tests': ['tp5n']}}, 08:10:15 INFO - 'talos.zip': {'path': '', 08:10:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:15 INFO - Running post-action listener: _resource_record_post_action 08:10:15 INFO - [mozharness: 2016-09-20 15:10:15.231967Z] Finished populate-webroot step (success) 08:10:15 INFO - [mozharness: 2016-09-20 15:10:15.232057Z] Running create-virtualenv step. 08:10:15 INFO - Running pre-action listener: _resource_record_pre_action 08:10:15 INFO - Running main action method: create_virtualenv 08:10:15 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:15 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 08:10:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:15 INFO - Using real prefix '/usr' 08:10:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:15 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:17 INFO - Installing pip.................done. 08:10:17 INFO - Return code: 0 08:10:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:10:17 INFO - Using env: {'DISPLAY': ':0', 08:10:17 INFO - 'HOME': '/home/cltbld', 08:10:17 INFO - 'LANG': 'en_US.UTF-8', 08:10:17 INFO - 'LANGUAGE': 'en_US:en', 08:10:17 INFO - 'LOGNAME': 'cltbld', 08:10:17 INFO - 'MAIL': '/var/mail/cltbld', 08:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:17 INFO - 'MOZ_NO_REMOTE': '1', 08:10:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:17 INFO - 'NO_EM_RESTART': '1', 08:10:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:17 INFO - 'PWD': '/builds/slave/test', 08:10:17 INFO - 'SHELL': '/bin/bash', 08:10:17 INFO - 'SHLVL': '1', 08:10:17 INFO - 'TERM': 'linux', 08:10:17 INFO - 'TMOUT': '86400', 08:10:17 INFO - 'USER': 'cltbld', 08:10:17 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:17 INFO - '_': '/tools/buildbot/bin/python'} 08:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:18 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 08:10:18 INFO - Cleaning up... 08:10:18 INFO - Return code: 0 08:10:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:18 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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:18 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 08:10:18 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 08:10:18 INFO - Using env: {'DISPLAY': ':0', 08:10:18 INFO - 'HOME': '/home/cltbld', 08:10:18 INFO - 'LANG': 'en_US.UTF-8', 08:10:18 INFO - 'LANGUAGE': 'en_US:en', 08:10:18 INFO - 'LOGNAME': 'cltbld', 08:10:18 INFO - 'MAIL': '/var/mail/cltbld', 08:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:18 INFO - 'MOZ_NO_REMOTE': '1', 08:10:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:18 INFO - 'NO_EM_RESTART': '1', 08:10:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:18 INFO - 'PWD': '/builds/slave/test', 08:10:18 INFO - 'SHELL': '/bin/bash', 08:10:18 INFO - 'SHLVL': '1', 08:10:18 INFO - 'TERM': 'linux', 08:10:18 INFO - 'TMOUT': '86400', 08:10:18 INFO - 'USER': 'cltbld', 08:10:18 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:18 INFO - '_': '/tools/buildbot/bin/python'} 08:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:18 INFO - Downloading/unpacking psutil>=3.1.1 08:10:18 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 08:10:18 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 08:10:18 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 08:10:18 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 08:10:18 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 08:10:18 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 08:10:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:20 INFO - Installing collected packages: psutil 08:10:20 INFO - Running setup.py install for psutil 08:10:20 INFO - building 'psutil._psutil_linux' extension 08:10:20 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 08:10:20 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 08:10:20 INFO - building 'psutil._psutil_posix' extension 08:10:20 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 08:10:20 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 08:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:20 INFO - Successfully installed psutil 08:10:20 INFO - Cleaning up... 08:10:20 INFO - Return code: 0 08:10:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:20 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')]} 08:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:20 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')]} 08:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:20 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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:20 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 08:10:20 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 08:10:20 INFO - Using env: {'DISPLAY': ':0', 08:10:20 INFO - 'HOME': '/home/cltbld', 08:10:20 INFO - 'LANG': 'en_US.UTF-8', 08:10:20 INFO - 'LANGUAGE': 'en_US:en', 08:10:20 INFO - 'LOGNAME': 'cltbld', 08:10:20 INFO - 'MAIL': '/var/mail/cltbld', 08:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:20 INFO - 'MOZ_NO_REMOTE': '1', 08:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:20 INFO - 'NO_EM_RESTART': '1', 08:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:20 INFO - 'PWD': '/builds/slave/test', 08:10:20 INFO - 'SHELL': '/bin/bash', 08:10:20 INFO - 'SHLVL': '1', 08:10:20 INFO - 'TERM': 'linux', 08:10:20 INFO - 'TMOUT': '86400', 08:10:20 INFO - 'USER': 'cltbld', 08:10:20 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:20 INFO - '_': '/tools/buildbot/bin/python'} 08:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10:21 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 08:10:21 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 08:10:21 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 08:10:21 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 08:10:21 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 08:10:21 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 08:10:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:23 INFO - Installing collected packages: mozsystemmonitor 08:10:23 INFO - Running setup.py install for mozsystemmonitor 08:10:23 INFO - Successfully installed mozsystemmonitor 08:10:23 INFO - Cleaning up... 08:10:23 INFO - Return code: 0 08:10:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:10:23 INFO - Using env: {'DISPLAY': ':0', 08:10:23 INFO - 'HOME': '/home/cltbld', 08:10:23 INFO - 'LANG': 'en_US.UTF-8', 08:10:23 INFO - 'LANGUAGE': 'en_US:en', 08:10:23 INFO - 'LOGNAME': 'cltbld', 08:10:23 INFO - 'MAIL': '/var/mail/cltbld', 08:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:23 INFO - 'MOZ_NO_REMOTE': '1', 08:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:23 INFO - 'NO_EM_RESTART': '1', 08:10:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:23 INFO - 'PWD': '/builds/slave/test', 08:10:23 INFO - 'SHELL': '/bin/bash', 08:10:23 INFO - 'SHLVL': '1', 08:10:23 INFO - 'TERM': 'linux', 08:10:23 INFO - 'TMOUT': '86400', 08:10:23 INFO - 'USER': 'cltbld', 08:10:23 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:23 INFO - '_': '/tools/buildbot/bin/python'} 08:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:23 INFO - Downloading/unpacking jsonschema==2.5.1 08:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:10:25 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 08:10:25 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 08:10:25 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 08:10:25 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 08:10:25 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 08:10:25 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 08:10:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:25 INFO - warning: no files found matching '*.txt' 08:10:25 INFO - no previously-included directories found matching 'build' 08:10:25 INFO - no previously-included directories found matching 'dist' 08:10:25 INFO - no previously-included directories found matching '.git*' 08:10:25 INFO - Installing collected packages: jsonschema, functools32 08:10:25 INFO - Running setup.py install for functools32 08:10:25 INFO - warning: no files found matching '*.txt' 08:10:25 INFO - no previously-included directories found matching 'build' 08:10:25 INFO - no previously-included directories found matching 'dist' 08:10:25 INFO - no previously-included directories found matching '.git*' 08:10:25 INFO - Successfully installed jsonschema functools32 08:10:25 INFO - Cleaning up... 08:10:25 INFO - Return code: 0 08:10:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:10:25 INFO - Using env: {'DISPLAY': ':0', 08:10:25 INFO - 'HOME': '/home/cltbld', 08:10:25 INFO - 'LANG': 'en_US.UTF-8', 08:10:25 INFO - 'LANGUAGE': 'en_US:en', 08:10:25 INFO - 'LOGNAME': 'cltbld', 08:10:25 INFO - 'MAIL': '/var/mail/cltbld', 08:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:25 INFO - 'MOZ_NO_REMOTE': '1', 08:10:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:25 INFO - 'NO_EM_RESTART': '1', 08:10:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:25 INFO - 'PWD': '/builds/slave/test', 08:10:25 INFO - 'SHELL': '/bin/bash', 08:10:25 INFO - 'SHLVL': '1', 08:10:25 INFO - 'TERM': 'linux', 08:10:25 INFO - 'TMOUT': '86400', 08:10:25 INFO - 'USER': 'cltbld', 08:10:25 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:25 INFO - '_': '/tools/buildbot/bin/python'} 08:10:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:10:25 INFO - Cleaning up... 08:10:25 INFO - Return code: 0 08:10:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:10:25 INFO - Using env: {'DISPLAY': ':0', 08:10:25 INFO - 'HOME': '/home/cltbld', 08:10:25 INFO - 'LANG': 'en_US.UTF-8', 08:10:25 INFO - 'LANGUAGE': 'en_US:en', 08:10:25 INFO - 'LOGNAME': 'cltbld', 08:10:25 INFO - 'MAIL': '/var/mail/cltbld', 08:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:25 INFO - 'MOZ_NO_REMOTE': '1', 08:10:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:25 INFO - 'NO_EM_RESTART': '1', 08:10:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:25 INFO - 'PWD': '/builds/slave/test', 08:10:25 INFO - 'SHELL': '/bin/bash', 08:10:25 INFO - 'SHLVL': '1', 08:10:25 INFO - 'TERM': 'linux', 08:10:25 INFO - 'TMOUT': '86400', 08:10:25 INFO - 'USER': 'cltbld', 08:10:25 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:25 INFO - '_': '/tools/buildbot/bin/python'} 08:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:28 INFO - Downloading docopt-0.6.1.tar.gz 08:10:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:28 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:28 INFO - Running setup.py install for blobuploader 08:10:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:28 INFO - Running setup.py install for requests 08:10:29 INFO - Running setup.py install for docopt 08:10:29 INFO - Successfully installed blobuploader requests docopt 08:10:29 INFO - Cleaning up... 08:10:29 INFO - Return code: 0 08:10:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:29 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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:29 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 08:10:29 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 08:10:29 INFO - Using env: {'DISPLAY': ':0', 08:10:29 INFO - 'HOME': '/home/cltbld', 08:10:29 INFO - 'LANG': 'en_US.UTF-8', 08:10:29 INFO - 'LANGUAGE': 'en_US:en', 08:10:29 INFO - 'LOGNAME': 'cltbld', 08:10:29 INFO - 'MAIL': '/var/mail/cltbld', 08:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:29 INFO - 'MOZ_NO_REMOTE': '1', 08:10:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:29 INFO - 'NO_EM_RESTART': '1', 08:10:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:29 INFO - 'PWD': '/builds/slave/test', 08:10:29 INFO - 'SHELL': '/bin/bash', 08:10:29 INFO - 'SHLVL': '1', 08:10:29 INFO - 'TERM': 'linux', 08:10:29 INFO - 'TMOUT': '86400', 08:10:29 INFO - 'USER': 'cltbld', 08:10:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:29 INFO - '_': '/tools/buildbot/bin/python'} 08:10:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:29 INFO - Running setup.py (path:/tmp/pip-lGLrwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:29 INFO - Running setup.py (path:/tmp/pip-2jqq42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:29 INFO - Running setup.py (path:/tmp/pip-439uuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:29 INFO - Running setup.py (path:/tmp/pip-chWSwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:29 INFO - Running setup.py (path:/tmp/pip-HJhekA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:29 INFO - Running setup.py (path:/tmp/pip-1ic_9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:30 INFO - Running setup.py (path:/tmp/pip-yt7Tui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:30 INFO - Running setup.py (path:/tmp/pip-vdOrgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:30 INFO - Running setup.py (path:/tmp/pip-LHe_CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:30 INFO - Running setup.py (path:/tmp/pip-JU_UWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:30 INFO - Running setup.py (path:/tmp/pip-D4FUmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:30 INFO - Running setup.py (path:/tmp/pip-n3TlVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:30 INFO - Running setup.py (path:/tmp/pip-6bBnBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:30 INFO - Running setup.py (path:/tmp/pip-L2jjBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:30 INFO - Running setup.py (path:/tmp/pip-GrfnGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:30 INFO - Running setup.py (path:/tmp/pip-pjJVPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:30 INFO - Running setup.py (path:/tmp/pip-qrZVaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:10:30 INFO - Running setup.py install for manifestparser 08:10:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:10:30 INFO - Running setup.py install for mozcrash 08:10:31 INFO - Running setup.py install for mozdebug 08:10:31 INFO - Running setup.py install for mozdevice 08:10:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozfile 08:10:31 INFO - Running setup.py install for mozhttpd 08:10:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozinfo 08:10:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozInstall 08:10:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozleak 08:10:31 INFO - Running setup.py install for mozlog 08:10:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for moznetwork 08:10:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozprocess 08:10:32 INFO - Running setup.py install for mozprofile 08:10:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Running setup.py install for mozrunner 08:10:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Running setup.py install for mozscreenshot 08:10:32 INFO - Running setup.py install for moztest 08:10:32 INFO - Running setup.py install for mozversion 08:10:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:32 INFO - Cleaning up... 08:10:32 INFO - Return code: 0 08:10:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:32 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')]} 08:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:32 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')]} 08:10:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:32 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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:32 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 08:10:32 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 08:10:32 INFO - Using env: {'DISPLAY': ':0', 08:10:32 INFO - 'HOME': '/home/cltbld', 08:10:32 INFO - 'LANG': 'en_US.UTF-8', 08:10:32 INFO - 'LANGUAGE': 'en_US:en', 08:10:32 INFO - 'LOGNAME': 'cltbld', 08:10:32 INFO - 'MAIL': '/var/mail/cltbld', 08:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:32 INFO - 'MOZ_NO_REMOTE': '1', 08:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:32 INFO - 'NO_EM_RESTART': '1', 08:10:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:32 INFO - 'PWD': '/builds/slave/test', 08:10:32 INFO - 'SHELL': '/bin/bash', 08:10:32 INFO - 'SHLVL': '1', 08:10:32 INFO - 'TERM': 'linux', 08:10:32 INFO - 'TMOUT': '86400', 08:10:32 INFO - 'USER': 'cltbld', 08:10:32 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:32 INFO - '_': '/tools/buildbot/bin/python'} 08:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:32 INFO - Running setup.py (path:/tmp/pip-rIMgjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:32 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)) 08:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:32 INFO - Running setup.py (path:/tmp/pip-WWr9Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:33 INFO - Running setup.py (path:/tmp/pip-yrJoM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:33 INFO - Running setup.py (path:/tmp/pip-w8sV4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:33 INFO - Running setup.py (path:/tmp/pip-4piqK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:33 INFO - Running setup.py (path:/tmp/pip-wZFp0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:33 INFO - Running setup.py (path:/tmp/pip-nisb_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:33 INFO - Running setup.py (path:/tmp/pip-hzGfru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:33 INFO - Running setup.py (path:/tmp/pip-b6EPWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:33 INFO - Running setup.py (path:/tmp/pip-eBhx65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:33 INFO - Running setup.py (path:/tmp/pip-9XvU7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:33 INFO - Running setup.py (path:/tmp/pip-OBxorm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:33 INFO - Running setup.py (path:/tmp/pip-FtzXpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:33 INFO - Running setup.py (path:/tmp/pip-I1gyls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:33 INFO - Running setup.py (path:/tmp/pip-3YwUDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:33 INFO - Running setup.py (path:/tmp/pip-sgGrDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:34 INFO - Running setup.py (path:/tmp/pip-BMjO3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:34 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)) 08:10:34 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)) 08:10:34 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)) 08:10:34 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)) 08:10:34 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)) 08:10:34 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)) 08:10:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:36 INFO - Downloading blessings-1.6.tar.gz 08:10:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:36 INFO - Installing collected packages: blessings 08:10:36 INFO - Running setup.py install for blessings 08:10:36 INFO - Successfully installed blessings 08:10:36 INFO - Cleaning up... 08:10:36 INFO - Return code: 0 08:10:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:36 INFO - Reading from file tmpfile_stdout 08:10:36 INFO - Current package versions: 08:10:36 INFO - argparse == 1.2.1 08:10:36 INFO - blessings == 1.6 08:10:36 INFO - blobuploader == 1.2.4 08:10:36 INFO - docopt == 0.6.1 08:10:36 INFO - functools32 == 3.2.3-2 08:10:36 INFO - jsonschema == 2.5.1 08:10:36 INFO - manifestparser == 1.1 08:10:36 INFO - mozInstall == 1.12 08:10:36 INFO - mozcrash == 0.17 08:10:36 INFO - mozdebug == 0.1 08:10:36 INFO - mozdevice == 0.48 08:10:36 INFO - mozfile == 1.2 08:10:36 INFO - mozhttpd == 0.7 08:10:36 INFO - mozinfo == 0.9 08:10:36 INFO - mozleak == 0.1 08:10:36 INFO - mozlog == 3.4 08:10:36 INFO - moznetwork == 0.27 08:10:36 INFO - mozprocess == 0.23 08:10:36 INFO - mozprofile == 0.28 08:10:36 INFO - mozrunner == 6.12 08:10:36 INFO - mozscreenshot == 0.1 08:10:36 INFO - mozsystemmonitor == 0.3 08:10:36 INFO - moztest == 0.7 08:10:36 INFO - mozversion == 1.4 08:10:36 INFO - psutil == 3.1.1 08:10:36 INFO - requests == 1.2.3 08:10:36 INFO - wsgiref == 0.1.2 08:10:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:36 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 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:36 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 08:10:36 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 08:10:36 INFO - Using env: {'DISPLAY': ':0', 08:10:36 INFO - 'HOME': '/home/cltbld', 08:10:36 INFO - 'LANG': 'en_US.UTF-8', 08:10:36 INFO - 'LANGUAGE': 'en_US:en', 08:10:36 INFO - 'LOGNAME': 'cltbld', 08:10:36 INFO - 'MAIL': '/var/mail/cltbld', 08:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:36 INFO - 'MOZ_NO_REMOTE': '1', 08:10:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:36 INFO - 'NO_EM_RESTART': '1', 08:10:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:36 INFO - 'PWD': '/builds/slave/test', 08:10:36 INFO - 'SHELL': '/bin/bash', 08:10:36 INFO - 'SHLVL': '1', 08:10:36 INFO - 'TERM': 'linux', 08:10:36 INFO - 'TMOUT': '86400', 08:10:36 INFO - 'USER': 'cltbld', 08:10:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:36 INFO - '_': '/tools/buildbot/bin/python'} 08:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 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)) 08:10:36 INFO - Cleaning up... 08:10:36 INFO - Return code: 0 08:10:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7aa5bbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f17b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, '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': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', '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 08:10:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:10:36 INFO - Using env: {'DISPLAY': ':0', 08:10:36 INFO - 'HOME': '/home/cltbld', 08:10:36 INFO - 'LANG': 'en_US.UTF-8', 08:10:36 INFO - 'LANGUAGE': 'en_US:en', 08:10:36 INFO - 'LOGNAME': 'cltbld', 08:10:36 INFO - 'MAIL': '/var/mail/cltbld', 08:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:36 INFO - 'MOZ_NO_REMOTE': '1', 08:10:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:36 INFO - 'NO_EM_RESTART': '1', 08:10:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:36 INFO - 'PWD': '/builds/slave/test', 08:10:36 INFO - 'SHELL': '/bin/bash', 08:10:36 INFO - 'SHLVL': '1', 08:10:36 INFO - 'TERM': 'linux', 08:10:36 INFO - 'TMOUT': '86400', 08:10:36 INFO - 'USER': 'cltbld', 08:10:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:36 INFO - '_': '/tools/buildbot/bin/python'} 08:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:10:36 INFO - Cleaning up... 08:10:36 INFO - Return code: 0 08:10:36 INFO - Running post-action listener: _resource_record_post_action 08:10:36 INFO - Running post-action listener: _start_resource_monitoring 08:10:36 INFO - Starting resource monitoring. 08:10:36 INFO - [mozharness: 2016-09-20 15:10:36.960843Z] Finished create-virtualenv step (success) 08:10:36 INFO - [mozharness: 2016-09-20 15:10:36.961357Z] Running install step. 08:10:36 INFO - Running pre-action listener: _resource_record_pre_action 08:10:36 INFO - Running main action method: install 08:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:37 INFO - Reading from file tmpfile_stdout 08:10:37 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:37 INFO - Reading from file tmpfile_stdout 08:10:37 INFO - Output received: 08:10:37 INFO - Usage: mozinstall [options] installer 08:10:37 INFO - Options: 08:10:37 INFO - -h, --help show this help message and exit 08:10:37 INFO - -d DEST, --destination=DEST 08:10:37 INFO - Directory to install application into. [default: 08:10:37 INFO - "/builds/slave/test"] 08:10:37 INFO - --app=APP Application being installed. [default: firefox] 08:10:37 INFO - mkdir: /builds/slave/test/build/application 08:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:10:54 INFO - Reading from file tmpfile_stdout 08:10:54 INFO - Output received: 08:10:54 INFO - /builds/slave/test/build/application/firefox/firefox 08:10:54 INFO - Running post-action listener: _resource_record_post_action 08:10:54 INFO - [mozharness: 2016-09-20 15:10:54.238516Z] Finished install step (success) 08:10:54 INFO - [mozharness: 2016-09-20 15:10:54.238760Z] Running run-tests step. 08:10:54 INFO - Running pre-action listener: _resource_record_pre_action 08:10:54 INFO - Running main action method: run_tests 08:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:54 INFO - Python 2.7.3 08:10:54 INFO - Return code: 0 08:10:54 INFO - grabbing minidump binary from tooltool 08:10: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')]} 08:10:54 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 0x2680790>, '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 0x2681690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2681b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:54 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 08:10:54 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 08:10:54 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 08:10:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:54 INFO - Return code: 0 08:10:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:10:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:10:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 08:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 08:10:54 INFO - Using env: {'DISPLAY': ':0', 08:10:54 INFO - 'HOME': '/home/cltbld', 08:10:54 INFO - 'LANG': 'en_US.UTF-8', 08:10:54 INFO - 'LANGUAGE': 'en_US:en', 08:10:54 INFO - 'LOGNAME': 'cltbld', 08:10:54 INFO - 'MAIL': '/var/mail/cltbld', 08:10:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:54 INFO - 'MOZ_NO_REMOTE': '1', 08:10:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:10:54 INFO - 'NO_EM_RESTART': '1', 08:10:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:54 INFO - 'PWD': '/builds/slave/test', 08:10:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:54 INFO - 'SHELL': '/bin/bash', 08:10:54 INFO - 'SHLVL': '1', 08:10:54 INFO - 'TERM': 'linux', 08:10:54 INFO - 'TMOUT': '86400', 08:10:54 INFO - 'USER': 'cltbld', 08:10:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864', 08:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:54 INFO - '_': '/tools/buildbot/bin/python'} 08:10:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 08:10:54 INFO - mozversion application_buildid: 20160920033006 08:10:54 INFO - mozversion application_changeset: 14705f779a46da3dbbc41af098209911479cff01 08:10:54 INFO - mozversion application_display_name: Nightly 08:10:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:54 INFO - mozversion application_name: Firefox 08:10:54 INFO - mozversion application_remotingname: firefox 08:10:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:10:54 INFO - mozversion application_vendor: Mozilla 08:10:54 INFO - mozversion application_version: 52.0a1 08:10:54 INFO - mozversion platform_buildid: 20160920033006 08:10:54 INFO - mozversion platform_changeset: 14705f779a46da3dbbc41af098209911479cff01 08:10:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:10:54 INFO - mozversion platform_version: 52.0a1 08:10:54 INFO - using testdate: 1474384254 08:10:54 INFO - actual date: 1474384254 08:10:54 INFO - starting webserver on 'localhost:56085' 08:10:54 INFO - SUITE-START | Running 2 tests 08:10:54 INFO - TEST-START | dromaeo_css 08:10:54 INFO - operating with platform_type : linux_ 08:10:54 INFO - Initialising browser for dromaeo_css test... 08:10:54 INFO - TEST-INFO | started process 6780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZU9fp_/profile http://localhost:56085/getInfo.html) 08:10:58 INFO - PROCESS | 6780 | __metrics Screen width/height:1600/1200 08:10:58 INFO - PROCESS | 6780 | colorDepth:24 08:10:58 INFO - PROCESS | 6780 | Browser inner width/height: 1024/697 08:10:58 INFO - PROCESS | 6780 | __metrics 08:10:59 INFO - PROCESS | 6780 | 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" 08:10:59 INFO - PROCESS | 6780 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:59 INFO - PROCESS | 6780 | console.error: PushService: 08:10:59 INFO - PROCESS | 6780 | stateChangeProcessEnqueue: Error transitioning state 08:10:59 INFO - PROCESS | 6780 | UnknownError 08:11:00 INFO - TEST-INFO | 6780: exit 0 08:11:00 INFO - Browser initialized. 08:11:00 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:00 INFO - TEST-INFO | started process 6865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZU9fp_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:12 INFO - PROCESS | 6865 | RSS: Main: 173404160 08:11:12 INFO - PROCESS | 6865 | 08:11:22 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:11:47 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 08:12:03 INFO - PROCESS | 6865 | 1474384323953 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] 08:12:13 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.08338 seconds. 08:12:17 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:12:24 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:12:41 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:12:48 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 08:12:56 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 08:12:58 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 08:13:42 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:13:52 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:14:00 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.10007 seconds. 08:14:08 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:14:11 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:14:13 INFO - PROCESS | 6865 | Cycle 1(1): loaded http://localhost:56085/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56085/tests/dromaeo/cssquery-ext.html) 08:14:14 INFO - PROCESS | 6865 | RSS: Main: 291102720 08:14:14 INFO - PROCESS | 6865 | 08:14:25 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:14:27 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:14:37 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:14:43 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:14:59 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:15:04 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 08:15:09 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:15:33 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:15:48 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 08:16:04 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:16:24 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:16:28 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:16:34 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:16:36 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:16:38 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:16:56 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:16:58 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:17:08 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:17:16 INFO - PROCESS | 6865 | Cycle 1(1): loaded http://localhost:56085/tests/dromaeo/cssquery-ext.html (next: http://localhost:56085/tests/dromaeo/cssquery-jquery.html) 08:17:17 INFO - PROCESS | 6865 | RSS: Main: 279965696 08:17:17 INFO - PROCESS | 6865 | 08:17:20 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:17:40 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:17:42 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:17:50 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:17:54 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:00 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:24 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:34 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:18:58 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:19:03 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:19:08 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:19:09 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:19:13 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:19:19 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:19:27 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:19:29 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:19:35 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:19:43 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:19:52 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:20:07 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:20:19 INFO - PROCESS | 6865 | Cycle 1(1): loaded http://localhost:56085/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56085/tests/dromaeo/cssquery-mootools.html) 08:20:20 INFO - PROCESS | 6865 | RSS: Main: 215851008 08:20:20 INFO - PROCESS | 6865 | 08:20:23 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:20:30 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:32 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:37 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:38 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:20:40 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:21:19 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:23 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:31 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:21:51 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:22:07 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:22:09 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:22:31 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:22:33 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:22:41 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:06 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:23:22 INFO - PROCESS | 6865 | Cycle 1(1): loaded http://localhost:56085/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56085/tests/dromaeo/cssquery-prototype.html) 08:23:22 INFO - PROCESS | 6865 | RSS: Main: 271355904 08:23:22 INFO - PROCESS | 6865 | 08:23:26 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 08:23:27 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 08:23:29 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:23:31 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:23:36 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.05009 seconds. 08:23:48 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 08:23:49 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 08:24:00 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:24:05 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:49 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:24:58 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:25:02 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:04 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:25:06 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:25:08 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 08:25:11 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:12 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:25:24 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:25:27 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:25:35 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 08:25:37 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:25:50 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:25:57 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:26:16 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:26:24 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:26:25 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 08:26:25 INFO - PROCESS | 6865 | Cycle 1(1): loaded http://localhost:56085/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56085/tests/dromaeo/cssquery-yui.html) 08:26:26 INFO - PROCESS | 6865 | RSS: Main: 215273472 08:26:26 INFO - PROCESS | 6865 | 08:26:31 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 08:26:34 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:26:45 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:26:49 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:26:56 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:27:01 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:27:07 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:27:19 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:27:47 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:27:57 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:28:13 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:28:30 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:28:37 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:28:40 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:28:52 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:28:56 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:29:08 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 08:29:15 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:29:27 INFO - PROCESS | 6865 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:29:29 INFO - PROCESS | 6865 | Cycle 1(1): loaded http://localhost:56085/tests/dromaeo/cssquery-yui.html (next: http://localhost:56085/tests/dromaeo/cssquery-dojo.html) 08:29:29 INFO - PROCESS | 6865 | RSS: Main: 305905664 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | __start_tp_report 08:29:29 INFO - PROCESS | 6865 | _x_x_mozilla_page_load 08:29:29 INFO - PROCESS | 6865 | _x_x_mozilla_page_load_details 08:29:29 INFO - PROCESS | 6865 | |i|pagename|runs| 08:29:29 INFO - PROCESS | 6865 | |0;dojo.html;2972.8662210200537;3001.774866884984;3003.804752691074;3030.9848450757745;3050.359424520852;5060.41805192403;5163.7418129093485;5223.582113430926;5308.044551980642;5329.89340213195;5160.14857548505;5167.896642067157;5186.688798672082;5227.921581176276;5257.290265814124;4692.06158768246;4718.882027949295;4764.428268607772;4777.689450185744;4851.393575803025;4870.634702397317;4892.804287828482;4918.729469879156;4938.061768264036;4970.975145124269;4609.4238220222305;4630.282306242538;4700.4594471635855;4739.241721324606;4856.490068542805;4750.097481478508;4751.95248047522;4815.49437309081;4825.372701548777;4836.758162091881;4698.38920940279;4720.811167553328;4732.810687572493;4743.525647435264;4781.187198176319;4989.126902791997;5033.245013248005;5225.163973764217;5236.345456817898;5287.524122828964;5404.189371594253;5429;5429.782808687648;5434.83695489136;5441.537469315113;5422.810201642963;5431.21247419122;5530.834074977757;5539.50144486994;5569.582281328917;5207.453217412193;5228.294180285613;5266.183741520071;5291.074062039166;5295.973520132375;5655.264815573949;5669.121286200645;5706.71466426677;5714.942850571524;5717.399673034354;5339.225812257278;5374.059539580558;5495.258140151109;5564.8330550083565;5571.0807716727095;5519.889602207933;5564.360098588714;5569.10894256917;5575.749091290902;5643.266375371258;5499.312585926759;5542.61201715876;5548.805791797267;5551;5554.2779438672715;5404.1353383459245;5553.444655534495;5564.8052318169475;5577.247071645357;5629.802956896489;4249.915001699948;4296.312589985588;4321.049369138785;4337.262665346899;4366.279563871989;3857.035741064734;3858.054776579756;3861.8455261789777;3889.727719059695;3904.472896158982;5281.815136470205;5282.7622756976025;5283.788648454019;5288.312519372457;5343.0649636314265;5487.670739755627;5531.197976293415;5544.251526043932;5630.07103827872;5649.152627105885;5303.920441193385;5316.9202461963105;5329.1206950852675;5387.030334539742;5389.245505629215;4823.397075365579;4861.052094841472;4864.246041863516;4875.561199492063;4929.728864912464;5651.463111004366;5760.711964401679;5884.293884733859;5886.911696324559;5897.734601942923;5571.13647384656;5611.186377975252;5625;5626.465485778845;5698.686572238566;5805.390434004369;5808.796692115756;5810.389909059657;5817.185594016925;5821.417858214145;5298.70857102863;5498.560115190695;5523.558115350682;5532.114861622121;5533.418991006048;5406.945930540644;5587.99416105105;5598.244237027949;5602.52378547828;5693.857653558529;5415.377231618414;5443.455654434683;5464.316960379952;5468.835934921959;5470.370907345706;5430.592705547178;5468.835934921959;5484.2596249507405;5485.286912701324;5557.472040156179;5204.63567550283;5266.34170728659;5342.225377320345;5398.46015398457;5414.810481633124;5530.557555395479;5534.612577119563;5540.75066622011;5543.556515478833;5598.944010559842;5387.488188622075;5406.891862162814;5415.5126038655135;5418.566514678895;5483.204935284281 08:29:29 INFO - PROCESS | 6865 | |1;ext.html;558.0412259128664;595.2975488923071;596.2874365133664;604.4046614085127;607.8176547035894;32980.34039319212;43749;44579.33131003029;44601.33098003532;44700.105997880026;45016.549834501726;45440.77279613597;45465.31802022972;45494.545054549446;45499.31751023729;8832.11678832118;9010.819783604324;9012.27901767857;9025.368224224307;9031.412958157698;8883.378163528556;8939.240164586017;8944;8977.371583989123;8978.41640293382;8917.73246802597;8974.416662916921;8978.23684986777;8980.101989801;8983.416077954913;8970.775730606721;8994.505302208376;9004.729858104267;9008.864867027;9015.684451044248;8868.955655221682;8935.419197752126;8935.41919775219;8936.642534298686;8940.195382415548;42359.788201059106;42660;42762.57237427648;42774.78612606917;42790;3328.367610154063;3470.514128022079;3499.125218695326;3702.1114932416026;3853.9807300963316;32144.517832232534;32383.190420239684;32851.835740821385;33757;34120.48819267713;4258.297380932143;4260.190563792869;4293.463317085364;4308.784560771949;4329.06925011121;342083;344466;344572;344841;345921;48428;54910.45089549053;55017.44982550123;55104.724476378164;55208.44791552114;50308.245376319384;57665.71167144137;59091.70454147702;59522.70238648779;63566;16045.117518536359;16127.193640317937;16138.596535086483;16188.676226475405;16189.919050404673;10057.899421005843;12336.50653973849;12390.141478778256;12397.070219733556;12406.503739850305;14483.203423811792;15872.127033013065;16379.09914954689;16394.426195083113;16580;6148.9692551537855;6161.260648722182;6198.752049917953;6238.688065596702;6327.810165694945;903.1735961595198;976.8485884687884;978.6330126202674;979.5298256836759;1009.7324209084599;29727.40545189084;31666.841665791522;31709;31976.200594985312;32282.515762263585;1030.062642994871;1033.850091736694;1045.451138152476;1098.3245304137897;1136.7783282260045;15623.921880390526;16737.16314184286;16764;16783.74824377611;16878;508868;510630;513479;514300;515050;54618.726906366006;54663.18005229925;55085.1737223942;55093.44906550963;55229;1301.3688361144837;1308.640123965898;1339.7655410303353;1340.902515189895;1359.0758284366725;29608.851955740083;30671.45647948711;31557.36885262328;31650.683493165696;32030;49853;54316.45683543114;55984.16023759566;56038.15942760943;60071.09893351691;456086;458790;459058;459144;461391;24443;25198;27846.16461506158;28256.15231543057;28464.573031404132;44680;47361;47574.52425475839;51621.22568161556;51789;40020.959652667916;41164.38253426261;42383.7880810594;42422.27364275626;42510.5748942519;41835.19648960405;42557.52392588048;42572.78713606412;42641.786791065846;42782.35826462668;40217.19565608613;40278;40842.1831563373;40878.79560602297;40930.38604420877;34562.481562776076;37219.81390093032;37306.44040339451;37800.243995119396;38190.236195275385;28139.71860281453;28221.73002214864;28324.59240964541;28411.289717757223;28447.43105137927 08:29:29 INFO - PROCESS | 6865 | |2;jquery.html;377.4112384679898;396.94045893116026;404.4600458388052;406.2301937827816;417.31351926081595;13466.25935573543;15528.922355388222;19347.226110955562;20048.899755501203;20055.799442005577;18485;18909.338173163942;18922.337718179868;19918.40244792655;19957.800421995777;5321.946780532184;5386.622936394444;5471.316085489314;5547.972260138694;5548.306461692288;5366.302380690505;5473.452654734515;5475.0418676731615;5475.561955043607;5478.45215478453;3966.127451960564;4196.727212731163;5562.415946325638;5970.850728731773;5999.310079340867;5866.237389139427;6073.240844894388;6076.240469941257;6080.0575910733905;6086.939130608704;5922.1116832475045;5924.081767326072;5925.762969481216;5925.881482370329;5928.318243401977;14315;18261.908690456596;22560.774392256033;23114.537709245888;23315.76684233153;2173.739151301854;2231.564844855254;2298.678185054094;2390.796782273509;2987.626546681665;10204.693859184237;10338.276320657556;10564.154867610572;12696.238225706395;14824.481143160045;1884.3875740384292;1925.4993701637536;1925.8363039141689;1942.436338747308;1960.8137226963415;78483;82657.58671206665;84430.57784711037;84860.15139848585;87399.12600873974;10856.72858178536;11338.546458141747;11342.546298147981;11389.943050284695;11419.543218271176;15948.521544353687;15992.280347384396;16015.43945961909;16016.279267432761;16020;7937.920620793833;7947.4436789425345;8099.433039687283;8130.634121464548;8168.428210025241;7521.360684341892;7741.264579864904;7753.457257991887;7917.841643167219;7923.801904952307;4719.622430205575;4754.31062495943;4824.228123500223;4872.220444728827;4965.329680493087;1963.1840547493512;2002.6995950607234;2016.3043749906258;2104.46336184272;2110.4196346004974;875;897.5915958238986;907.0430695616188;1053.167997282154;1068.3696618994675;17714.379996700052;19460.70808937867;19481.318153864548;19770.110345034507;19779.703304450446;942.189716843514;961.5336561767538;977.4428575711918;1017.31839667423;1049.569676432659;10365.533550990072;10929.617463388902;11045.116390688725;11068.833967490495;11124.110071194285;362398;369647;480658;483206;489289;94747.52626236825;95157.5242123785;95216;98396.50801746089;100215;997.9501024948723;1018.7402212435782;1044.6657069737612;1047.6333283350766;1054.435876805905;16770.580735481224;17103;17677.292908283525;18096.27614895442;18511.35210267634;41879.79060104802;42129;42229.5777042238;43328.78335608302;43462.130757385305;57235;57839;59117;59713.70143149256;61247.38752612417;8468.264005878656;8518.957405213183;8538.316934645089;8681;8977.910220897707;9460.148586627236;12270.509179632714;12718.10973231865;13065.531339887671;14081.929590352393;5787.102999035156;6301.842453938688;12122.333271670142;12162.297278380856;12361.07291953089;11317.547298107984;11717.121215909012;11762.94148524022;11851.170418070653;12491.125621206429;11676.299422034705;11691.181617286707;12025.4588543514;12112.152149349813;12578.748425031265;8356.45405599966;8702.564871756513;8785.956070219609;8859.60131794058;9091.454512729257;11139.944300278446;11518.36648984314;11556.88443115558;11979.940100299442;12105.394730263628 08:29:29 INFO - PROCESS | 6865 | |3;mootools.html;1288.2335010668648;1446.4937271954818;1447.3342262559224;1601.9919900400496;1735.9739603905944;7065.328793764591;8676.958764948207;8954.417962832427;8967.192952634261;9161.587728552215;8944.552772361389;9014.098590140982;9033.593488293027;9044.90955090449;9045.592948317326;2388.2835149455223;2403.807695384365;2411.6020856558735;2419.552382809186;2427.526632306701;2332.370260029791;2354.1878052072093;2374.726906405768;2376.5603363377745;2381.583222935988;2348.9530209395803;2365.597848365773;2374.27584586701;2385.3798012516695;2394.8682822444757;2331.358876309029;2349.459624286407;2350.8119350452007;2350.9529809403803;2372.1223147435385;2317.7913987741017;2339.9883000584887;2353.776391242829;2354.411397150712;2356.5051339218785;8756.8686469703;8869.645214191425;8889.955550222208;8919.197272245465;8938.821223575493;4885.8778530536665;5069.746512674388;5075.5178258065425;5076.492350764931;5082.237664350377;3246.399416108004;3342.4317865962844;3362.6469220731715;3364.9831750841336;3375.9156021099425;1117.4189421500773;1139.811931031387;1140.201858698914;1159.6173262823334;1163.4531770068054;12396.256224626552;12436.689082772913;12467.688307792287;12493.562725304662;12496.937515312366;4376.693631445768;4468.754218518013;4591.242444996605;4620;4663.976680116645;9704.466254355937;9764.218862490983;9764.51177441125;9772.267079969031;9778.853317200248;5115.386153661584;5120.129577971754;5120.743962801845;5123.410807757081;5124.564412024945;3837.7505462144873;3969.980150099231;4061.9796901015898;4108.77401742901;4784.808607655655;3411.4030044742567;3876.341022026206;3918.392649139388;3922.7450215735885;3956.169204467094;1207.5833837326109;1228.91397602167;1236.3756303066893;1241.1436109084702;1247.2454165229906;838.1869586501084;851.3487182305541;853.7012045783925;887.9556022198864;897.4391005621486;5574.916376254359;5602.11491082093;5632.887342253132;5686.34607020198;5724.112762521816;440.3768667335748;442.2084468800875;445.8194431255347;447.86564030790635;451.2621863253604;3680.2271522980473;3757.887263382103;3764.3036038333;3802.239552089593;3984.7210695251056;10854.185936054828;11141.610043648434;11241.044511216638;11241.887581124247;11241.943790280997;10922.508487118099;11081.445927703744;11174.553017879194;11182.217244792786;11284.54861805535;8374.53940033304;8399.110200204905;8465.746027619058;8469.576521173916;8474.1949514796;5472.644278121909;5512.5314348280845;5522.6134170607675;5556.91664625039;5699.800506982236;10262.281640285108;10307.94846025765;10316.123129534073;10430.06129448323;10443.94778026105;8412.537310447738;10463.529141188512;10473.528691208761;10499.527521261714;10620.362778233331;5941.584089113893;7140.964295178491;7163.176234732864;7276.781696549112;7279.818004549928;8278.875816862872;8393.390841938026;8410.705625303084;8488.702895398632;8489.278411335106;7581.734639287599;7698.653560589675;7703.03241547582;7722.150563437905;7861.685532578862;7671.578063206577;7707.383409327353;7745.961270193613;7766.378689704923;7935.365170786208;6843.828904277433;6913.654317284217;6915.03189553473;6935;6993.055937448583;4840.1529732296;4908.852734417973;4923.990581930767;4955.1823949048;5060.620453465931;4834.791302174456;4961.3798275215595;4988.201887697951;5043.52086551777;5045.839456924855 08:29:29 INFO - PROCESS | 6865 | |4;prototype.html;316.38778962707147;320.47922126544364;328.68610476994473;333.5913505955205;334.7623187536848;5123.180291153416;5238.214267859823;5252.343457067866;5295.179247216678;5318.202269659553;5115.411727651322;5239.5546378557865;5253.133233016557;5269.789208431658;5281.63028587999;4169.520505141917;4177.916441671165;4193.895152621179;4198.937015944771;4212.241796476633;3984.402339649047;4130.483689538808;4180.602842729951;4181.3309870420735;4198.97900510497;4081.0003098481825;4097.074375043725;4097.282975479294;4097.938530922039;4105.32262176742;4081.0817566047403;4110.362893751473;4113.8765837024785;4118.4440100586335;4122.113745544707;3500;3606.314800187956;3628.092976755811;3636.1455058061333;3641.814911053373;5089.720065396402;5093.802956305265;5111.795528178868;5113.590912726973;5118.488151184852;4677.228257337535;4721.6458765592715;4728.739919304437;4750.144973904696;4755.286706993944;5400.29796126505;5421.457854214586;5482.451754824526;5491.670499769986;5569.749361278752;3956;3961.58403367648;3984.083660758042;3988.541317748467;3990.4812374391436;5641.8589535261945;5685.204071430003;5716.485516303553;5722.713864306768;5743.540516758691;3950.802459876995;3999.6600288975765;4045.1909618075915;4048.7975601219823;4060.39094135882;4041.4942735504173;4046.927564195432;4050.858219962346;4052.1895620875944;4068.3083875741195;4626.727649896306;4659.44086709597;4669.95330046702;4696.084263568571;4708.8116475341285;5282.286891269706;5531.1426728857095;5573.916391254134;5606.299212598426;5606.467385598362;1326.1247576599399;1360.7074478987065;1363.9181649101085;1364.645192250022;1364.969448066723;954.9952250238705;984.8965858584745;989.2629990657007;994.7662299359645;999.9500024998721;5291.761870715827;5298.2052692096495;5302.09864323066;5305.442928492453;5311;5516.558675305966;5663.858403539944;5678.801241956512;5739.253896993364;5786.508146807484;5338.706371149624;5339.41266460689;5345.625806193607;5361.222622719683;5361.35663720356;2954.3012767658684;3003.5044217703835;3020.652624948159;3026.8206891038417;3032.863521141554;5890.263717035371;5914.645121292736;5923.1864864189165;5924.822255332347;5931.881362372728;5820.970895145582;5825.941740582624;5832.1834943108;5835.891180675658;5844.357120716718;5602.887942241132;5821.2141360916585;5869.413058694096;5896.528277737771;5956.404359564096;5631.239782629293;5636.887262254814;5677.403872593318;5704.914426283692;5711.971440142773;5629.943700562942;5638.323401191883;5639.520640745583;5645;5672.461116193955;5623.493885550321;5869.94130058711;5885.911711324419;5895.879782841246;5914.852128696645;4090.263752524455;4094.2016306819883;4114.403411505375;4138.606832350922;4148.688848336326;5652.7738890443925;5747.367789543064;5790.565707572033;5802.245708057925;5813.982553053114;5487.506124448819;5496.835094947158;5532.723363831873;5536.674596669489;5572;5396.244525766474;5545.223668686468;5554.833614890115;5569.832905012856;5625.943740562542;5330.2804121443105;5446.046941785251;5488.835334939959;5512.531434827924;5542.972285138549;5534.225208470898;5567.331920169606;5590.944090559043;5595.328560572757;5631.352394474688;5278.392984806796;5455.9633669602645;5468.371137319259;5499.240243122163;5510.134729940329 08:29:29 INFO - PROCESS | 6865 | |5;yui.html;779.4816447062703;797.649034424853;809.1503920883073;809.6639894443806;852.9829403411936;14880.330385132667;16015.599610009756;16086.436974705888;16151.757723634155;16158.596035099114;12878.678033049178;13268.867311326883;13362.064655474122;13372.063955523117;13521.256330901797;644.5423749138117;658.9275179730225;785.426638553856;803.7990502374406;807.8545861744883;784.674360140541;807.0235015631093;807.2250639386197;808.2119933065273;809.1746418652971;845.8646616541355;847.2840449819893;850.1201256699318;854.1159899504015;856.4732689396039;678.7285085965603;691.4468425259822;850.2050582705191;850.230541360067;850.7022542110274;509.0302972836715;510.6093838213769;595.3986473661589;611.0803241122127;615.7444660465947;13888.861111388858;13942.86057139436;13974.441022359093;13979.930100349535;13997.650058748512;13994.720105597933;14103.506377276848;14310.785338219936;14367;14759.852401476062;12773.042021848398;12893.226406415644;12910.225386476748;13262.469501219939;13306.534271300556;4314.676399270067;4348.717128447116;4400.3399490076745;4535.319702044687;4654.441467023945;83864.58067709683;84747.15252847454;85855;86039;87679.56160219222;11195.384253865952;11358.829617555743;11364.886351136549;11380.260283081574;11489.655310340702;21837.67243491349;21843.890780546313;21852.344429667137;21858;21920.123195072018;8501.61742721579;8508.319334453228;8567.443116197428;8608.784780380416;9012.41419307743;4762.047590481918;4804.519548045238;4835.298881662139;4886.828960986349;4970.776315065831;3383.543221665044;3447.9655203448146;3475.4786781982907;3479.1650003999366;3493.825308734553;572.9627574207664;582.0570675505708;582.4641329976432;586.6861229242335;588.2793577867078;680.5814424129223;696.6586372677453;698.12734082397;699.101654374137;718.5616773767997;9801;9920.4543750093;9922.206223502102;9945.30382873192;9963.352382095285;662.8342914271432;665.6339013542532;667.9666016699146;701.3133414139004;738.8448425830636;4462.843800467033;4567.794449249792;4616.722996620214;4746.098241334135;4747.8575642730775;66551.66724166348;67253.66373168102;67878;82008.17991820125;122832.38583807024;104329;108668.45665771779;109137.45431272793;110673.44663276793;112749;78377.60811195908;80591.59704201441;90447.54776226077;92889.53555232316;93860.99999999863;9122.631605259265;9176.53175491918;9290.349675522648;9306.441613503212;9323.533823308942;51472.48527514827;51923;52665.47334526606;52995.73502132465;54833;63599.682001589696;64167.67916160389;70698;74959;81017.18982810096;12997.220166790023;13154.671133221746;13708.52020179289;13769.31153442344;13918.58244252674;15350.23248837576;15359.61600959985;15429.84570154329;16596.50210493687;16763.41328053561;14085.295735212994;14493.92753036228;14570.125792452487;14940.252987350807;15578.454754084007;10899.836502452312;13029.153105048521;13481.2585307809;13703.794443083561;14049.929750351183;13896.305184740924;14045.15729056219;14058.086224395382;14229.573112806633;14650.63373415673;11984;12159.939200303941;12542;12724.345835041386;13445.126066805626;11978.221415607957;12091.637250882488;12142.575009874612;12308.384580771104;12416.379181041093 08:29:29 INFO - PROCESS | 6865 | __end_tp_report 08:29:29 INFO - PROCESS | 6865 | __start_cc_report 08:29:29 INFO - PROCESS | 6865 | _x_x_mozilla_cycle_collect,917 08:29:29 INFO - PROCESS | 6865 | __end_cc_report 08:29:29 INFO - PROCESS | 6865 | __startTimestamp1474385369382__endTimestamp 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | ------- Summary: start ------- 08:29:29 INFO - PROCESS | 6865 | Number of tests: 6 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | [#0] dojo.html Cycles:165 Average:5191.55 Median:5398.46 stddev:577.31 (10.7%) stddev-sans-first:552.22 08:29:29 INFO - PROCESS | 6865 | Values: 2972.9 3001.8 3003.8 3031.0 3050.4 5060.4 5163.7 5223.6 5308.0 5329.9 5160.1 5167.9 5186.7 5227.9 5257.3 4692.1 4718.9 4764.4 4777.7 4851.4 4870.6 4892.8 4918.7 4938.1 4971.0 4609.4 4630.3 4700.5 4739.2 4856.5 4750.1 4752.0 4815.5 4825.4 4836.8 4698.4 4720.8 4732.8 4743.5 4781.2 4989.1 5033.2 5225.2 5236.3 5287.5 5404.2 5429.0 5429.8 5434.8 5441.5 5422.8 5431.2 5530.8 5539.5 5569.6 5207.5 5228.3 5266.2 5291.1 5296.0 5655.3 5669.1 5706.7 5714.9 5717.4 5339.2 5374.1 5495.3 5564.8 5571.1 5519.9 5564.4 5569.1 5575.7 5643.3 5499.3 5542.6 5548.8 5551.0 5554.3 5404.1 5553.4 5564.8 5577.2 5629.8 4249.9 4296.3 4321.0 4337.3 4366.3 3857.0 3858.1 3861.8 3889.7 3904.5 5281.8 5282.8 5283.8 5288.3 5343.1 5487.7 5531.2 5544.3 5630.1 5649.2 5303.9 5316.9 5329.1 5387.0 5389.2 4823.4 4861.1 4864.2 4875.6 4929.7 5651.5 5760.7 5884.3 5886.9 5897.7 5571.1 5611.2 5625.0 5626.5 5698.7 5805.4 5808.8 5810.4 5817.2 5821.4 5298.7 5498.6 5523.6 5532.1 5533.4 5406.9 5588.0 5598.2 5602.5 5693.9 5415.4 5443.5 5464.3 5468.8 5470.4 5430.6 5468.8 5484.3 5485.3 5557.5 5204.6 5266.3 5342.2 5398.5 5414.8 5530.6 5534.6 5540.8 5543.6 5598.9 5387.5 5406.9 5415.5 5418.6 5483.2 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | [#1] ext.html Cycles:180 Average:59791.10 Median:42768.68 stddev:117714.61 (275.2%) stddev-sans-first:117960.82 08:29:29 INFO - PROCESS | 6865 | Values: 558.0 595.3 596.3 604.4 607.8 32980.3 43749.0 44579.3 44601.3 44700.1 45016.5 45440.8 45465.3 45494.5 45499.3 8832.1 9010.8 9012.3 9025.4 9031.4 8883.4 8939.2 8944.0 8977.4 8978.4 8917.7 8974.4 8978.2 8980.1 8983.4 8970.8 8994.5 9004.7 9008.9 9015.7 8869.0 8935.4 8935.4 8936.6 8940.2 42359.8 42660.0 42762.6 42774.8 42790.0 3328.4 3470.5 3499.1 3702.1 3854.0 32144.5 32383.2 32851.8 33757.0 34120.5 4258.3 4260.2 4293.5 4308.8 4329.1 342083.0 344466.0 344572.0 344841.0 345921.0 48428.0 54910.5 55017.4 55104.7 55208.4 50308.2 57665.7 59091.7 59522.7 63566.0 16045.1 16127.2 16138.6 16188.7 16189.9 10057.9 12336.5 12390.1 12397.1 12406.5 14483.2 15872.1 16379.1 16394.4 16580.0 6149.0 6161.3 6198.8 6238.7 6327.8 903.2 976.8 978.6 979.5 1009.7 29727.4 31666.8 31709.0 31976.2 32282.5 1030.1 1033.9 1045.5 1098.3 1136.8 15623.9 16737.2 16764.0 16783.7 16878.0 508868.0 510630.0 513479.0 514300.0 515050.0 54618.7 54663.2 55085.2 55093.4 55229.0 1301.4 1308.6 1339.8 1340.9 1359.1 29608.9 30671.5 31557.4 31650.7 32030.0 49853.0 54316.5 55984.2 56038.2 60071.1 456086.0 458790.0 459058.0 459144.0 461391.0 24443.0 25198.0 27846.2 28256.2 28464.6 44680.0 47361.0 47574.5 51621.2 51789.0 40021.0 41164.4 42383.8 42422.3 42510.6 41835.2 42557.5 42572.8 42641.8 42782.4 40217.2 40278.0 40842.2 40878.8 40930.4 34562.5 37219.8 37306.4 37800.2 38190.2 28139.7 28221.7 28324.6 28411.3 28447.4 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | [#2] jquery.html Cycles:180 Average:27677.84 Median:182692.81 stddev:73243.78 (40.1%) stddev-sans-first:73420.56 08:29:29 INFO - PROCESS | 6865 | Values: 377.4 396.9 404.5 406.2 417.3 13466.3 15528.9 19347.2 20048.9 20055.8 18485.0 18909.3 18922.3 19918.4 19957.8 5321.9 5386.6 5471.3 5548.0 5548.3 5366.3 5473.5 5475.0 5475.6 5478.5 3966.1 4196.7 5562.4 5970.9 5999.3 5866.2 6073.2 6076.2 6080.1 6086.9 5922.1 5924.1 5925.8 5925.9 5928.3 14315.0 18261.9 22560.8 23114.5 23315.8 2173.7 2231.6 2298.7 2390.8 2987.6 10204.7 10338.3 10564.2 12696.2 14824.5 1884.4 1925.5 1925.8 1942.4 1960.8 78483.0 82657.6 84430.6 84860.2 87399.1 10856.7 11338.5 11342.5 11389.9 11419.5 15948.5 15992.3 16015.4 16016.3 16020.0 7937.9 7947.4 8099.4 8130.6 8168.4 7521.4 7741.3 7753.5 7917.8 7923.8 4719.6 4754.3 4824.2 4872.2 4965.3 1963.2 2002.7 2016.3 2104.5 2110.4 875.0 897.6 907.0 1053.2 1068.4 17714.4 19460.7 19481.3 19770.1 19779.7 942.2 961.5 977.4 1017.3 1049.6 10365.5 10929.6 11045.1 11068.8 11124.1 362398.0 369647.0 480658.0 483206.0 489289.0 94747.5 95157.5 95216.0 98396.5 100215.0 998.0 1018.7 1044.7 1047.6 1054.4 16770.6 17103.0 17677.3 18096.3 18511.4 41879.8 42129.0 42229.6 43328.8 43462.1 57235.0 57839.0 59117.0 59713.7 61247.4 8468.3 8519.0 8538.3 8681.0 8977.9 9460.1 12270.5 12718.1 13065.5 14081.9 5787.1 6301.8 12122.3 12162.3 12361.1 11317.5 11717.1 11762.9 11851.2 12491.1 11676.3 11691.2 12025.5 12112.2 12578.7 8356.5 8702.6 8786.0 8859.6 9091.5 11139.9 11518.4 11556.9 11979.9 12105.4 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | [#3] mootools.html Cycles:180 Average:5709.79 Median:4605.62 stddev:3354.11 (72.8%) stddev-sans-first:3347.06 08:29:29 INFO - PROCESS | 6865 | Values: 1288.2 1446.5 1447.3 1602.0 1736.0 7065.3 8677.0 8954.4 8967.2 9161.6 8944.6 9014.1 9033.6 9044.9 9045.6 2388.3 2403.8 2411.6 2419.6 2427.5 2332.4 2354.2 2374.7 2376.6 2381.6 2349.0 2365.6 2374.3 2385.4 2394.9 2331.4 2349.5 2350.8 2351.0 2372.1 2317.8 2340.0 2353.8 2354.4 2356.5 8756.9 8869.6 8890.0 8919.2 8938.8 4885.9 5069.7 5075.5 5076.5 5082.2 3246.4 3342.4 3362.6 3365.0 3375.9 1117.4 1139.8 1140.2 1159.6 1163.5 12396.3 12436.7 12467.7 12493.6 12496.9 4376.7 4468.8 4591.2 4620.0 4664.0 9704.5 9764.2 9764.5 9772.3 9778.9 5115.4 5120.1 5120.7 5123.4 5124.6 3837.8 3970.0 4062.0 4108.8 4784.8 3411.4 3876.3 3918.4 3922.7 3956.2 1207.6 1228.9 1236.4 1241.1 1247.2 838.2 851.3 853.7 888.0 897.4 5574.9 5602.1 5632.9 5686.3 5724.1 440.4 442.2 445.8 447.9 451.3 3680.2 3757.9 3764.3 3802.2 3984.7 10854.2 11141.6 11241.0 11241.9 11241.9 10922.5 11081.4 11174.6 11182.2 11284.5 8374.5 8399.1 8465.7 8469.6 8474.2 5472.6 5512.5 5522.6 5556.9 5699.8 10262.3 10307.9 10316.1 10430.1 10443.9 8412.5 10463.5 10473.5 10499.5 10620.4 5941.6 7141.0 7163.2 7276.8 7279.8 8278.9 8393.4 8410.7 8488.7 8489.3 7581.7 7698.7 7703.0 7722.2 7861.7 7671.6 7707.4 7746.0 7766.4 7935.4 6843.8 6913.7 6915.0 6935.0 6993.1 4840.2 4908.9 4924.0 4955.2 5060.6 4834.8 4961.4 4988.2 5043.5 5045.8 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | [#4] prototype.html Cycles:180 Average:4667.45 Median:5314.60 stddev:1374.52 (25.9%) stddev-sans-first:1339.02 08:29:29 INFO - PROCESS | 6865 | Values: 316.4 320.5 328.7 333.6 334.8 5123.2 5238.2 5252.3 5295.2 5318.2 5115.4 5239.6 5253.1 5269.8 5281.6 4169.5 4177.9 4193.9 4198.9 4212.2 3984.4 4130.5 4180.6 4181.3 4199.0 4081.0 4097.1 4097.3 4097.9 4105.3 4081.1 4110.4 4113.9 4118.4 4122.1 3500.0 3606.3 3628.1 3636.1 3641.8 5089.7 5093.8 5111.8 5113.6 5118.5 4677.2 4721.6 4728.7 4750.1 4755.3 5400.3 5421.5 5482.5 5491.7 5569.7 3956.0 3961.6 3984.1 3988.5 3990.5 5641.9 5685.2 5716.5 5722.7 5743.5 3950.8 3999.7 4045.2 4048.8 4060.4 4041.5 4046.9 4050.9 4052.2 4068.3 4626.7 4659.4 4670.0 4696.1 4708.8 5282.3 5531.1 5573.9 5606.3 5606.5 1326.1 1360.7 1363.9 1364.6 1365.0 955.0 984.9 989.3 994.8 1000.0 5291.8 5298.2 5302.1 5305.4 5311.0 5516.6 5663.9 5678.8 5739.3 5786.5 5338.7 5339.4 5345.6 5361.2 5361.4 2954.3 3003.5 3020.7 3026.8 3032.9 5890.3 5914.6 5923.2 5924.8 5931.9 5821.0 5825.9 5832.2 5835.9 5844.4 5602.9 5821.2 5869.4 5896.5 5956.4 5631.2 5636.9 5677.4 5704.9 5712.0 5629.9 5638.3 5639.5 5645.0 5672.5 5623.5 5869.9 5885.9 5895.9 5914.9 4090.3 4094.2 4114.4 4138.6 4148.7 5652.8 5747.4 5790.6 5802.2 5814.0 5487.5 5496.8 5532.7 5536.7 5572.0 5396.2 5545.2 5554.8 5569.8 5625.9 5330.3 5446.0 5488.8 5512.5 5543.0 5534.2 5567.3 5590.9 5595.3 5631.4 5278.4 5456.0 5468.4 5499.2 5510.1 08:29:29 INFO - PROCESS | 6865 | 08:29:29 INFO - PROCESS | 6865 | [#5] yui.html Cycles:180 Average:20511.72 Median:4754.95 stddev:28928.83 (608.4%) stddev-sans-first:28972.04 08:29:29 INFO - PROCESS | 6865 | Values: 779.5 797.6 809.2 809.7 853.0 14880.3 16015.6 16086.4 16151.8 16158.6 12878.7 13268.9 13362.1 13372.1 13521.3 644.5 658.9 785.4 803.8 807.9 784.7 807.0 807.2 808.2 809.2 845.9 847.3 850.1 854.1 856.5 678.7 691.4 850.2 850.2 850.7 509.0 510.6 595.4 611.1 615.7 13888.9 13942.9 13974.4 13979.9 13997.7 13994.7 14103.5 14310.8 14367.0 14759.9 12773.0 12893.2 12910.2 13262.5 13306.5 4314.7 4348.7 4400.3 4535.3 4654.4 83864.6 84747.2 85855.0 86039.0 87679.6 11195.4 11358.8 11364.9 11380.3 11489.7 21837.7 21843.9 21852.3 21858.0 21920.1 8501.6 8508.3 8567.4 8608.8 9012.4 4762.0 4804.5 4835.3 4886.8 4970.8 3383.5 3448.0 3475.5 3479.2 3493.8 573.0 582.1 582.5 586.7 588.3 680.6 696.7 698.1 699.1 718.6 9801.0 9920.5 9922.2 9945.3 9963.4 662.8 665.6 668.0 701.3 738.8 4462.8 4567.8 4616.7 4746.1 4747.9 66551.7 67253.7 67878.0 82008.2 122832.4 104329.0 108668.5 109137.5 110673.4 112749.0 78377.6 80591.6 90447.5 92889.5 93861.0 9122.6 9176.5 9290.3 9306.4 9323.5 51472.5 51923.0 52665.5 52995.7 54833.0 63599.7 64167.7 70698.0 74959.0 81017.2 12997.2 13154.7 13708.5 13769.3 13918.6 15350.2 15359.6 15429.8 16596.5 16763.4 14085.3 14493.9 14570.1 14940.3 15578.5 10899.8 13029.2 13481.3 13703.8 14049.9 13896.3 14045.2 14058.1 14229.6 14650.6 11984.0 12159.9 12542.0 12724.3 13445.1 11978.2 12091.6 12142.6 12308.4 12416.4 08:29:29 INFO - PROCESS | 6865 | -------- Summary: end -------- 08:29:29 INFO - PROCESS | 6865 | 08:29:30 INFO - TEST-INFO | 6865: exit 0 08:29:30 INFO - TEST-OK | dromaeo_css | took 1115781ms 08:29:30 INFO - TEST-START | kraken 08:29:30 INFO - operating with platform_type : linux_ 08:29:30 INFO - Initialising browser for kraken test... 08:29:30 INFO - TEST-INFO | started process 7173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp0xe8A/profile http://localhost:56085/getInfo.html) 08:29:30 INFO - PROCESS | 7173 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 08:29:30 INFO - TEST-INFO | 7173: exit 127 08:29:30 INFO - Could not find __metrics(.*)__metrics in browser output 08:29:30 INFO - Raw results:Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 08:29:30 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 08:29:30 ERROR - Traceback (most recent call last): 08:29:30 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 08:29:30 INFO - talos_results.add(mytest.runTest(browser_config, test)) 08:29:30 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 08:29:30 INFO - with FFSetup(browser_config, test_config) as setup: 08:29:30 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 165, in __enter__ 08:29:30 INFO - self._run_profile() 08:29:30 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 141, in _run_profile 08:29:30 INFO - raise TalosError("browser failed to close after being initialized") 08:29:30 INFO - TalosError: browser failed to close after being initialized 08:29:30 INFO - TEST-INFO took 277ms 08:29:30 INFO - SUITE-END | took 1116s 08:29:30 ERROR - Return code: 2 08:29:30 WARNING - setting return code to 2 08:29:30 ERROR - # TBPL FAILURE # 08:29:30 INFO - Running post-action listener: _resource_record_post_action 08:29:30 INFO - [mozharness: 2016-09-20 15:29:30.812800Z] Finished run-tests step (success) 08:29:30 INFO - Running post-run listener: _resource_record_post_run 08:29:30 INFO - Total resource usage - Wall time: 1133s; CPU: 13.0%; Read bytes: 4096; Write bytes: 345387008; Read time: 12; Write time: 305504 08:29:30 INFO - TinderboxPrint: CPU usage
13.1% 08:29:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:29:30 INFO - TinderboxPrint: I/O write bytes / time
345,387,008 / 305,504 08:29:30 INFO - TinderboxPrint: CPU idle
7,852.4 (86.8%) 08:29:30 INFO - TinderboxPrint: CPU user
1,150.2 (12.7%) 08:29:30 INFO - TinderboxPrint: Swap in / out
0 / 0 08:29:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115191808; Read time: 0; Write time: 104456 08:29:30 INFO - run-tests - Wall time: 1117s; CPU: 13.0%; Read bytes: 0; Write bytes: 230195200; Read time: 0; Write time: 201048 08:29:31 INFO - Running post-run listener: _upload_blobber_files 08:29:31 INFO - Blob upload gear active. 08:29:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:31 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 08:29:31 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:29:31 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:29:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:32 INFO - (blobuploader) - INFO - Done attempting. 08:29:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:33 INFO - (blobuploader) - INFO - Done attempting. 08:29:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:29:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:37 INFO - (blobuploader) - INFO - Done attempting. 08:29:37 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:37 INFO - Return code: 0 08:29:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4464d30f642ded2ae20e1ae09d48437c00eb28fa27b378600c249cbf3faebf3d1b4a73bd45bda174c683779c13ccad732b7edcc4a1fa899785415595e0415956", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6448a36d398638605b67261c704edb69c6fd3e5ed561f421bb3d21830c264bef3d5ce60cfceaf495bd5c16706fd27f10b3f531329664d346d8b96a945bc5229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5134c209cd7e714688c89e437e25ce3c9b192ef3b7bbe8b4a9fcad7845a81b14d09e09b409b3f17611323e18180f37bff824bdc571d9f5b6e40bfd706180d7dc"} 08:29:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:37 INFO - Contents: 08:29:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4464d30f642ded2ae20e1ae09d48437c00eb28fa27b378600c249cbf3faebf3d1b4a73bd45bda174c683779c13ccad732b7edcc4a1fa899785415595e0415956", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6448a36d398638605b67261c704edb69c6fd3e5ed561f421bb3d21830c264bef3d5ce60cfceaf495bd5c16706fd27f10b3f531329664d346d8b96a945bc5229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5134c209cd7e714688c89e437e25ce3c9b192ef3b7bbe8b4a9fcad7845a81b14d09e09b409b3f17611323e18180f37bff824bdc571d9f5b6e40bfd706180d7dc"} 08:29:37 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:37 INFO - Copying logs to upload dir... 08:29:37 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:37 INFO - Copying logs to upload dir... 08:29:37 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1176.436246 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 19 mins, 36 secs) (at 2016-09-20 08:29:37.252755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 08:29:37.258073) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4464d30f642ded2ae20e1ae09d48437c00eb28fa27b378600c249cbf3faebf3d1b4a73bd45bda174c683779c13ccad732b7edcc4a1fa899785415595e0415956", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6448a36d398638605b67261c704edb69c6fd3e5ed561f421bb3d21830c264bef3d5ce60cfceaf495bd5c16706fd27f10b3f531329664d346d8b96a945bc5229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5134c209cd7e714688c89e437e25ce3c9b192ef3b7bbe8b4a9fcad7845a81b14d09e09b409b3f17611323e18180f37bff824bdc571d9f5b6e40bfd706180d7dc"} build_url:https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/OpKaR8B7TH2wTmQvagyifQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4464d30f642ded2ae20e1ae09d48437c00eb28fa27b378600c249cbf3faebf3d1b4a73bd45bda174c683779c13ccad732b7edcc4a1fa899785415595e0415956", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6448a36d398638605b67261c704edb69c6fd3e5ed561f421bb3d21830c264bef3d5ce60cfceaf495bd5c16706fd27f10b3f531329664d346d8b96a945bc5229", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5134c209cd7e714688c89e437e25ce3c9b192ef3b7bbe8b4a9fcad7845a81b14d09e09b409b3f17611323e18180f37bff824bdc571d9f5b6e40bfd706180d7dc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 08:29:37.283494) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 08:29:37.283928) ========= 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=17051fcd5208b27b50bedfeb000001dd-1474384198.394068-1769193864 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 08:29:37.368702) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 08:29:37.369070) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 08:29:37.369389) ========= ========= Total master_lag: 0.18 =========