Hallo zusammen,
ich wollte mich mal an STR/S3 machen - und es diesmal nach Fehlversuch nicht wieder abstellen, wie bisher 
Also habe ich es im Webfrontend mal wieder eingestellt.
Beim Shutdown kommt der Rechner aber direkt wieder hoch - das Syslog scheint ein paar Hinweise zu geben, mit denen ich aber (noch) nicht so recht was anfangen kann:
Erstmal dauerts 53 Sekunden im Status "Preparing system for mem sleep" was ich recht lange finde...
Mar 24 19:00:53 vdr kernel: [ 847.716623] PM: Preparing system for mem sleep
Mar 24 19:01:46 vdr kernel: [ 848.130610] Freezing user space processes ... (elapsed 0.001 seconds) done.
Mar 24 19:01:46 vdr kernel: [ 848.132847] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Mar 24 19:01:46 vdr kernel: [ 848.134029] PM: Entering mem sleep
Dann scheint er Probleme zu haben, die Platten schlafen zu schicken:
Mar 24 19:01:46 vdr kernel: [ 878.816049] ata4.00: failed command: STANDBY IMMEDIATE
und
Mar 24 19:01:46 vdr kernel: [ 878.848051] ata5.00: failed command: STANDBY IMMEDIATE
Dann ist das suspend of devices fertig; ggf geht hier schon ein großteil der Zeit drauf (31 Sekunden):
Mar 24 19:01:46 vdr kernel: [ 879.348060] PM: suspend of devices complete after 31213.712 msecs
Aber dann gibts einen Fehler mit Stacktrace, beginnt im Log mit:
Mar 24 19:01:46 vdr kernel: [ 879.511934] ------------[ cut here ]------------
Mar 24 19:01:46 vdr kernel: [ 879.511939] WARNING: CPU: 0 PID: 2947 at /build/linux-uz1EVo/linux-3.13.0/fs/sysfs/dir.c:486 sysfs_warn_dup+0x86/0xa0()
Mar 24 19:01:46 vdr kernel: [ 879.511940] sysfs: cannot create duplicate filename '/devices/system/cpu/cpu1/cpufreq'
Mar 24 19:01:46 vdr kernel: [ 879.511975] Modules linked in: tcp_diag inet_diag autofs4 snd_hda_codec_hdmi nfsd auth_rpcgss nfs_acl nfs lockd sunrpc fscache snd_hda_codec_analog stv0299(OX) stv6110x(OX) lnbp21(OX) kvm serio_raw k8temp edac_core edac_mce_amd snd_hda_intel videobuf_dma_sg(OX) snd_hda_codec snd_hwdep videobuf_core(OX) snd_pcm v4l2_common(OX) videodev(OX) snd_page_alloc snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq rc_imon_pad(OX) snd_seq_device joydev snd_timer imon(OX) rc_core(OX) nvidia(POX) stv090x(OX) snd i2c_nforce2 soundcore drm dvb_core(OX) media(OX) shpchp lp mac_hid parport hid_generic usbhid hid usb_storage psmouse forcedeth floppy pata_acpi sata_nv pata_amd [last unloaded: cxd2099]
Mar 24 19:01:46 vdr kernel: [ 879.511978] CPU: 0 PID: 2947 Comm: s2ram Tainted: P OX 3.13.0-113-generic #160-Ubuntu
Mar 24 19:01:46 vdr kernel: [ 879.511979] Hardware name: System manufacturer System Product Name/M2NPV-VM, BIOS ASUS M2NPV-VM ACPI BIOS Revision 5005 06/02/2010
Und dann gehts direkt wieder in den Neustart:
Mar 24 19:01:46 vdr kernel: [ 879.512392] CPU1 is up
Mar 24 19:01:46 vdr kernel: [ 879.512664] ACPI: Waking up from system sleep state S3
Hier der komplette (meiner Meinung nach) relevante Ausschnitt des Syslog:
Display Spoiler
Mar 24 19:00:53 vdr kernel: [ 847.693897] PM: Syncing filesystems ... done.
Mar 24 19:00:53 vdr kernel: [ 847.716623] PM: Preparing system for mem sleep
Mar 24 19:01:46 vdr kernel: [ 848.130610] Freezing user space processes ... (elapsed 0.001 seconds) done.
Mar 24 19:01:46 vdr kernel: [ 848.132847] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Mar 24 19:01:46 vdr kernel: [ 848.134029] PM: Entering mem sleep
Mar 24 19:01:46 vdr kernel: [ 848.134052] Suspending console(s) (use no_console_suspend to debug)
Mar 24 19:01:46 vdr kernel: [ 848.134540] sd 5:0:0:0: [sdc] Synchronizing SCSI cache
Mar 24 19:01:46 vdr kernel: [ 848.134646] sd 4:0:0:0: [sdb] Synchronizing SCSI cache
Mar 24 19:01:46 vdr kernel: [ 848.134702] sd 5:0:0:0: [sdc] Stopping disk
Mar 24 19:01:46 vdr kernel: [ 848.134825] sd 4:0:0:0: [sdb] Stopping disk
Mar 24 19:01:46 vdr kernel: [ 848.134838] sd 3:0:0:0: [sda] Synchronizing SCSI cache
Mar 24 19:01:46 vdr kernel: [ 848.134924] sd 3:0:0:0: [sda] Stopping disk
Mar 24 19:01:46 vdr kernel: [ 878.816046] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
Mar 24 19:01:46 vdr kernel: [ 878.816049] ata4.00: failed command: STANDBY IMMEDIATE
Mar 24 19:01:46 vdr kernel: [ 878.816056] ata4.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 11
Mar 24 19:01:46 vdr kernel: [ 878.816056] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
Mar 24 19:01:46 vdr kernel: [ 878.816057] ata4.00: status: { DRDY }
Mar 24 19:01:46 vdr kernel: [ 878.816062] ata4: hard resetting link
Mar 24 19:01:46 vdr kernel: [ 878.816063] ata4: nv: skipping hardreset on occupied port
Mar 24 19:01:46 vdr kernel: [ 878.848048] ata5.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
Mar 24 19:01:46 vdr kernel: [ 878.848051] ata5.00: failed command: STANDBY IMMEDIATE
Mar 24 19:01:46 vdr kernel: [ 878.848058] ata5.00: cmd e0/00:00:00:00:00/00:00:00:00:00/a0 tag 28
Mar 24 19:01:46 vdr kernel: [ 878.848058] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
Mar 24 19:01:46 vdr kernel: [ 878.848059] ata5.00: status: { DRDY }
Mar 24 19:01:46 vdr kernel: [ 878.848064] ata5: hard resetting link
Mar 24 19:01:46 vdr kernel: [ 878.848065] ata5: nv: skipping hardreset on occupied port
Mar 24 19:01:46 vdr kernel: [ 879.284060] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 19:01:46 vdr kernel: [ 879.308605] ata4.00: configured for UDMA/133
Mar 24 19:01:46 vdr kernel: [ 879.308608] ata4.00: device reported invalid CHS sector 0
Mar 24 19:01:46 vdr kernel: [ 879.308617] ata4: EH complete
Mar 24 19:01:46 vdr kernel: [ 879.308630] sd 3:0:0:0: [sda] START_STOP FAILED
Mar 24 19:01:46 vdr kernel: [ 879.308632] sd 3:0:0:0: [sda]
Mar 24 19:01:46 vdr kernel: [ 879.308633] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Mar 24 19:01:46 vdr kernel: [ 879.308635] sd 3:0:0:0: [sda]
Mar 24 19:01:46 vdr kernel: [ 879.308638] Sense Key : Aborted Command [current] [descriptor]
Mar 24 19:01:46 vdr kernel: [ 879.308639] sd 3:0:0:0: [sda]
Mar 24 19:01:46 vdr kernel: [ 879.308641] Add. Sense: No additional sense information
Mar 24 19:01:46 vdr kernel: [ 879.316062] ata5: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 24 19:01:46 vdr kernel: [ 879.332522] ata5.00: configured for UDMA/133
Mar 24 19:01:46 vdr kernel: [ 879.332525] ata5.00: device reported invalid CHS sector 0
Mar 24 19:01:46 vdr kernel: [ 879.332532] ata5: EH complete
Mar 24 19:01:46 vdr kernel: [ 879.332541] sd 4:0:0:0: [sdb] START_STOP FAILED
Mar 24 19:01:46 vdr kernel: [ 879.332542] sd 4:0:0:0: [sdb]
Mar 24 19:01:46 vdr kernel: [ 879.332543] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Mar 24 19:01:46 vdr kernel: [ 879.332545] sd 4:0:0:0: [sdb]
Mar 24 19:01:46 vdr kernel: [ 879.332546] Sense Key : Aborted Command [current] [descriptor]
Mar 24 19:01:46 vdr kernel: [ 879.332547] sd 4:0:0:0: [sdb]
Mar 24 19:01:46 vdr kernel: [ 879.332548] Add. Sense: No additional sense information
Mar 24 19:01:46 vdr kernel: [ 879.348060] PM: suspend of devices complete after 31213.712 msecs
Mar 24 19:01:46 vdr kernel: [ 879.348345] PM: late suspend of devices complete after 0.281 msecs
Mar 24 19:01:46 vdr kernel: [ 879.348610] forcedeth 0000:00:14.0: System wakeup enabled by ACPI
Mar 24 19:01:46 vdr kernel: [ 879.364141] ehci-pci 0000:00:0b.1: System wakeup enabled by ACPI
Mar 24 19:01:46 vdr kernel: [ 879.380072] ohci-pci 0000:00:0b.0: System wakeup enabled by ACPI
Mar 24 19:01:46 vdr kernel: [ 879.396339] PM: noirq suspend of devices complete after 47.991 msecs
Mar 24 19:01:46 vdr kernel: [ 879.396406] ACPI: Preparing to enter system sleep state S3
Mar 24 19:01:46 vdr kernel: [ 879.397159] PM: Saving platform NVS memory
Mar 24 19:01:46 vdr kernel: [ 879.397192] Disabling non-boot CPUs ...
Mar 24 19:01:46 vdr acpid: client 1418[0:0] has disconnected
Mar 24 19:01:46 vdr kernel: [ 879.500020] smpboot: CPU 1 is now offline
Mar 24 19:01:46 vdr kernel: [ 879.500215] ACPI: Low-level resume complete
Mar 24 19:01:46 vdr kernel: [ 879.500215] PM: Restoring platform NVS memory
Mar 24 19:01:46 vdr kernel: [ 879.500619] Enabling non-boot CPUs ...
Mar 24 19:01:46 vdr kernel: [ 879.500659] x86: Booting SMP configuration:
Mar 24 19:01:46 vdr kernel: [ 879.500661] smpboot: Booting Node 0 Processor 1 APIC 0x1
Mar 24 19:01:46 vdr kernel: [ 879.511934] ------------[ cut here ]------------
Mar 24 19:01:46 vdr kernel: [ 879.511939] WARNING: CPU: 0 PID: 2947 at /build/linux-uz1EVo/linux-3.13.0/fs/sysfs/dir.c:486 sysfs_warn_dup+0x86/0xa0()
Mar 24 19:01:46 vdr kernel: [ 879.511940] sysfs: cannot create duplicate filename '/devices/system/cpu/cpu1/cpufreq'
Mar 24 19:01:46 vdr kernel: [ 879.511975] Modules linked in: tcp_diag inet_diag autofs4 snd_hda_codec_hdmi nfsd auth_rpcgss nfs_acl nfs lockd sunrpc fscache snd_hda_codec_analog stv0299(OX) stv6110x(OX) lnbp21(OX) kvm serio_raw k8temp edac_core edac_mce_amd snd_hda_intel videobuf_dma_sg(OX) snd_hda_codec snd_hwdep videobuf_core(OX) snd_pcm v4l2_common(OX) videodev(OX) snd_page_alloc snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq rc_imon_pad(OX) snd_seq_device joydev snd_timer imon(OX) rc_core(OX) nvidia(POX) stv090x(OX) snd i2c_nforce2 soundcore drm dvb_core(OX) media(OX) shpchp lp mac_hid parport hid_generic usbhid hid usb_storage psmouse forcedeth floppy pata_acpi sata_nv pata_amd [last unloaded: cxd2099]
Mar 24 19:01:46 vdr kernel: [ 879.511978] CPU: 0 PID: 2947 Comm: s2ram Tainted: P OX 3.13.0-113-generic #160-Ubuntu
Mar 24 19:01:46 vdr kernel: [ 879.511979] Hardware name: System manufacturer System Product Name/M2NPV-VM, BIOS ASUS M2NPV-VM ACPI BIOS Revision 5005 06/02/2010
Mar 24 19:01:46 vdr kernel: [ 879.511982] 0000000000000000 ffff880078d37b90 ffffffff8172d269 ffff880078d37bd8
Mar 24 19:01:46 vdr kernel: [ 879.511985] 0000000000000009 ffff880078d37bc8 ffffffff8106a76d ffff880078c27000
Mar 24 19:01:46 vdr kernel: [ 879.511987] ffff880078c27000 ffff88007c1b57e0 0000000000000000 0000000000000001
Mar 24 19:01:46 vdr kernel: [ 879.511987] Call Trace:
Mar 24 19:01:46 vdr kernel: [ 879.511993] [<ffffffff8172d269>] dump_stack+0x64/0x82
Mar 24 19:01:46 vdr kernel: [ 879.511996] [<ffffffff8106a76d>] warn_slowpath_common+0x7d/0xa0
Mar 24 19:01:46 vdr kernel: [ 879.511998] [<ffffffff8106a7dc>] warn_slowpath_fmt+0x4c/0x50
Mar 24 19:01:46 vdr kernel: [ 879.512001] [<ffffffff8123ae36>] sysfs_warn_dup+0x86/0xa0
Mar 24 19:01:46 vdr kernel: [ 879.512003] [<ffffffff8123ae90>] sysfs_add_one+0x40/0x50
Mar 24 19:01:46 vdr kernel: [ 879.512005] [<ffffffff8123b9ff>] sysfs_do_create_link_sd.isra.2+0xbf/0x210
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff8123bb75>] sysfs_create_link+0x25/0x50
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff815d3153>] __cpufreq_add_dev.isra.20+0x1b3/0x920
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff815d3932>] cpufreq_cpu_callback+0x72/0xc0
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff817399dc>] notifier_call_chain+0x4c/0x70
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff810934fe>] __raw_notifier_call_chain+0xe/0x10
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff8106a973>] cpu_notify+0x23/0x50
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff8106abb8>] _cpu_up+0x168/0x180
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff8171c710>] enable_nonboot_cpus+0x90/0xe0
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff810b7fc5>] suspend_devices_and_enter+0x355/0x490
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff810b8278>] pm_suspend+0x178/0x260
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff810b7049>] state_store+0x79/0xf0
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff8136da5f>] kobj_attr_store+0xf/0x20
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff81239918>] sysfs_write_file+0x128/0x1c0
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff811c24e4>] vfs_write+0xb4/0x1f0
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff811c2f19>] SyS_write+0x49/0xa0
Mar 24 19:01:46 vdr kernel: [ 879.512008] [<ffffffff8173de1d>] system_call_fastpath+0x1a/0x1f
Mar 24 19:01:46 vdr kernel: [ 879.512065] ---[ end trace c2c30281c9826cdd ]---
Mar 24 19:01:46 vdr kernel: [ 879.512392] CPU1 is up
Mar 24 19:01:46 vdr kernel: [ 879.512664] ACPI: Waking up from system sleep state S3
Mar 24 19:01:46 vdr kernel: [ 879.512921] pci 0000:00:00.0: Found disabled HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.512926] pci 0000:00:00.0: Enabling HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.513056] pci 0000:00:00.0: Found enabled HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.513102] pci 0000:00:00.0: Found enabled HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.528073] ohci-pci 0000:00:0b.0: System wakeup disabled by ACPI
Mar 24 19:01:46 vdr kernel: [ 879.544090] ehci-pci 0000:00:0b.1: System wakeup disabled by ACPI
Mar 24 19:01:46 vdr kernel: [ 879.576141] pci 0000:00:09.0: Found disabled HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.576154] sata_nv 0000:00:0e.0: Enabling HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.592155] pci 0000:00:09.0: Found disabled HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.592168] sata_nv 0000:00:0f.0: Enabling HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.608166] pci 0000:00:09.0: Found disabled HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.608180] snd_hda_intel 0000:00:10.1: Enabling HT MSI Mapping
Mar 24 19:01:46 vdr kernel: [ 879.640232] PM: noirq resume of devices complete after 127.366 msecs
Mar 24 19:01:46 vdr kernel: [ 879.640501] PM: early resume of devices complete after 0.157 msecs
Mar 24 19:01:46 vdr kernel: [ 879.640730] forcedeth 0000:00:14.0: System wakeup disabled by ACPI
Mar 24 19:01:46 vdr kernel: [ 879.646290] ata2: port disabled--ignoring
Mar 24 19:01:46 vdr kernel: [ 879.646547] ata1: port disabled--ignoring
Mar 24 19:01:46 vdr kernel: [ 880.108063] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 19:01:46 vdr kernel: [ 880.112070] ata6: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 19:01:46 vdr kernel: [ 880.116385] ata3.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out
Mar 24 19:01:46 vdr kernel: [ 880.120385] ata6.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out
Mar 24 19:01:46 vdr kernel: [ 880.128493] ata6.00: configured for UDMA/100
Mar 24 19:01:46 vdr kernel: [ 880.128542] sd 5:0:0:0: [sdc] Starting disk
Mar 24 19:01:46 vdr kernel: [ 880.132383] ata3.00: configured for UDMA/33
Mar 24 19:01:46 vdr kernel: [ 880.164954] forcedeth 0000:00:14.0 eth0: no link during initialization
Mar 24 19:01:46 vdr kernel: [ 880.220044] usb 2-7: reset low-speed USB device number 4 using ohci-pci
Mar 24 19:01:46 vdr kernel: [ 880.280061] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Mar 24 19:01:46 vdr kernel: [ 880.288386] ata4.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out
Mar 24 19:01:46 vdr kernel: [ 880.312642] ata4.00: configured for UDMA/133
Mar 24 19:01:46 vdr kernel: [ 880.312687] sd 3:0:0:0: [sda] Starting disk
Mar 24 19:01:46 vdr kernel: [ 880.676032] usb 1-8: reset high-speed USB device number 5 using ehci-pci
Mar 24 19:01:46 vdr kernel: [ 883.228056] forcedeth 0000:00:14.0 eth0: link up
Mar 24 19:01:46 vdr kernel: [ 885.152033] ata5: link is slow to respond, please be patient (ready=0)
Mar 24 19:01:46 vdr kernel: [ 889.688032] ata5: SRST failed (errno=-16)
Mar 24 19:01:46 vdr kernel: [ 891.164059] ata5: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 24 19:01:46 vdr kernel: [ 891.172392] ata5.00: ACPI cmd ef/03:46:00:00:00:a0 (SET FEATURES) filtered out
Mar 24 19:01:46 vdr kernel: [ 891.228903] ata5.00: configured for UDMA/133
Mar 24 19:01:46 vdr kernel: [ 891.228947] sd 4:0:0:0: [sdb] Starting disk
Mar 24 19:01:46 vdr kernel: [ 894.232031]
Mar 24 19:01:46 vdr kernel: [ 894.232031] floppy driver state
Mar 24 19:01:46 vdr kernel: [ 894.232032] -------------------
Mar 24 19:01:46 vdr kernel: [ 894.232047] now=4295115854 last interrupt=4294892541 diff=223313 last called handler=reset_interrupt [floppy]
Mar 24 19:01:46 vdr kernel: [ 894.232048] timeout_message=lock fdc
Mar 24 19:01:46 vdr kernel: [ 894.232048] last output bytes:
Mar 24 19:01:46 vdr kernel: [ 894.232049] 8 80 4294892536
Mar 24 19:01:46 vdr kernel: [ 894.232050] 8 80 4294892537
Mar 24 19:01:46 vdr kernel: [ 894.232051] 8 80 4294892537
Mar 24 19:01:46 vdr kernel: [ 894.232052] 8 80 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232053] 8 80 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232054] 8 80 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232054] 8 80 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232055] e 80 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232056] 13 80 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232057] 0 90 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232058] 1a 90 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232059] 0 90 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232059] 12 90 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232060] 0 90 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232061] 14 90 4294892540
Mar 24 19:01:46 vdr kernel: [ 894.232062] 18 80 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232063] 8 80 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232064] 8 80 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232064] 8 80 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232065] 8 80 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232066] last result at 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232067] last redo_fd_request at 4294892541
Mar 24 19:01:46 vdr kernel: [ 894.232079] status=0
Mar 24 19:01:46 vdr kernel: [ 894.232080] fdc_busy=1
Mar 24 19:01:46 vdr kernel: [ 894.232084] do_floppy=reset_interrupt [floppy]
Mar 24 19:01:46 vdr kernel: [ 894.232085] cont=ffffffffa0034380
Mar 24 19:01:46 vdr kernel: [ 894.232086] current_req= (null)
Mar 24 19:01:46 vdr kernel: [ 894.232086] command_status=-1
Mar 24 19:01:46 vdr kernel: [ 894.232087]
Mar 24 19:01:46 vdr kernel: [ 894.232090] floppy0: floppy timeout called
Mar 24 19:01:46 vdr kernel: [ 894.232265] PM: resume of devices complete after 14591.757 msecs
Mar 24 19:01:46 vdr kernel: [ 894.232653] PM: Finishing wakeup.
Mar 24 19:01:46 vdr kernel: [ 894.233117] pci 0000:00:00.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233120] pci 0000:00:00.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233127] pci 0000:00:00.2: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233130] pci 0000:00:00.2: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233136] pci 0000:00:00.3: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233139] pci 0000:00:00.3: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233145] pci 0000:00:00.4: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233148] pci 0000:00:00.4: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233154] pci 0000:00:00.5: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233157] pci 0000:00:00.5: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233163] pci 0000:00:00.6: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233165] pci 0000:00:00.6: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233171] pci 0000:00:00.7: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233174] pci 0000:00:00.7: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233180] pcieport 0000:00:03.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233190] pci 0000:01:00.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233194] pcieport 0000:00:04.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233203] nvidia 0000:02:00.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233211] snd_hda_intel 0000:02:00.1: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233215] pci 0000:00:09.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233218] pci 0000:00:09.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233233] pci 0000:00:0a.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233235] pci 0000:00:0a.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233245] nForce2_smbus 0000:00:0a.1: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233247] nForce2_smbus 0000:00:0a.1: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233258] pci 0000:00:0a.2: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233260] pci 0000:00:0a.2: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233270] ohci-pci 0000:00:0b.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233273] ohci-pci 0000:00:0b.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233282] ehci-pci 0000:00:0b.1: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233285] ehci-pci 0000:00:0b.1: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233294] pata_amd 0000:00:0d.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233296] pata_amd 0000:00:0d.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233306] sata_nv 0000:00:0e.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233309] sata_nv 0000:00:0e.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233318] sata_nv 0000:00:0f.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233320] sata_nv 0000:00:0f.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233330] pci 0000:00:10.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233332] pci 0000:00:10.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233347] pci 0000:03:09.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233349] pci 0000:03:09.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233360] snd_hda_intel 0000:00:10.1: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233362] snd_hda_intel 0000:00:10.1: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233373] forcedeth 0000:00:14.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233375] forcedeth 0000:00:14.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233385] pci 0000:00:18.0: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233387] pci 0000:00:18.0: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233392] pci 0000:00:18.1: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233394] pci 0000:00:18.1: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233399] pci 0000:00:18.2: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233401] pci 0000:00:18.2: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.233407] k8temp 0000:00:18.3: no hotplug settings from platform
Mar 24 19:01:46 vdr kernel: [ 894.233409] k8temp 0000:00:18.3: using default PCI settings
Mar 24 19:01:46 vdr kernel: [ 894.232654] Restarting tasks ... done.
Mar 24 19:01:46 vdr kernel: [ 894.261381] usb 2-5: USB disconnect, device number 2
Mar 24 19:01:46 vdr acpid: input device has been disconnected, fd 14
Mar 24 19:01:46 vdr acpid: input device has been disconnected, fd 15
Mar 24 19:01:46 vdr acpid: client connected from 1418[0:0]
Mar 24 19:01:46 vdr acpid: 1 client rule loaded
Mar 24 19:01:47 vdr kernel: [ 894.624039] usb 2-5: new low-speed USB device number 5 using ohci-pci
Mar 24 19:01:47 vdr kernel: [ 894.836081] usb 2-5: New USB device found, idVendor=0b38, idProduct=0003
Mar 24 19:01:47 vdr kernel: [ 894.836103] usb 2-5: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Mar 24 19:01:47 vdr kernel: [ 894.836107] usb 2-5: Product: USB-compliant keyboard
Mar 24 19:01:47 vdr anacron[3140]: Anacron 2.3 started on 2017-03-24
Mar 24 19:01:47 vdr anacron[3140]: Normal exit (0 jobs run)
Mar 24 19:01:47 vdr kernel: [ 894.847731] input: USB-compliant keyboard as /devices/pci0000:00/0000:00:0b.0/usb2/2-5/2-5:1.0/input/input25
Mar 24 19:01:47 vdr kernel: [ 894.848673] hid-generic 0003:0B38:0003.0004: input,hidraw0: USB HID v1.10 Keyboard [USB-compliant keyboard] on usb-0000:00:0b.0-5/input0
Mar 24 19:01:47 vdr kernel: [ 894.858395] input: USB-compliant keyboard as /devices/pci0000:00/0000:00:0b.0/usb2/2-5/2-5:1.1/input/input26
Mar 24 19:01:47 vdr kernel: [ 894.859235] hid-generic 0003:0B38:0003.0005: input,hiddev0,hidraw1: USB HID v1.10 Mouse [USB-compliant keyboard] on usb-0000:00:0b.0-5/input1
Mar 24 19:01:47 vdr mtp-probe: checking bus 2, device 5: "/sys/devices/pci0000:00/0000:00:0b.0/usb2/2-5"
Mar 24 19:01:47 vdr mtp-probe: bus: 2, device: 5 was not an MTP device
Mar 24 19:01:47 vdr kernel: [ 894.927124] nGene PCIE bridge driver, Copyright (C) 2005-2007 Micronas
Mar 24 19:01:47 vdr kernel: [ 894.927317] ngene: Found Mystique SaTiX-S2 Dual (v2)
Mar 24 19:01:47 vdr kernel: [ 894.929987] ngene: Device version 1
Mar 24 19:01:47 vdr kernel: [ 894.930071] ngene: Loading firmware file ngene_18.fw.
Mar 24 19:01:47 vdr kernel: [ 894.948771] ngene 0000:01:00.0: irq 42 for MSI/MSI-X
Mar 24 19:01:47 vdr kernel: [ 894.950662] error in i2c_read_reg
Mar 24 19:01:47 vdr kernel: [ 894.950670] No CXD2099 detected at 40
Mar 24 19:01:47 vdr kernel: [ 895.256033] Attaching STV0900 demodulator(1) Cut=0x20
Mar 24 19:01:47 vdr kernel: [ 895.256712] LNBx2x attached on addr=a
Mar 24 19:01:47 vdr kernel: [ 895.256722] stv6110x_attach: Attaching STV6110x
Mar 24 19:01:47 vdr kernel: [ 895.256725] DVB: registering new adapter (nGene)
Mar 24 19:01:47 vdr kernel: [ 895.257061] ngene 0000:01:00.0: DVB: registering adapter 0 frontend 0 (STV090x Multistandard)...
Mar 24 19:01:47 vdr kernel: [ 895.300032] Attaching STV0900 demodulator(2) Cut=0x20
Mar 24 19:01:47 vdr kernel: [ 895.300715] LNBx2x attached on addr=8
Mar 24 19:01:47 vdr kernel: [ 895.300726] stv6110x_attach: Attaching STV6110x
Mar 24 19:01:47 vdr kernel: [ 895.300728] DVB: registering new adapter (nGene)
Mar 24 19:01:47 vdr kernel: [ 895.300885] ngene 0000:01:00.0: DVB: registering adapter 1 frontend 0 (STV090x Multistandard)...
Mar 24 19:01:47 vdr kernel: [ 895.303104] No demod found on chan 2
Mar 24 19:01:47 vdr kernel: [ 895.305627] No demod found on chan 3
Mar 24 19:01:47 vdr kernel: [ 895.312348] saa7146: register extension 'av7110'
Mar 24 19:01:47 vdr kernel: [ 895.312642] saa7146: found saa7146 @ mem ffffc9000037e000 (revision 1, irq 17) (0x13c2,0x000e)
Mar 24 19:01:47 vdr kernel: [ 895.313524] DVB: registering new adapter (Technotrend/Hauppauge WinTV Nexus-S rev2.3)
Mar 24 19:01:47 vdr kernel: [ 895.350052] adapter has MAC addr = 00:d0:5c:08:11:0e
Mar 24 19:01:48 vdr kernel: [ 895.570546] dvb-ttpci: gpioirq unknown type=0 len=0
Mar 24 19:01:48 vdr kernel: [ 895.588183] dvb-ttpci: info @ card 2: firm f0240009, rtsl b0250018, vid 71010068, app 8000261d
Mar 24 19:01:48 vdr kernel: [ 895.588187] dvb-ttpci: firmware @ card 2 supports CI link layer interface
Mar 24 19:01:48 vdr kernel: [ 895.636213] dvb-ttpci: Crystal audio DAC @ card 2 detected
Mar 24 19:01:48 vdr kernel: [ 895.637197] saa7146_vv: saa7146 (0): registered device video0 [v4l2]
Mar 24 19:01:48 vdr kernel: [ 895.844361] LNBx2x attached on addr=8
Mar 24 19:01:48 vdr kernel: [ 895.844471] av7110 0000:03:09.0: DVB: registering adapter 2 frontend 0 (ST STV0299 DVB-S)...
Mar 24 19:01:48 vdr kernel: [ 895.844576] input: DVB on-card IR receiver as /devices/pci0000:00/0000:00:10.0/0000:03:09.0/input/input27
Mar 24 19:01:48 vdr kernel: [ 895.844656] dvb-ttpci: found av7110-0.
Mar 24 19:01:48 vdr kernel: [ 895.947160] input: eventlircd as /devices/virtual/input/input28
Mar 24 19:01:48 vdr kernel: [ 895.950015] imon 2-6:1.0: Looks like you're trying to use an IR protocol this device does not support
Mar 24 19:01:48 vdr kernel: [ 895.950022] imon 2-6:1.0: Unsupported IR protocol specified, overriding to iMON IR protocol
Mar 24 19:01:48 vdr kernel: [ 895.953862] ------------[ cut here ]------------
Mar 24 19:01:48 vdr kernel: [ 895.953873] WARNING: CPU: 0 PID: 3169 at /build/linux-uz1EVo/linux-3.13.0/drivers/usb/core/urb.c:336 usb_submit_urb+0x45b/0x470()
Mar 24 19:01:48 vdr kernel: [ 895.953876] URB ffff88007b858000 submitted while active
Mar 24 19:01:48 vdr kernel: [ 895.953877] Modules linked in: stv0299(OX) dvb_ttpci(OX) saa7146_vv(OX) ttpci_eeprom(OX) saa7146(OX) ngene(OX) cxd2099(OX) tcp_diag inet_diag autofs4 snd_hda_codec_hdmi nfsd auth_rpcgss nfs_acl nfs lockd sunrpc fscache snd_hda_codec_analog stv6110x(OX) lnbp21(OX) kvm serio_raw k8temp edac_core edac_mce_amd snd_hda_intel videobuf_dma_sg(OX) snd_hda_codec snd_hwdep videobuf_core(OX) snd_pcm v4l2_common(OX) videodev(OX) snd_page_alloc snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq rc_imon_pad(OX) snd_seq_device joydev snd_timer imon(OX) rc_core(OX) nvidia(POX) stv090x(OX) snd i2c_nforce2 soundcore drm dvb_core(OX) media(OX) shpchp lp mac_hid parport hid_generic usbhid hid usb_storage psmouse forcedeth floppy pata_acpi sata_nv pata_amd [last unloaded: stv0299]
Mar 24 19:01:48 vdr kernel: [ 895.953928] CPU: 0 PID: 3169 Comm: LCDd Tainted: P W OX 3.13.0-113-generic #160-Ubuntu
Mar 24 19:01:48 vdr kernel: [ 895.953931] Hardware name: System manufacturer System Product Name/M2NPV-VM, BIOS ASUS M2NPV-VM ACPI BIOS Revision 5005 06/02/2010
Mar 24 19:01:48 vdr kernel: [ 895.953934] 0000000000000000 ffff880079883da0 ffffffff8172d269 ffff880079883de8
Mar 24 19:01:48 vdr kernel: [ 895.953938] 0000000000000009 ffff880079883dd8 ffffffff8106a76d ffff88007b858000
Mar 24 19:01:48 vdr kernel: [ 895.953941] 0000000000000000 0000000000000020 ffff88003627e0d0 ffff88003627e099
Mar 24 19:01:48 vdr kernel: [ 895.953944] Call Trace:
Mar 24 19:01:48 vdr kernel: [ 895.953951] [<ffffffff8172d269>] dump_stack+0x64/0x82
Mar 24 19:01:48 vdr kernel: [ 895.953955] [<ffffffff8106a76d>] warn_slowpath_common+0x7d/0xa0
Mar 24 19:01:48 vdr kernel: [ 895.953959] [<ffffffff8106a7dc>] warn_slowpath_fmt+0x4c/0x50
Mar 24 19:01:48 vdr kernel: [ 895.953963] [<ffffffff810776e0>] ? ftrace_raw_output_tick_stop+0x70/0x70
Mar 24 19:01:48 vdr kernel: [ 895.953966] [<ffffffff8154f81b>] usb_submit_urb+0x45b/0x470
Mar 24 19:01:48 vdr kernel: [ 895.953975] [<ffffffffa0c45876>] send_packet+0x106/0x330 [imon]
Mar 24 19:01:48 vdr kernel: [ 895.953980] [<ffffffffa0c45bc5>] vfd_write+0x125/0x300 [imon]
Mar 24 19:01:48 vdr kernel: [ 895.953985] [<ffffffff811c24e4>] vfs_write+0xb4/0x1f0
Mar 24 19:01:48 vdr kernel: [ 895.953989] [<ffffffff811c2f19>] SyS_write+0x49/0xa0
Mar 24 19:01:48 vdr kernel: [ 895.953994] [<ffffffff8173de1d>] system_call_fastpath+0x1a/0x1f
Mar 24 19:01:48 vdr kernel: [ 895.953996] ---[ end trace c2c30281c9826cde ]---
Mar 24 19:01:48 vdr kernel: [ 895.953999] imon:send_packet: error submitting urb(-16)
Mar 24 19:01:48 vdr kernel: [ 895.960514] imon:vfd_write: send packet #2 failed
Mar 24 19:01:48 vdr vdr: [3195] VDR version 2.2.0 started
Die DVB Module habe ich als force-reload eingetragen; habe gehofft, das hilft:
oliver@vdr:/etc/yavdr$ cat force-reload-modules.list
ngene
dvb_ttpci
stv0299
Kann mir jemand einen Tipp geben, wo und wie ich weiter suchen soll?
BTW: Auch, wenn ich oben von langer Dauer schreibe - wie lange STR dauert ist mir egal; ich finde es nur komisch.
Das Ziel ist, dass der Rechner aus dem S3 deutlich schneller started, als beim Cold Boot - allein BIOS POST dauert schon gefühlt "ewig", obwohl alles auf minimum geschaltet ist. Cold Boot incl. allem sind bei mir aktuell ca. 1 Minute.
Danke!
EDIT: Hier scheint ein entsprechender KernelBug besprochen zu werden, wenn ichs richtig interpretiere; mir ist noch nicht klar, ob das ursächlich für das Verhalten ist. Inzwischen habe ich auch das nvidia Modul in die force-reload Liste aufgenommen - ohne Erfolg.