VirtualBox

Changes between Initial Version and Version 1 of Ticket #15949, comment 11


Ignore:
Timestamp:
Mar 8, 2019 8:56:09 AM (6 years ago)
Author:
gombara

Legend:

Unmodified
Added
Removed
Modified
  • Ticket #15949, comment 11

    initial v1  
    11Yes, I want both date and time.Because it's not easy to calculate the date if it's a long-time running vm,I need to add hours to the base time in 2nd line.
    2 
    3 
    4 Replying to [comment:10 socratis]:
    5 > You posted the comment in your duplicate ticket #18474, but I thought it would be better to reply here.
    6 >
    7 > Replying to [https://www.215389.xyz/ticket/18474#comment:3 数组越界]:
    8 > > ''The problem is that if I used the following command''
    9 > > {{{
    10 > > VBoxManage debugvm "vm name" logflags --release time
    11 > > }}}
    12 > > ''In logs there is only time instead of date and time, since our vms have run a quite long time, I need to find out what happened inside release log when guest restarted.''
    13 >
    14 > According to the User Manual, ch. [https://www.215389.xyz/manual/ch08.html#vboxmanage-debugvm 8.42 VBoxManage debugvm]:
    15 > {{{
    16 > VBoxManage debugvm <uuid|vmname> logflags [[--release] | [--debug]] [flags...]
    17 > }}}
    18 > and in the "`flags`" section, I can see:
    19 > > `time`
    20 > >   Prefix each log line with the current UTC wall '''time'''.
    21 > My understanding is that you want the '''date''' as well. \\
    22 > \\
    23 > \\
    24 > I entered the following command:
    25 > {{{
    26 > VBoxManage startvm <VM>; VBoxManage debugvm <VM> logflags --release time
    27 > }}}
    28 > and although it doesn't start the time logging from the get-go, in my log I see the following:
    29 >
    30 > `00:00:00.918239 VirtualBox VM 6.0.97 r129161 darwin.amd64 (Mar  4 2019 14:44:21) ...` \\
    31 > `00:00:00.918243 Log opened `'''__`2019-03-06T07:57:11.964346000Z`__''' \\
    32 > `...` \\
    33 > `00:00:01.062177 ******************* End of VT-x features ********************` \\
    34 > `00:00:01.062192 VMEmt: Halt method global1 (5)` \\
    35 > `00:00:01.062261 VMEmt: HaltedGlobal1 config: cNsSpinBlockThresholdCfg=2000` \\
    36 > `00:00:01.062267 Changing the VM state from 'CREATING' to 'CREATED'` \\
    37 > `00:00:01.067689 Changing the VM state from 'CREATED' to 'POWERING_ON'` \\
    38 > `00:00:01.067754 Changing the VM state from 'POWERING_ON' to 'RUNNING'` \\
    39 > `00:00:01.067772 Console: Machine state changed to 'Running'` \\
    40 > `00:00:01.070337 VMMDev: Guest Log: BIOS: VirtualBox 6.0.97` \\
    41 > `00:00:01.070472 PCI: Setting up resources and interrupts` \\
    42 > `00:00:01.070876 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)` \\
    43 > `00:00:01.084837 Display::handleDisplayResize: uScreenId=0 ...` \\
    44 > `00:00:01.084900 GUI: UIFrameBufferPrivate::NotifyChange: ...` \\
    45 > `00:00:01.085589 VMMDev: Guest Log: CPUID EDX: 0x178bfbbf` \\
    46 > `00:00:01.085638 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 ...` \\
    47 > `00:00:01.085694 PIIX3 ATA: Ctl#0: finished processing RESET` \\
    48 > `00:00:01.086284 VMMDev: Guest Log: BIOS: ata0-0: ...` \\
    49 > `00:00:01.086977 PIIX3 ATA: Ctl#0: RESET, DevSel=1 AIOIf=0 ...` \\
    50 > `00:00:01.087026 PIIX3 ATA: Ctl#0: finished processing RESET` \\
    51 > `00:00:01.087132 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 ...` \\
    52 > `00:00:01.087192 PIIX3 ATA: Ctl#1: finished processing RESET` \\
    53 > `00:00:01.090711 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)` \\
    54 > `00:00:01.106529 Display::handleDisplayResize: uScreenId=0 ...` \\
    55 > `00:00:01.106579 GUI: UIFrameBufferPrivate::NotifyChange: ...` \\
    56 > '''__`07:57:12.619070`__''' `00:00:01.572977 GUI: UIMachineViewNormal::resendSizeHint...` \\
    57 > '''__`07:57:12.619126`__''' ` 00:00:01.573031 VMMDev: SetVideoModeHint: ...` \\
    58 > '''__`07:57:12.619155`__''' ` 00:00:01.573061 GUI: UIMachineView::sltHandleNotifyChange:...` \\
    59 > `...` \\
    60 >
    61 > So, after the "`00:00:01.106579`" time mark, the UTC time is prepended to the relative time i the log.
    62 >
    63 > A couple (maybe more) year ago I remember seeing a VBox.log where the UTC time was logged from the first line, I asked, never got an answer, and I never bothered to look how it was done.
    64 >
    65 > Now, you can combine the log start time (the 2nd line in any log) with the time and get something meaningful?
    66 >
    67 > My understanding is that you are proposing this:
    68 >
    69 > `...` \\
    70 > `00:00:01.106579 GUI: UIFrameBufferPrivate::NotifyChange: ...` \\
    71 > '''__`2019-03-06T07:57:12.619070`__''' `00:00:01.572977 GUI: UIMachineViewNormal::resendSizeHint...` \\
    72 > '''__`2019-03-06T07:57:12.619126`__''' ` 00:00:01.573031 VMMDev: SetVideoModeHint: ...` \\
    73 > '''__`2019-03-06T07:57:12.619155`__''' ` 00:00:01.573061 GUI: UIMachineView::sltHandleNotifyChange:...` \\
    74 > `...`
    75 >
    76 > Right?

© 2025 Oracle Support Privacy / Do Not Sell My Info Terms of Use Trademark Policy Automated Access Etiquette