lnav icon indicating copy to clipboard operation
lnav copied to clipboard

Published 20 hours ago • verified publisher icon tstack

Crash

Open Modified opened this issue 1 year ago • 1 comments

Don't know why.

NB: this SQL, in the log below, looks suspicious?

"mod_rewrite.c(483" TEXT     COLLATE 'naturalnocase',

From log below, seems crashed in command_executor.cc:127 Executing SQL, caught by lnav_log.cc:502 terminate called after throwing an instance of 'std::out_of_range', with parameters: what(): basic_string::erase: __pos (which is 83) > this->size() (which is 82).

Here's the end of crash-2022-07-12-12-47-54.170259.log:

2022-07-12T12:47:54.747 D hotkeys.cc:153 executing key sequence x50: :toggle-view pretty
2022-07-12T12:47:54.747 I command_executor.cc:100 Executing: toggle-view pretty
2022-07-12T12:47:54.747 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.747 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.747 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.747 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.748 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.749 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.750 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.750 D log_format.cc:885 not rewriting value with invalid origin -- c_ip
2022-07-12T12:47:54.750 D log_format.cc:885 not rewriting value with invalid origin -- c_port
2022-07-12T12:47:54.750 D log_format.cc:885 not rewriting value with invalid origin -- cs_uri_query
2022-07-12T12:47:54.773 D log_vtab_impl.cc:124 log_vtab_impl.get_table_statement() -> CREATE TABLE logline  (
  log_line        INTEGER  PRIMARY KEY,            -- The line number for the log message
  log_part        TEXT     COLLATE naturalnocase,  -- The partition the message is in
  log_time        DATETIME,                        -- The adjusted timestamp for the log message
  log_actual_time DATETIME HIDDEN,                 -- The timestamp from the original log file for this message
  log_idle_msecs  INTEGER,                         -- The difference in time between this messages and the previous
  log_level       TEXT     COLLATE loglevel,       -- The log message level
  log_mark        BOOLEAN,                         -- True if the log message was marked
  log_comment     TEXT,                            -- The comment for this message
  log_tags        TEXT,                            -- A JSON list of tags for this message
  log_filters     TEXT,                            -- A JSON list of filter IDs that matched this message
  -- BEGIN Format-specific fields:
  c_ip              TEXT     COLLATE 'ipaddress'    , -- The client IP address
  c_port            INTEGER  COLLATE 'BINARY'       ,
  module            TEXT     COLLATE 'BINARY'       ,
  pid               INTEGER  COLLATE 'BINARY'       ,
  tid               INTEGER  COLLATE 'BINARY'       , -- The thread id
  log_msg_instance  INTEGER  COLLATE 'BINARY'       ,
  "mod_rewrite.c(483" TEXT     COLLATE 'naturalnocase',
  prefix            TEXT     COLLATE 'naturalnocase',
  referer           TEXT     COLLATE 'naturalnocase',

  -- END Format-specific fields
  log_path        TEXT HIDDEN COLLATE naturalnocase, -- The path to the log file this message is from
  log_text        TEXT HIDDEN,                       -- The full text of the log message
  log_body        TEXT HIDDEN                        -- The body of the log message
);

2022-07-12T12:47:54.774 D log_vtab_impl.cc:210 creating log format table: logline = 0x558262df7098
2022-07-12T12:47:54.782 I command_executor.cc:127 Executing SQL: SELECT :sc_status || ' (' || (SELECT message FROM http_status_codes WHERE status = :sc_status) || ') '
2022-07-12T12:47:54.890 E lnav_log.cc:502 terminate called after throwing an instance of 'std::out_of_range'
  what():  basic_string::erase: __pos (which is 83) > this->size() (which is 82)
