duke@435: /*
never@2682:  * Copyright (c) 1997, 2011, Oracle and/or its affiliates. All rights reserved.
duke@435:  * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
duke@435:  *
duke@435:  * This code is free software; you can redistribute it and/or modify it
duke@435:  * under the terms of the GNU General Public License version 2 only, as
duke@435:  * published by the Free Software Foundation.
duke@435:  *
duke@435:  * This code is distributed in the hope that it will be useful, but WITHOUT
duke@435:  * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
duke@435:  * FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
duke@435:  * version 2 for more details (a copy is included in the LICENSE file that
duke@435:  * accompanied this code).
duke@435:  *
duke@435:  * You should have received a copy of the GNU General Public License version
duke@435:  * 2 along with this work; if not, write to the Free Software Foundation,
duke@435:  * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
duke@435:  *
trims@1907:  * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
trims@1907:  * or visit www.oracle.com if you need additional information or have any
trims@1907:  * questions.
duke@435:  *
duke@435:  */
duke@435: 
stefank@2314: #include "precompiled.hpp"
stefank@2314: #include "compiler/compileLog.hpp"
stefank@2314: #include "oops/oop.inline.hpp"
stefank@2314: #include "runtime/arguments.hpp"
stefank@2314: #include "utilities/defaultStream.hpp"
stefank@2314: #include "utilities/ostream.hpp"
stefank@2314: #include "utilities/top.hpp"
stefank@2314: #include "utilities/xmlstream.hpp"
stefank@2314: #ifdef TARGET_OS_FAMILY_linux
stefank@2314: # include "os_linux.inline.hpp"
stefank@2314: #endif
stefank@2314: #ifdef TARGET_OS_FAMILY_solaris
stefank@2314: # include "os_solaris.inline.hpp"
stefank@2314: #endif
stefank@2314: #ifdef TARGET_OS_FAMILY_windows
stefank@2314: # include "os_windows.inline.hpp"
stefank@2314: #endif
duke@435: 
duke@435: extern "C" void jio_print(const char* s); // Declarationtion of jvm method
duke@435: 
duke@435: outputStream::outputStream(int width) {
duke@435:   _width       = width;
duke@435:   _position    = 0;
duke@435:   _newlines    = 0;
duke@435:   _precount    = 0;
duke@435:   _indentation = 0;
duke@435: }
duke@435: 
duke@435: outputStream::outputStream(int width, bool has_time_stamps) {
duke@435:   _width       = width;
duke@435:   _position    = 0;
duke@435:   _newlines    = 0;
duke@435:   _precount    = 0;
duke@435:   _indentation = 0;
duke@435:   if (has_time_stamps)  _stamp.update();
duke@435: }
duke@435: 
duke@435: void outputStream::update_position(const char* s, size_t len) {
duke@435:   for (size_t i = 0; i < len; i++) {
duke@435:     char ch = s[i];
duke@435:     if (ch == '\n') {
duke@435:       _newlines += 1;
duke@435:       _precount += _position + 1;
duke@435:       _position = 0;
duke@435:     } else if (ch == '\t') {
jrose@535:       int tw = 8 - (_position & 7);
jrose@535:       _position += tw;
jrose@535:       _precount -= tw-1;  // invariant:  _precount + _position == total count
duke@435:     } else {
duke@435:       _position += 1;
duke@435:     }
duke@435:   }
duke@435: }
duke@435: 
duke@435: // Execute a vsprintf, using the given buffer if necessary.
duke@435: // Return a pointer to the formatted string.
duke@435: const char* outputStream::do_vsnprintf(char* buffer, size_t buflen,
duke@435:                                        const char* format, va_list ap,
duke@435:                                        bool add_cr,
duke@435:                                        size_t& result_len) {
duke@435:   const char* result;
duke@435:   if (add_cr)  buflen--;
duke@435:   if (!strchr(format, '%')) {
duke@435:     // constant format string
duke@435:     result = format;
duke@435:     result_len = strlen(result);
duke@435:     if (add_cr && result_len >= buflen)  result_len = buflen-1;  // truncate
duke@435:   } else if (format[0] == '%' && format[1] == 's' && format[2] == '\0') {
duke@435:     // trivial copy-through format string
duke@435:     result = va_arg(ap, const char*);
duke@435:     result_len = strlen(result);
duke@435:     if (add_cr && result_len >= buflen)  result_len = buflen-1;  // truncate
duke@435:   } else if (vsnprintf(buffer, buflen, format, ap) >= 0) {
duke@435:     result = buffer;
duke@435:     result_len = strlen(result);
duke@435:   } else {
duke@435:     DEBUG_ONLY(warning("increase O_BUFLEN in ostream.hpp -- output truncated");)
duke@435:     result = buffer;
duke@435:     result_len = buflen - 1;
duke@435:     buffer[result_len] = 0;
duke@435:   }
duke@435:   if (add_cr) {
duke@435:     if (result != buffer) {
duke@435:       strncpy(buffer, result, buflen);
duke@435:       result = buffer;
duke@435:     }
duke@435:     buffer[result_len++] = '\n';
duke@435:     buffer[result_len] = 0;
duke@435:   }
duke@435:   return result;
duke@435: }
duke@435: 
duke@435: void outputStream::print(const char* format, ...) {
duke@435:   char buffer[O_BUFLEN];
duke@435:   va_list ap;
duke@435:   va_start(ap, format);
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(buffer, O_BUFLEN, format, ap, false, len);
duke@435:   write(str, len);
duke@435:   va_end(ap);
duke@435: }
duke@435: 
duke@435: void outputStream::print_cr(const char* format, ...) {
duke@435:   char buffer[O_BUFLEN];
duke@435:   va_list ap;
duke@435:   va_start(ap, format);
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(buffer, O_BUFLEN, format, ap, true, len);
duke@435:   write(str, len);
duke@435:   va_end(ap);
duke@435: }
duke@435: 
duke@435: void outputStream::vprint(const char *format, va_list argptr) {
duke@435:   char buffer[O_BUFLEN];
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(buffer, O_BUFLEN, format, argptr, false, len);
duke@435:   write(str, len);
duke@435: }
duke@435: 
duke@435: void outputStream::vprint_cr(const char* format, va_list argptr) {
duke@435:   char buffer[O_BUFLEN];
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(buffer, O_BUFLEN, format, argptr, true, len);
duke@435:   write(str, len);
duke@435: }
duke@435: 
duke@435: void outputStream::fill_to(int col) {
jrose@535:   int need_fill = col - position();
jrose@535:   sp(need_fill);
jrose@535: }
jrose@535: 
jrose@535: void outputStream::move_to(int col, int slop, int min_space) {
jrose@535:   if (position() >= col + slop)
jrose@535:     cr();
jrose@535:   int need_fill = col - position();
jrose@535:   if (need_fill < min_space)
jrose@535:     need_fill = min_space;
jrose@535:   sp(need_fill);
duke@435: }
duke@435: 
duke@435: void outputStream::put(char ch) {
duke@435:   assert(ch != 0, "please fix call site");
duke@435:   char buf[] = { ch, '\0' };
duke@435:   write(buf, 1);
duke@435: }
duke@435: 
jrose@535: #define SP_USE_TABS false
jrose@535: 
jrose@535: void outputStream::sp(int count) {
jrose@535:   if (count < 0)  return;
jrose@535:   if (SP_USE_TABS && count >= 8) {
jrose@535:     int target = position() + count;
jrose@535:     while (count >= 8) {
jrose@535:       this->write("\t", 1);
jrose@535:       count -= 8;
jrose@535:     }
jrose@535:     count = target - position();
jrose@535:   }
jrose@535:   while (count > 0) {
jrose@535:     int nw = (count > 8) ? 8 : count;
jrose@535:     this->write("        ", nw);
jrose@535:     count -= nw;
jrose@535:   }
duke@435: }
duke@435: 
duke@435: void outputStream::cr() {
duke@435:   this->write("\n", 1);
duke@435: }
duke@435: 
duke@435: void outputStream::stamp() {
duke@435:   if (! _stamp.is_updated()) {
duke@435:     _stamp.update(); // start at 0 on first call to stamp()
duke@435:   }
duke@435: 
duke@435:   // outputStream::stamp() may get called by ostream_abort(), use snprintf
duke@435:   // to avoid allocating large stack buffer in print().
duke@435:   char buf[40];
duke@435:   jio_snprintf(buf, sizeof(buf), "%.3f", _stamp.seconds());
duke@435:   print_raw(buf);
duke@435: }
duke@435: 
ysr@777: void outputStream::stamp(bool guard,
ysr@777:                          const char* prefix,
ysr@777:                          const char* suffix) {
ysr@777:   if (!guard) {
ysr@777:     return;
ysr@777:   }
ysr@777:   print_raw(prefix);
ysr@777:   stamp();
ysr@777:   print_raw(suffix);
ysr@777: }
ysr@777: 
duke@435: void outputStream::date_stamp(bool guard,
duke@435:                               const char* prefix,
duke@435:                               const char* suffix) {
duke@435:   if (!guard) {
duke@435:     return;
duke@435:   }
duke@435:   print_raw(prefix);
duke@435:   static const char error_time[] = "yyyy-mm-ddThh:mm:ss.mmm+zzzz";
duke@435:   static const int buffer_length = 32;
duke@435:   char buffer[buffer_length];
duke@435:   const char* iso8601_result = os::iso8601_time(buffer, buffer_length);
duke@435:   if (iso8601_result != NULL) {
duke@435:     print_raw(buffer);
duke@435:   } else {
duke@435:     print_raw(error_time);
duke@435:   }
duke@435:   print_raw(suffix);
duke@435:   return;
duke@435: }
duke@435: 
duke@435: void outputStream::indent() {
duke@435:   while (_position < _indentation) sp();
duke@435: }
duke@435: 
duke@435: void outputStream::print_jlong(jlong value) {
duke@435:   // N.B. Same as INT64_FORMAT
duke@435:   print(os::jlong_format_specifier(), value);
duke@435: }
duke@435: 
duke@435: void outputStream::print_julong(julong value) {
duke@435:   // N.B. Same as UINT64_FORMAT
duke@435:   print(os::julong_format_specifier(), value);
duke@435: }
duke@435: 
duke@435: stringStream::stringStream(size_t initial_size) : outputStream() {
duke@435:   buffer_length = initial_size;
duke@435:   buffer        = NEW_RESOURCE_ARRAY(char, buffer_length);
duke@435:   buffer_pos    = 0;
duke@435:   buffer_fixed  = false;
duke@435: }
duke@435: 
duke@435: // useful for output to fixed chunks of memory, such as performance counters
duke@435: stringStream::stringStream(char* fixed_buffer, size_t fixed_buffer_size) : outputStream() {
duke@435:   buffer_length = fixed_buffer_size;
duke@435:   buffer        = fixed_buffer;
duke@435:   buffer_pos    = 0;
duke@435:   buffer_fixed  = true;
duke@435: }
duke@435: 
duke@435: void stringStream::write(const char* s, size_t len) {
duke@435:   size_t write_len = len;               // number of non-null bytes to write
duke@435:   size_t end = buffer_pos + len + 1;    // position after write and final '\0'
duke@435:   if (end > buffer_length) {
duke@435:     if (buffer_fixed) {
duke@435:       // if buffer cannot resize, silently truncate
duke@435:       end = buffer_length;
duke@435:       write_len = end - buffer_pos - 1; // leave room for the final '\0'
duke@435:     } else {
duke@435:       // For small overruns, double the buffer.  For larger ones,
duke@435:       // increase to the requested size.
duke@435:       if (end < buffer_length * 2) {
duke@435:         end = buffer_length * 2;
duke@435:       }
duke@435:       char* oldbuf = buffer;
duke@435:       buffer = NEW_RESOURCE_ARRAY(char, end);
duke@435:       strncpy(buffer, oldbuf, buffer_pos);
duke@435:       buffer_length = end;
duke@435:     }
duke@435:   }
duke@435:   // invariant: buffer is always null-terminated
duke@435:   guarantee(buffer_pos + write_len + 1 <= buffer_length, "stringStream oob");
duke@435:   buffer[buffer_pos + write_len] = 0;
duke@435:   strncpy(buffer + buffer_pos, s, write_len);
duke@435:   buffer_pos += write_len;
duke@435: 
duke@435:   // Note that the following does not depend on write_len.
duke@435:   // This means that position and count get updated
duke@435:   // even when overflow occurs.
duke@435:   update_position(s, len);
duke@435: }
duke@435: 
duke@435: char* stringStream::as_string() {
duke@435:   char* copy = NEW_RESOURCE_ARRAY(char, buffer_pos+1);
duke@435:   strncpy(copy, buffer, buffer_pos);
duke@435:   copy[buffer_pos] = 0;  // terminating null
duke@435:   return copy;
duke@435: }
duke@435: 
duke@435: stringStream::~stringStream() {}
duke@435: 
duke@435: xmlStream*   xtty;
duke@435: outputStream* tty;
duke@435: outputStream* gclog_or_tty;
duke@435: extern Mutex* tty_lock;
duke@435: 
duke@435: fileStream::fileStream(const char* file_name) {
duke@435:   _file = fopen(file_name, "w");
duke@435:   _need_close = true;
duke@435: }
duke@435: 
kamg@2515: fileStream::fileStream(const char* file_name, const char* opentype) {
kamg@2515:   _file = fopen(file_name, opentype);
kamg@2515:   _need_close = true;
kamg@2515: }
kamg@2515: 
duke@435: void fileStream::write(const char* s, size_t len) {
xlu@948:   if (_file != NULL)  {
xlu@948:     // Make an unused local variable to avoid warning from gcc 4.x compiler.
xlu@948:     size_t count = fwrite(s, 1, len, _file);
xlu@948:   }
duke@435:   update_position(s, len);
duke@435: }
duke@435: 
kamg@2515: long fileStream::fileSize() {
kamg@2515:   long size = -1;
kamg@2515:   if (_file != NULL) {
kamg@2515:     long pos  = ::ftell(_file);
kamg@2515:     if (::fseek(_file, 0, SEEK_END) == 0) {
kamg@2515:       size = ::ftell(_file);
kamg@2515:     }
kamg@2515:     ::fseek(_file, pos, SEEK_SET);
kamg@2515:   }
kamg@2515:   return size;
kamg@2515: }
kamg@2515: 
kamg@2515: char* fileStream::readln(char *data, int count ) {
kamg@2515:   char * ret = ::fgets(data, count, _file);
kamg@2515:   //Get rid of annoying \n char
kamg@2515:   data[::strlen(data)-1] = '\0';
kamg@2515:   return ret;
kamg@2515: }
kamg@2515: 
duke@435: fileStream::~fileStream() {
duke@435:   if (_file != NULL) {
duke@435:     if (_need_close) fclose(_file);
minqi@2964:     _file      = NULL;
duke@435:   }
duke@435: }
duke@435: 
duke@435: void fileStream::flush() {
duke@435:   fflush(_file);
duke@435: }
duke@435: 
duke@435: fdStream::fdStream(const char* file_name) {
duke@435:   _fd = open(file_name, O_WRONLY | O_CREAT | O_TRUNC, 0666);
duke@435:   _need_close = true;
duke@435: }
duke@435: 
duke@435: fdStream::~fdStream() {
duke@435:   if (_fd != -1) {
duke@435:     if (_need_close) close(_fd);
duke@435:     _fd = -1;
duke@435:   }
duke@435: }
duke@435: 
duke@435: void fdStream::write(const char* s, size_t len) {
xlu@948:   if (_fd != -1) {
xlu@948:     // Make an unused local variable to avoid warning from gcc 4.x compiler.
xlu@948:     size_t count = ::write(_fd, s, (int)len);
xlu@948:   }
duke@435:   update_position(s, len);
duke@435: }
duke@435: 
minqi@2964: rotatingFileStream::~rotatingFileStream() {
minqi@2964:   if (_file != NULL) {
minqi@2964:     if (_need_close) fclose(_file);
minqi@2964:     _file      = NULL;
minqi@2964:     FREE_C_HEAP_ARRAY(char, _file_name);
minqi@2964:     _file_name = NULL;
minqi@2964:   }
minqi@2964: }
minqi@2964: 
minqi@2964: rotatingFileStream::rotatingFileStream(const char* file_name) {
minqi@2964:   _cur_file_num = 0;
minqi@2964:   _bytes_writen = 0L;
minqi@2964:   _file_name = NEW_C_HEAP_ARRAY(char, strlen(file_name)+10);
minqi@2964:   jio_snprintf(_file_name, strlen(file_name)+10, "%s.%d", file_name, _cur_file_num);
minqi@2964:   _file = fopen(_file_name, "w");
minqi@2964:   _need_close = true;
minqi@2964: }
minqi@2964: 
minqi@2964: rotatingFileStream::rotatingFileStream(const char* file_name, const char* opentype) {
minqi@2964:   _cur_file_num = 0;
minqi@2964:   _bytes_writen = 0L;
minqi@2964:   _file_name = NEW_C_HEAP_ARRAY(char, strlen(file_name)+10);
minqi@2964:   jio_snprintf(_file_name, strlen(file_name)+10, "%s.%d", file_name, _cur_file_num);
minqi@2964:   _file = fopen(_file_name, opentype);
minqi@2964:   _need_close = true;
minqi@2964: }
minqi@2964: 
minqi@2964: void rotatingFileStream::write(const char* s, size_t len) {
minqi@2964:   if (_file != NULL)  {
minqi@2964:     // Make an unused local variable to avoid warning from gcc 4.x compiler.
minqi@2964:     size_t count = fwrite(s, 1, len, _file);
minqi@2964:     Atomic::add((jlong)count, &_bytes_writen);
minqi@2964:   }
minqi@2964:   update_position(s, len);
minqi@2964: }
minqi@2964: 
minqi@2964: // rotate_log must be called from VMThread at safepoint. In case need change parameters
minqi@2964: // for gc log rotation from thread other than VMThread, a sub type of VM_Operation
minqi@2964: // should be created and be submitted to VMThread's operation queue. DO NOT call this
minqi@2964: // function directly. Currently, it is safe to rotate log at safepoint through VMThread.
minqi@2964: // That is, no mutator threads and concurrent GC threads run parallel with VMThread to
minqi@2964: // write to gc log file at safepoint. If in future, changes made for mutator threads or
minqi@2964: // concurrent GC threads to run parallel with VMThread at safepoint, write and rotate_log
minqi@2964: // must be synchronized.
minqi@2964: void rotatingFileStream::rotate_log() {
minqi@2964:   if (_bytes_writen < (jlong)GCLogFileSize) return;
minqi@2964: #ifdef ASSERT
minqi@2964:   Thread *thread = Thread::current();
minqi@2964:   assert(thread == NULL ||
minqi@2964:          (thread->is_VM_thread() && SafepointSynchronize::is_at_safepoint()),
minqi@2964:          "Must be VMThread at safepoint");
minqi@2964: #endif
minqi@2964:   if (NumberOfGCLogFiles == 1) {
minqi@2964:     // rotate in same file
minqi@2964:     rewind();
minqi@2964:     _bytes_writen = 0L;
minqi@2964:     return;
minqi@2964:   }
minqi@2964: 
minqi@2964:   // rotate file in names file.0, file.1, file.2, ..., file.<MaxGCLogFileNumbers-1>
minqi@2964:   // close current file, rotate to next file
minqi@2964:   if (_file != NULL) {
minqi@2964:     _cur_file_num ++;
minqi@2964:     if (_cur_file_num >= NumberOfGCLogFiles) _cur_file_num = 0;
minqi@2964:     jio_snprintf(_file_name, strlen(Arguments::gc_log_filename()) + 10, "%s.%d",
minqi@2964:              Arguments::gc_log_filename(), _cur_file_num);
minqi@2964:     fclose(_file);
minqi@2964:     _file = NULL;
minqi@2964:   }
minqi@2964:   _file = fopen(_file_name, "w");
minqi@2964:   if (_file != NULL) {
minqi@2964:     _bytes_writen = 0L;
minqi@2964:     _need_close = true;
minqi@2964:   } else {
minqi@2964:     tty->print_cr("failed to open rotation log file %s due to %s\n",
minqi@2964:                   _file_name, strerror(errno));
minqi@2964:     _need_close = false;
minqi@2964:   }
minqi@2964: }
minqi@2964: 
duke@435: defaultStream* defaultStream::instance = NULL;
duke@435: int defaultStream::_output_fd = 1;
duke@435: int defaultStream::_error_fd  = 2;
duke@435: FILE* defaultStream::_output_stream = stdout;
duke@435: FILE* defaultStream::_error_stream  = stderr;
duke@435: 
duke@435: #define LOG_MAJOR_VERSION 160
duke@435: #define LOG_MINOR_VERSION 1
duke@435: 
duke@435: void defaultStream::init() {
duke@435:   _inited = true;
duke@435:   if (LogVMOutput || LogCompilation) {
duke@435:     init_log();
duke@435:   }
duke@435: }
duke@435: 
duke@435: bool defaultStream::has_log_file() {
duke@435:   // lazily create log file (at startup, LogVMOutput is false even
duke@435:   // if +LogVMOutput is used, because the flags haven't been parsed yet)
duke@435:   // For safer printing during fatal error handling, do not init logfile
duke@435:   // if a VM error has been reported.
duke@435:   if (!_inited && !is_error_reported())  init();
duke@435:   return _log_file != NULL;
duke@435: }
duke@435: 
coleenp@1788: static const char* make_log_name(const char* log_name, const char* force_directory) {
duke@435:   const char* basename = log_name;
duke@435:   char file_sep = os::file_separator()[0];
duke@435:   const char* cp;
duke@435:   for (cp = log_name; *cp != '\0'; cp++) {
duke@435:     if (*cp == '/' || *cp == file_sep) {
duke@435:       basename = cp+1;
duke@435:     }
duke@435:   }
duke@435:   const char* nametail = log_name;
duke@435: 
coleenp@1788:   // Compute buffer length
coleenp@1788:   size_t buffer_length;
coleenp@1788:   if (force_directory != NULL) {
coleenp@1788:     buffer_length = strlen(force_directory) + strlen(os::file_separator()) +
coleenp@1788:                     strlen(basename) + 1;
coleenp@1788:   } else {
coleenp@1788:     buffer_length = strlen(log_name) + 1;
coleenp@1788:   }
coleenp@1788: 
coleenp@1788:   const char* star = strchr(basename, '*');
coleenp@1788:   int star_pos = (star == NULL) ? -1 : (star - nametail);
never@2682:   int skip = 1;
never@2682:   if (star == NULL) {
never@2682:     // Try %p
never@2682:     star = strstr(basename, "%p");
never@2682:     if (star != NULL) {
never@2682:       skip = 2;
never@2682:     }
never@2682:   }
never@2682:   star_pos = (star == NULL) ? -1 : (star - nametail);
coleenp@1788: 
coleenp@1788:   char pid[32];
coleenp@1788:   if (star_pos >= 0) {
coleenp@1788:     jio_snprintf(pid, sizeof(pid), "%u", os::current_process_id());
coleenp@1788:     buffer_length += strlen(pid);
coleenp@1788:   }
coleenp@1788: 
coleenp@1788:   // Create big enough buffer.
coleenp@1788:   char *buf = NEW_C_HEAP_ARRAY(char, buffer_length);
coleenp@1788: 
duke@435:   strcpy(buf, "");
duke@435:   if (force_directory != NULL) {
duke@435:     strcat(buf, force_directory);
duke@435:     strcat(buf, os::file_separator());
duke@435:     nametail = basename;       // completely skip directory prefix
duke@435:   }
duke@435: 
duke@435:   if (star_pos >= 0) {
never@2682:     // convert foo*bar.log or foo%pbar.log to foo123bar.log
duke@435:     int buf_pos = (int) strlen(buf);
duke@435:     strncpy(&buf[buf_pos], nametail, star_pos);
coleenp@1788:     strcpy(&buf[buf_pos + star_pos], pid);
never@2682:     nametail += star_pos + skip;  // skip prefix and pid format
duke@435:   }
duke@435: 
duke@435:   strcat(buf, nametail);      // append rest of name, or all of name
duke@435:   return buf;
duke@435: }
duke@435: 
duke@435: void defaultStream::init_log() {
duke@435:   // %%% Need a MutexLocker?
duke@435:   const char* log_name = LogFile != NULL ? LogFile : "hotspot.log";
coleenp@1788:   const char* try_name = make_log_name(log_name, NULL);
duke@435:   fileStream* file = new(ResourceObj::C_HEAP) fileStream(try_name);
duke@435:   if (!file->is_open()) {
duke@435:     // Try again to open the file.
duke@435:     char warnbuf[O_BUFLEN*2];
coleenp@1788:     jio_snprintf(warnbuf, sizeof(warnbuf),
coleenp@1788:                  "Warning:  Cannot open log file: %s\n", try_name);
duke@435:     // Note:  This feature is for maintainer use only.  No need for L10N.
duke@435:     jio_print(warnbuf);
coleenp@1788:     FREE_C_HEAP_ARRAY(char, try_name);
never@2682:     try_name = make_log_name("hs_pid%p.log", os::get_temp_directory());
coleenp@1788:     jio_snprintf(warnbuf, sizeof(warnbuf),
coleenp@1788:                  "Warning:  Forcing option -XX:LogFile=%s\n", try_name);
duke@435:     jio_print(warnbuf);
duke@435:     delete file;
duke@435:     file = new(ResourceObj::C_HEAP) fileStream(try_name);
coleenp@1788:     FREE_C_HEAP_ARRAY(char, try_name);
duke@435:   }
duke@435:   if (file->is_open()) {
duke@435:     _log_file = file;
duke@435:     xmlStream* xs = new(ResourceObj::C_HEAP) xmlStream(file);
duke@435:     _outer_xmlStream = xs;
duke@435:     if (this == tty)  xtty = xs;
duke@435:     // Write XML header.
duke@435:     xs->print_cr("<?xml version='1.0' encoding='UTF-8'?>");
duke@435:     // (For now, don't bother to issue a DTD for this private format.)
duke@435:     jlong time_ms = os::javaTimeMillis() - tty->time_stamp().milliseconds();
duke@435:     // %%% Should be: jlong time_ms = os::start_time_milliseconds(), if
duke@435:     // we ever get round to introduce that method on the os class
duke@435:     xs->head("hotspot_log version='%d %d'"
duke@435:              " process='%d' time_ms='"INT64_FORMAT"'",
duke@435:              LOG_MAJOR_VERSION, LOG_MINOR_VERSION,
duke@435:              os::current_process_id(), time_ms);
duke@435:     // Write VM version header immediately.
duke@435:     xs->head("vm_version");
duke@435:     xs->head("name"); xs->text("%s", VM_Version::vm_name()); xs->cr();
duke@435:     xs->tail("name");
duke@435:     xs->head("release"); xs->text("%s", VM_Version::vm_release()); xs->cr();
duke@435:     xs->tail("release");
duke@435:     xs->head("info"); xs->text("%s", VM_Version::internal_vm_info_string()); xs->cr();
duke@435:     xs->tail("info");
duke@435:     xs->tail("vm_version");
duke@435:     // Record information about the command-line invocation.
duke@435:     xs->head("vm_arguments");  // Cf. Arguments::print_on()
duke@435:     if (Arguments::num_jvm_flags() > 0) {
duke@435:       xs->head("flags");
duke@435:       Arguments::print_jvm_flags_on(xs->text());
duke@435:       xs->tail("flags");
duke@435:     }
duke@435:     if (Arguments::num_jvm_args() > 0) {
duke@435:       xs->head("args");
duke@435:       Arguments::print_jvm_args_on(xs->text());
duke@435:       xs->tail("args");
duke@435:     }
duke@435:     if (Arguments::java_command() != NULL) {
duke@435:       xs->head("command"); xs->text()->print_cr("%s", Arguments::java_command());
duke@435:       xs->tail("command");
duke@435:     }
duke@435:     if (Arguments::sun_java_launcher() != NULL) {
duke@435:       xs->head("launcher"); xs->text()->print_cr("%s", Arguments::sun_java_launcher());
duke@435:       xs->tail("launcher");
duke@435:     }
duke@435:     if (Arguments::system_properties() !=  NULL) {
duke@435:       xs->head("properties");
duke@435:       // Print it as a java-style property list.
duke@435:       // System properties don't generally contain newlines, so don't bother with unparsing.
duke@435:       for (SystemProperty* p = Arguments::system_properties(); p != NULL; p = p->next()) {
duke@435:         xs->text()->print_cr("%s=%s", p->key(), p->value());
duke@435:       }
duke@435:       xs->tail("properties");
duke@435:     }
duke@435:     xs->tail("vm_arguments");
duke@435:     // tty output per se is grouped under the <tty>...</tty> element.
duke@435:     xs->head("tty");
duke@435:     // All further non-markup text gets copied to the tty:
duke@435:     xs->_text = this;  // requires friend declaration!
duke@435:   } else {
duke@435:     delete(file);
duke@435:     // and leave xtty as NULL
duke@435:     LogVMOutput = false;
duke@435:     DisplayVMOutput = true;
duke@435:     LogCompilation = false;
duke@435:   }
duke@435: }
duke@435: 
duke@435: // finish_log() is called during normal VM shutdown. finish_log_on_error() is
duke@435: // called by ostream_abort() after a fatal error.
duke@435: //
duke@435: void defaultStream::finish_log() {
duke@435:   xmlStream* xs = _outer_xmlStream;
duke@435:   xs->done("tty");
duke@435: 
duke@435:   // Other log forks are appended here, at the End of Time:
duke@435:   CompileLog::finish_log(xs->out());  // write compile logging, if any, now
duke@435: 
duke@435:   xs->done("hotspot_log");
duke@435:   xs->flush();
duke@435: 
duke@435:   fileStream* file = _log_file;
duke@435:   _log_file = NULL;
duke@435: 
duke@435:   delete _outer_xmlStream;
duke@435:   _outer_xmlStream = NULL;
duke@435: 
duke@435:   file->flush();
duke@435:   delete file;
duke@435: }
duke@435: 
duke@435: void defaultStream::finish_log_on_error(char *buf, int buflen) {
duke@435:   xmlStream* xs = _outer_xmlStream;
duke@435: 
duke@435:   if (xs && xs->out()) {
duke@435: 
duke@435:     xs->done_raw("tty");
duke@435: 
duke@435:     // Other log forks are appended here, at the End of Time:
duke@435:     CompileLog::finish_log_on_error(xs->out(), buf, buflen);  // write compile logging, if any, now
duke@435: 
duke@435:     xs->done_raw("hotspot_log");
duke@435:     xs->flush();
duke@435: 
duke@435:     fileStream* file = _log_file;
duke@435:     _log_file = NULL;
duke@435:     _outer_xmlStream = NULL;
duke@435: 
duke@435:     if (file) {
duke@435:       file->flush();
duke@435: 
duke@435:       // Can't delete or close the file because delete and fclose aren't
duke@435:       // async-safe. We are about to die, so leave it to the kernel.
duke@435:       // delete file;
duke@435:     }
duke@435:   }
duke@435: }
duke@435: 
duke@435: intx defaultStream::hold(intx writer_id) {
duke@435:   bool has_log = has_log_file();  // check before locking
duke@435:   if (// impossible, but who knows?
duke@435:       writer_id == NO_WRITER ||
duke@435: 
duke@435:       // bootstrap problem
duke@435:       tty_lock == NULL ||
duke@435: 
duke@435:       // can't grab a lock or call Thread::current() if TLS isn't initialized
duke@435:       ThreadLocalStorage::thread() == NULL ||
duke@435: 
duke@435:       // developer hook
duke@435:       !SerializeVMOutput ||
duke@435: 
duke@435:       // VM already unhealthy
duke@435:       is_error_reported() ||
duke@435: 
duke@435:       // safepoint == global lock (for VM only)
duke@435:       (SafepointSynchronize::is_synchronizing() &&
duke@435:        Thread::current()->is_VM_thread())
duke@435:       ) {
duke@435:     // do not attempt to lock unless we know the thread and the VM is healthy
duke@435:     return NO_WRITER;
duke@435:   }
duke@435:   if (_writer == writer_id) {
duke@435:     // already held, no need to re-grab the lock
duke@435:     return NO_WRITER;
duke@435:   }
duke@435:   tty_lock->lock_without_safepoint_check();
duke@435:   // got the lock
duke@435:   if (writer_id != _last_writer) {
duke@435:     if (has_log) {
duke@435:       _log_file->bol();
duke@435:       // output a hint where this output is coming from:
duke@435:       _log_file->print_cr("<writer thread='"INTX_FORMAT"'/>", writer_id);
duke@435:     }
duke@435:     _last_writer = writer_id;
duke@435:   }
duke@435:   _writer = writer_id;
duke@435:   return writer_id;
duke@435: }
duke@435: 
duke@435: void defaultStream::release(intx holder) {
duke@435:   if (holder == NO_WRITER) {
duke@435:     // nothing to release:  either a recursive lock, or we scribbled (too bad)
duke@435:     return;
duke@435:   }
duke@435:   if (_writer != holder) {
duke@435:     return;  // already unlocked, perhaps via break_tty_lock_for_safepoint
duke@435:   }
duke@435:   _writer = NO_WRITER;
duke@435:   tty_lock->unlock();
duke@435: }
duke@435: 
duke@435: 
duke@435: // Yuck:  jio_print does not accept char*/len.
duke@435: static void call_jio_print(const char* s, size_t len) {
duke@435:   char buffer[O_BUFLEN+100];
duke@435:   if (len > sizeof(buffer)-1) {
duke@435:     warning("increase O_BUFLEN in ostream.cpp -- output truncated");
duke@435:     len = sizeof(buffer)-1;
duke@435:   }
duke@435:   strncpy(buffer, s, len);
duke@435:   buffer[len] = '\0';
duke@435:   jio_print(buffer);
duke@435: }
duke@435: 
duke@435: 
duke@435: void defaultStream::write(const char* s, size_t len) {
duke@435:   intx thread_id = os::current_thread_id();
duke@435:   intx holder = hold(thread_id);
duke@435: 
duke@435:   if (DisplayVMOutput &&
duke@435:       (_outer_xmlStream == NULL || !_outer_xmlStream->inside_attrs())) {
duke@435:     // print to output stream. It can be redirected by a vfprintf hook
duke@435:     if (s[len] == '\0') {
duke@435:       jio_print(s);
duke@435:     } else {
duke@435:       call_jio_print(s, len);
duke@435:     }
duke@435:   }
duke@435: 
duke@435:   // print to log file
duke@435:   if (has_log_file()) {
duke@435:     int nl0 = _newlines;
duke@435:     xmlTextStream::write(s, len);
duke@435:     // flush the log file too, if there were any newlines
duke@435:     if (nl0 != _newlines){
duke@435:       flush();
duke@435:     }
duke@435:   } else {
duke@435:     update_position(s, len);
duke@435:   }
duke@435: 
duke@435:   release(holder);
duke@435: }
duke@435: 
duke@435: intx ttyLocker::hold_tty() {
duke@435:   if (defaultStream::instance == NULL)  return defaultStream::NO_WRITER;
duke@435:   intx thread_id = os::current_thread_id();
duke@435:   return defaultStream::instance->hold(thread_id);
duke@435: }
duke@435: 
duke@435: void ttyLocker::release_tty(intx holder) {
duke@435:   if (holder == defaultStream::NO_WRITER)  return;
duke@435:   defaultStream::instance->release(holder);
duke@435: }
duke@435: 
never@2570: bool ttyLocker::release_tty_if_locked() {
never@2570:   intx thread_id = os::current_thread_id();
never@2570:   if (defaultStream::instance->writer() == thread_id) {
never@2570:     // release the lock and return true so callers know if was
never@2570:     // previously held.
never@2570:     release_tty(thread_id);
never@2570:     return true;
never@2570:   }
never@2570:   return false;
never@2570: }
never@2570: 
duke@435: void ttyLocker::break_tty_lock_for_safepoint(intx holder) {
duke@435:   if (defaultStream::instance != NULL &&
duke@435:       defaultStream::instance->writer() == holder) {
duke@435:     if (xtty != NULL) {
duke@435:       xtty->print_cr("<!-- safepoint while printing -->");
duke@435:     }
duke@435:     defaultStream::instance->release(holder);
duke@435:   }
duke@435:   // (else there was no lock to break)
duke@435: }
duke@435: 
duke@435: void ostream_init() {
duke@435:   if (defaultStream::instance == NULL) {
duke@435:     defaultStream::instance = new(ResourceObj::C_HEAP) defaultStream();
duke@435:     tty = defaultStream::instance;
duke@435: 
duke@435:     // We want to ensure that time stamps in GC logs consider time 0
duke@435:     // the time when the JVM is initialized, not the first time we ask
duke@435:     // for a time stamp. So, here, we explicitly update the time stamp
duke@435:     // of tty.
duke@435:     tty->time_stamp().update_to(1);
duke@435:   }
duke@435: }
duke@435: 
duke@435: void ostream_init_log() {
duke@435:   // For -Xloggc:<file> option - called in runtime/thread.cpp
duke@435:   // Note : this must be called AFTER ostream_init()
duke@435: 
duke@435:   gclog_or_tty = tty; // default to tty
duke@435:   if (Arguments::gc_log_filename() != NULL) {
minqi@2964:     fileStream * gclog  = UseGCLogFileRotation ?
minqi@2964:                           new(ResourceObj::C_HEAP)
minqi@2964:                              rotatingFileStream(Arguments::gc_log_filename()) :
minqi@2964:                           new(ResourceObj::C_HEAP)
minqi@2964:                              fileStream(Arguments::gc_log_filename());
duke@435:     if (gclog->is_open()) {
duke@435:       // now we update the time stamp of the GC log to be synced up
duke@435:       // with tty.
duke@435:       gclog->time_stamp().update_to(tty->time_stamp().ticks());
duke@435:     }
minqi@2964:     gclog_or_tty = gclog;
duke@435:   }
duke@435: 
duke@435:   // If we haven't lazily initialized the logfile yet, do it now,
duke@435:   // to avoid the possibility of lazy initialization during a VM
duke@435:   // crash, which can affect the stability of the fatal error handler.
duke@435:   defaultStream::instance->has_log_file();
duke@435: }
duke@435: 
duke@435: // ostream_exit() is called during normal VM exit to finish log files, flush
duke@435: // output and free resource.
duke@435: void ostream_exit() {
duke@435:   static bool ostream_exit_called = false;
duke@435:   if (ostream_exit_called)  return;
duke@435:   ostream_exit_called = true;
duke@435:   if (gclog_or_tty != tty) {
duke@435:       delete gclog_or_tty;
duke@435:   }
duke@435:   {
duke@435:       // we temporaly disable PrintMallocFree here
duke@435:       // as otherwise it'll lead to using of almost deleted
duke@435:       // tty or defaultStream::instance in logging facility
duke@435:       // of HeapFree(), see 6391258
duke@435:       DEBUG_ONLY(FlagSetting fs(PrintMallocFree, false);)
duke@435:       if (tty != defaultStream::instance) {
duke@435:           delete tty;
duke@435:       }
duke@435:       if (defaultStream::instance != NULL) {
duke@435:           delete defaultStream::instance;
duke@435:       }
duke@435:   }
duke@435:   tty = NULL;
duke@435:   xtty = NULL;
duke@435:   gclog_or_tty = NULL;
duke@435:   defaultStream::instance = NULL;
duke@435: }
duke@435: 
duke@435: // ostream_abort() is called by os::abort() when VM is about to die.
duke@435: void ostream_abort() {
duke@435:   // Here we can't delete gclog_or_tty and tty, just flush their output
duke@435:   if (gclog_or_tty) gclog_or_tty->flush();
duke@435:   if (tty) tty->flush();
duke@435: 
duke@435:   if (defaultStream::instance != NULL) {
duke@435:     static char buf[4096];
duke@435:     defaultStream::instance->finish_log_on_error(buf, sizeof(buf));
duke@435:   }
duke@435: }
duke@435: 
duke@435: staticBufferStream::staticBufferStream(char* buffer, size_t buflen,
duke@435:                                        outputStream *outer_stream) {
duke@435:   _buffer = buffer;
duke@435:   _buflen = buflen;
duke@435:   _outer_stream = outer_stream;
kvn@2702:   // compile task prints time stamp relative to VM start
kvn@2702:   _stamp.update_to(1);
duke@435: }
duke@435: 
duke@435: void staticBufferStream::write(const char* c, size_t len) {
duke@435:   _outer_stream->print_raw(c, (int)len);
duke@435: }
duke@435: 
duke@435: void staticBufferStream::flush() {
duke@435:   _outer_stream->flush();
duke@435: }
duke@435: 
duke@435: void staticBufferStream::print(const char* format, ...) {
duke@435:   va_list ap;
duke@435:   va_start(ap, format);
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(_buffer, _buflen, format, ap, false, len);
duke@435:   write(str, len);
duke@435:   va_end(ap);
duke@435: }
duke@435: 
duke@435: void staticBufferStream::print_cr(const char* format, ...) {
duke@435:   va_list ap;
duke@435:   va_start(ap, format);
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(_buffer, _buflen, format, ap, true, len);
duke@435:   write(str, len);
duke@435:   va_end(ap);
duke@435: }
duke@435: 
duke@435: void staticBufferStream::vprint(const char *format, va_list argptr) {
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(_buffer, _buflen, format, argptr, false, len);
duke@435:   write(str, len);
duke@435: }
duke@435: 
duke@435: void staticBufferStream::vprint_cr(const char* format, va_list argptr) {
duke@435:   size_t len;
duke@435:   const char* str = do_vsnprintf(_buffer, _buflen, format, argptr, true, len);
duke@435:   write(str, len);
duke@435: }
duke@435: 
never@657: bufferedStream::bufferedStream(size_t initial_size, size_t bufmax) : outputStream() {
duke@435:   buffer_length = initial_size;
duke@435:   buffer        = NEW_C_HEAP_ARRAY(char, buffer_length);
duke@435:   buffer_pos    = 0;
duke@435:   buffer_fixed  = false;
never@657:   buffer_max    = bufmax;
duke@435: }
duke@435: 
never@657: bufferedStream::bufferedStream(char* fixed_buffer, size_t fixed_buffer_size, size_t bufmax) : outputStream() {
duke@435:   buffer_length = fixed_buffer_size;
duke@435:   buffer        = fixed_buffer;
duke@435:   buffer_pos    = 0;
duke@435:   buffer_fixed  = true;
never@657:   buffer_max    = bufmax;
duke@435: }
duke@435: 
duke@435: void bufferedStream::write(const char* s, size_t len) {
never@657: 
never@657:   if(buffer_pos + len > buffer_max) {
never@657:     flush();
never@657:   }
never@657: 
duke@435:   size_t end = buffer_pos + len;
duke@435:   if (end >= buffer_length) {
duke@435:     if (buffer_fixed) {
duke@435:       // if buffer cannot resize, silently truncate
duke@435:       len = buffer_length - buffer_pos - 1;
duke@435:     } else {
duke@435:       // For small overruns, double the buffer.  For larger ones,
duke@435:       // increase to the requested size.
duke@435:       if (end < buffer_length * 2) {
duke@435:         end = buffer_length * 2;
duke@435:       }
duke@435:       buffer = REALLOC_C_HEAP_ARRAY(char, buffer, end);
duke@435:       buffer_length = end;
duke@435:     }
duke@435:   }
duke@435:   memcpy(buffer + buffer_pos, s, len);
duke@435:   buffer_pos += len;
duke@435:   update_position(s, len);
duke@435: }
duke@435: 
duke@435: char* bufferedStream::as_string() {
duke@435:   char* copy = NEW_RESOURCE_ARRAY(char, buffer_pos+1);
duke@435:   strncpy(copy, buffer, buffer_pos);
duke@435:   copy[buffer_pos] = 0;  // terminating null
duke@435:   return copy;
duke@435: }
duke@435: 
duke@435: bufferedStream::~bufferedStream() {
duke@435:   if (!buffer_fixed) {
duke@435:     FREE_C_HEAP_ARRAY(char, buffer);
duke@435:   }
duke@435: }
duke@435: 
duke@435: #ifndef PRODUCT
duke@435: 
duke@435: #if defined(SOLARIS) || defined(LINUX)
duke@435: #include <sys/types.h>
duke@435: #include <sys/socket.h>
duke@435: #include <netinet/in.h>
duke@435: #include <arpa/inet.h>
duke@435: #endif
duke@435: 
duke@435: // Network access
never@657: networkStream::networkStream() : bufferedStream(1024*10, 1024*10) {
duke@435: 
duke@435:   _socket = -1;
duke@435: 
ikrylov@2322:   int result = os::socket(AF_INET, SOCK_STREAM, 0);
duke@435:   if (result <= 0) {
duke@435:     assert(false, "Socket could not be created!");
duke@435:   } else {
duke@435:     _socket = result;
duke@435:   }
duke@435: }
duke@435: 
duke@435: int networkStream::read(char *buf, size_t len) {
ikrylov@2322:   return os::recv(_socket, buf, (int)len, 0);
duke@435: }
duke@435: 
duke@435: void networkStream::flush() {
duke@435:   if (size() != 0) {
ikrylov@2322:     int result = os::raw_send(_socket, (char *)base(), (int)size(), 0);
never@657:     assert(result != -1, "connection error");
never@657:     assert(result == (int)size(), "didn't send enough data");
duke@435:   }
duke@435:   reset();
duke@435: }
duke@435: 
duke@435: networkStream::~networkStream() {
duke@435:   close();
duke@435: }
duke@435: 
duke@435: void networkStream::close() {
duke@435:   if (_socket != -1) {
duke@435:     flush();
ikrylov@2322:     os::socket_close(_socket);
duke@435:     _socket = -1;
duke@435:   }
duke@435: }
duke@435: 
duke@435: bool networkStream::connect(const char *ip, short port) {
duke@435: 
duke@435:   struct sockaddr_in server;
duke@435:   server.sin_family = AF_INET;
duke@435:   server.sin_port = htons(port);
duke@435: 
duke@435:   server.sin_addr.s_addr = inet_addr(ip);
jcoomes@515:   if (server.sin_addr.s_addr == (uint32_t)-1) {
ikrylov@2322:     struct hostent* host = os::get_host_by_name((char*)ip);
duke@435:     if (host != NULL) {
duke@435:       memcpy(&server.sin_addr, host->h_addr_list[0], host->h_length);
duke@435:     } else {
duke@435:       return false;
duke@435:     }
duke@435:   }
duke@435: 
duke@435: 
ikrylov@2322:   int result = os::connect(_socket, (struct sockaddr*)&server, sizeof(struct sockaddr_in));
duke@435:   return (result >= 0);
duke@435: }
duke@435: 
duke@435: #endif