1 /*
   2  * Copyright (c) 2017 Oracle and/or its affiliates. All rights reserved.
   3  * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
   4  *
   5  * This code is free software; you can redistribute it and/or modify it
   6  * under the terms of the GNU General Public License version 2 only, as
   7  * published by the Free Software Foundation.
   8  *
   9  * This code is distributed in the hope that it will be useful, but WITHOUT
  10  * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
  11  * FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
  12  * version 2 for more details (a copy is included in the LICENSE file that
  13  * accompanied this code).
  14  *
  15  * You should have received a copy of the GNU General Public License version
  16  * 2 along with this work; if not, write to the Free Software Foundation,
  17  * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
  18  *
  19  * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
  20  * or visit www.oracle.com if you need additional information or have any
  21  * questions.
  22  */
  23 
  24 import java.io.BufferedReader;
  25 import java.io.File;
  26 import java.io.FileInputStream;
  27 import java.io.InputStreamReader;
  28 import java.util.regex.Pattern;
  29 
  30 import jdk.test.lib.process.OutputAnalyzer;
  31 import jdk.test.lib.Platform;
  32 import jdk.test.lib.process.ProcessTools;
  33 
  34 /*
  35  * @test
  36  * @bug 8166944
  37  * @summary Hanging Error Reporting steps may lead to torn error logs
  38  * @modules java.base/jdk.internal.misc
  39  * @library /test/lib
  40  * @requires (vm.debug == true) & (os.family != "windows")
  41  * @author Thomas Stuefe (SAP)
  42  */
  43 
  44 public class TimeoutInErrorHandlingTest {
  45 
  46 
  47     public static void main(String[] args) throws Exception {
  48 
  49         /* Start the VM and let it crash. Specify TestUnresponsiveErrorHandler which will
  50          * let five subsequent error reporting steps hang. The Timeout handling triggered
  51          * by the WatcherThread should kick in and interrupt those steps. In theory, the
  52          * text "timeout occurred during error reporting in step .." (the little timeouts)
  53          * should occur in the error log up to four times, followed by the final big timeout
  54          * "------ Timeout during error reporting after xx s. ------"
  55          *
  56          * Note that there are a number of uncertainties which make writing a 100% foolproof
  57          * test challenging. The time the error reporting thread takes to react to the
  58          * timeout triggers is unknown. So it is difficult to predict how many little timeouts
  59          * will be visible before the big timeout kicks in. Also, once the big timeout hits,
  60          * error reporting thread and Watcherthread will race. The former writes his last
  61          * message to the error logs and flushes, the latter waits 200ms and then exits the
  62          * process without further synchronization with the error reporting thread.
  63          *
  64          * Because of all this and the desire to write a bullet proof test which does
  65          * not fail sporadically, we will not test for the final timeout message nor for all
  66          * of the optimally expected little timeout messages. We just test for two of the
  67          * little timeout messages to see that repeated timeout handling is basically working.
  68          */
  69 
  70         ProcessBuilder pb = ProcessTools.createJavaProcessBuilder(
  71             "-XX:+UnlockDiagnosticVMOptions",
  72             "-Xmx100M",
  73             "-XX:ErrorHandlerTest=14",
  74             "-XX:+TestUnresponsiveErrorHandler",
  75             "-XX:ErrorLogTimeout=16", // 16 seconds big timeout = 4 seconds per little timeout
  76             "-XX:-CreateCoredumpOnCrash",
  77             "-version");
  78 
  79         OutputAnalyzer output_detail = new OutputAnalyzer(pb.start());
  80 
  81         // we should have crashed with a SIGSEGV
  82         output_detail.shouldMatch("# A fatal error has been detected by the Java Runtime Environment:.*");
  83         output_detail.shouldMatch("# +(?:SIGSEGV|EXCEPTION_ACCESS_VIOLATION).*");
  84 
  85         // VM should have been aborted by WatcherThread
  86         output_detail.shouldMatch(".*timer expired, abort.*");
  87 
  88         // extract hs-err file
  89         String hs_err_file = output_detail.firstMatch("# *(\\S*hs_err_pid\\d+\\.log)", 1);
  90         if (hs_err_file == null) {
  91             throw new RuntimeException("Did not find hs-err file in output.\n");
  92         }
  93 
  94         File f = new File(hs_err_file);
  95         if (!f.exists()) {
  96             throw new RuntimeException("hs-err file missing at "
  97                 + f.getAbsolutePath() + ".\n");
  98         }
  99 
 100         System.out.println("Found hs_err file. Scanning...");
 101 
 102         FileInputStream fis = new FileInputStream(f);
 103         BufferedReader br = new BufferedReader(new InputStreamReader(fis));
 104         String line = null;
 105 
 106 
 107 
 108         Pattern [] pattern = new Pattern[] {
 109             Pattern.compile(".*timeout occurred during error reporting in step.*"),
 110             Pattern.compile(".*timeout occurred during error reporting in step.*")
 111         };
 112         int currentPattern = 0;
 113 
 114         String lastLine = null;
 115         while ((line = br.readLine()) != null) {
 116             if (currentPattern < pattern.length) {
 117               if (pattern[currentPattern].matcher(line).matches()) {
 118                 System.out.println("Found: " + line + ".");
 119                 currentPattern ++;
 120               }
 121             }
 122             lastLine = line;
 123         }
 124         br.close();
 125 
 126         if (currentPattern < pattern.length) {
 127             throw new RuntimeException("hs-err file incomplete (first missing pattern: " +  currentPattern + ")");
 128         }
 129 
 130         System.out.println("OK.");
 131 
 132     }
 133 
 134 }
 135