2022-07-12T12:47:54.890 E lnav_log.cc:502 
2022-07-12T12:47:54.890 E lnav_log.cc:352 Received signal: 6
lnav(+0x206ef9)[0x5582612eeef9]
/lib/x86_64-linux-gnu/libc.so.6(+0x38920)[0x7fbca0e19920]
/lib/x86_64-linux-gnu/libc.so.6(gsignal+0x141)[0x7fbca0e198a1]
/lib/x86_64-linux-gnu/libc.so.6(abort+0x112)[0x7fbca0e03546]
/usr/lib/x86_64-linux-gnu/libstdc++.so.6(+0x9d909)[0x7fbca11bd909]
/usr/lib/x86_64-linux-gnu/libstdc++.so.6(+0xa8f2a)[0x7fbca11c8f2a]
/usr/lib/x86_64-linux-gnu/libstdc++.so.6(+0xa8f95)[0x7fbca11c8f95]
/usr/lib/x86_64-linux-gnu/libstdc++.so.6(+0xa91e8)[0x7fbca11c91e8]
/usr/lib/x86_64-linux-gnu/libstdc++.so.6(+0xa0265)[0x7fbca11c0265]
lnav(+0x108069)[0x5582611f0069]
lnav(+0x13cfb2)[0x558261224fb2]
lnav(+0x1cc5a9)[0x5582612b45a9]
lnav(+0x1cd5bd)[0x5582612b55bd]
lnav(+0xce13d)[0x5582611b613d]
lnav(+0x82462)[0x55826116a462]
lnav(+0x86d66)[0x55826116ed66]
lnav(+0xb5d20)[0x55826119dd20]
lnav(+0xb6c5c)[0x55826119ec5c]
lnav(+0xc0b9c)[0x5582611a8b9c]
lnav(+0x32051)[0x55826111a051]
/lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xcd)[0x7fbca0e047fd]
lnav(+0x3ed6a)[0x558261126d6a]
2022-07-12T12:47:54.893 I lnav_log.cc:186 uname:
2022-07-12T12:47:54.893 I lnav_log.cc:187   sysname=Linux
2022-07-12T12:47:54.893 I lnav_log.cc:188   nodename=…
2022-07-12T12:47:54.893 I lnav_log.cc:189   machine=x86_64
2022-07-12T12:47:54.893 I lnav_log.cc:190   release=5.16.0-6-amd64
2022-07-12T12:47:54.893 I lnav_log.cc:191   version=#1 SMP PREEMPT Debian 5.16.18-1 (2022-03-29)
2022-07-12T12:47:54.893 I lnav_log.cc:192 PCRE:
2022-07-12T12:47:54.893 I lnav_log.cc:193   jit=1
2022-07-12T12:47:54.893 I lnav_log.cc:194   jittarget=x86 64bit (little endian + unaligned)
2022-07-12T12:47:54.893 I lnav_log.cc:195 Environment:
2022-07-12T12:47:54.893 I lnav_log.cc:196   HOME=/home/…
2022-07-12T12:47:54.893 I lnav_log.cc:197   XDG_CONFIG_HOME=(null)
2022-07-12T12:47:54.893 I lnav_log.cc:198   LANG=en_US.UTF-8
2022-07-12T12:47:54.893 I lnav_log.cc:199   PATH=/home/ilan/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
2022-07-12T12:47:54.893 I lnav_log.cc:200   TERM=xterm-256color
2022-07-12T12:47:54.893 I lnav_log.cc:201   TZ=(null)
2022-07-12T12:47:54.893 I lnav_log.cc:202 Process:
2022-07-12T12:47:54.893 I lnav_log.cc:203   pid=170259
2022-07-12T12:47:54.893 I lnav_log.cc:204   ppid=45861
2022-07-12T12:47:54.893 I lnav_log.cc:205   pgrp=170259
2022-07-12T12:47:54.893 I lnav_log.cc:206   uid=1000
2022-07-12T12:47:54.893 I lnav_log.cc:207   gid=1000
2022-07-12T12:47:54.893 I lnav_log.cc:208   euid=1000
2022-07-12T12:47:54.893 I lnav_log.cc:209   egid=1000
2022-07-12T12:47:54.893 I lnav_log.cc:214   cwd=/…/Apache/logs
2022-07-12T12:47:54.893 I lnav_log.cc:216 Executable:
2022-07-12T12:47:54.893 I lnav_log.cc:217   version=lnav 0.9.0
2022-07-12T12:47:54.893 I lnav_log.cc:220 rusage:
2022-07-12T12:47:54.893 I lnav_log.cc:220   utime=41.571460
2022-07-12T12:47:54.893 I lnav_log.cc:220   stime=27.657079
2022-07-12T12:47:54.893 I lnav_log.cc:220   maxrss=296692
2022-07-12T12:47:54.893 I lnav_log.cc:220   ixrss=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   idrss=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   isrss=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   minflt=31655
2022-07-12T12:47:54.893 I lnav_log.cc:220   majflt=85
2022-07-12T12:47:54.893 I lnav_log.cc:220   nswap=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   inblock=12104
2022-07-12T12:47:54.893 I lnav_log.cc:220   oublock=1280
2022-07-12T12:47:54.893 I lnav_log.cc:220   msgsnd=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   msgrcv=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   nsignals=0
2022-07-12T12:47:54.893 I lnav_log.cc:220   nvcsw=409876
2022-07-12T12:47:54.893 I lnav_log.cc:220   nivcsw=9682

Modified avatar Jul 12 '22 11:07 Modified

It looks like you're using v0.9.0 of lnav, which is quite old at this point. Can you please upgrade to a newer version (v0.10.1 is the latest) and see if things are working better?

tstack avatar Jul 12 '22 21:07 tstack