[sisyphus] init 0 killed my monitor

[Ivan Adzhubey]

Приветствую!

Из разряда курьезов...

Купил я тут себе домой UPS (MGE Pulsar Ellipse), подключил, установил nut, 
настроил - все работает. Решил провести полевые испытания путем выдергивания 
вилки из розетки. Все опять же отработало штатно, за исключением странностей 
в процессе FSD запущенного nut'ом после того как батарея UPSа разрядилась до 
установленного уровня в 30%. В целях эксперимента приближенного к реальности 
разрядки батареи ждал сидя в сеансе КДЕ. Как только UPS пропищал battery low 
иксы убились - черный экран. Дальше однако они по-видимому зачем-то 
попытались перезапуститься, в результате монитор странно моргнул и выкинул 
табличку с написью что-то вроде Unsupported video mode, please select optimal 
mode: 1280x1024. Дальнейший шатдаун прошел нормально, после перезапуска 
компьютера все файловые системы clean, все хорошо. Но у монитора (LCD Hitachi 
178c 17") судя по всему сдохла одна из ламп подсветки - верхняя половина 
экрана теперь заметно темнее и розового оттенка. Монитор довольно старый и 
то, что он стал умирать именно после теста могло быть просто совпадением. Тем 
не менее хотелось бы избежать таких сюрпризов в будущем и разобраться, 
какого ... процедура шатдауна из иксов некорректно и зазря дергает видео. 
Внизу приведено, как это выглядит в логах. Насколько я понимаю, сначала иксы 
просто убиваются SIGTERMом, после чего ничего не подозревающий сервис dm 
пытается их перезапустить, начиная с перезагрузки видео драйверов (fglrx), но 
попадает в race condition когда его самого останавливает init. В результате 
видео остается в непредсказуемом состоянии вплоть до завершения остановки 
системы и отключения питания, а монитор за это время вполне может, как 
показывает опыт, накрыться медным тазом. Что-то не так в датском королевстве, 
вам не кажется?

Jun 10 18:16:47 localhost upsmon[11267]: UPS ellipse at localhost battery is low
Jun 10 18:16:47 localhost upsd[11245]: Client monuser at 127.0.0.1 set FSD on UPS 
[ellipse]
Jun 10 18:16:47 localhost upsmon[11267]: Executing automatic power-fail 
shutdown
Jun 10 18:16:47 localhost wall[22650]: wall: user upsmon broadcasted 2 lines 
(43 chars)
Jun 10 18:16:47 localhost wall[22649]: wall: user upsmon broadcasted 1 lines 
(38 chars)
Jun 10 18:16:47 localhost upsmon[11267]: Auto logout and shutdown proceeding
Jun 10 18:16:47 localhost wall[22654]: wall: user upsmon broadcasted 1 lines 
(37 chars)
Jun 10 18:16:50 localhost upsd[11245]: Connection from 127.0.0.1
Jun 10 18:16:50 localhost upsd[11245]: Client on 127.0.0.1 logged out
Jun 10 18:16:52 localhost upsd[11245]: Host 127.0.0.1 disconnected (read 
failure)
Jun 10 18:16:52 localhost shutdown[22660]: shutting down for system halt
Jun 10 18:16:52 localhost init: init_setenv: INIT_HALT, (null), 10
Jun 10 18:16:52 localhost init: Switching to runlevel: 0
Jun 10 18:16:52 localhost kdm[10475]: X server for display :0 terminated 
unexpectedly
Jun 10 18:16:53 localhost kernel: ACPI: PCI Interrupt 0000:01:00.0[A] -> GSI 
16 (level, low) -> IRQ 17
Jun 10 18:16:53 localhost messagebus: sending signal 15 to dbus-daemon 
succeeded
Jun 10 18:16:54 localhost kernel: [fglrx] AGP detected, AgpState   = 
0x1f004a1b (hardware caps of chipset)
Jun 10 18:16:54 localhost kernel: agpgart: Found an AGP 3.0 compliant device 
at 0000:00:00.0.
Jun 10 18:16:54 localhost kernel: agpgart: Putting AGP V3 device at 
0000:00:00.0 into 8x mode
Jun 10 18:16:54 localhost kernel: agpgart: Putting AGP V3 device at 
0000:01:00.0 into 8x mode
Jun 10 18:16:54 localhost kernel: [fglrx] AGP enabled,  AgpCommand = 
0x1f004312 (selected caps)
Jun 10 18:16:54 localhost kernel: [fglrx] total      GART = 134217728
Jun 10 18:16:54 localhost kernel: [fglrx] free       GART = 118222848
Jun 10 18:16:54 localhost kernel: [fglrx] max single GART = 118222848
Jun 10 18:16:54 localhost kernel: [fglrx] total      LFB  = 126873600
Jun 10 18:16:54 localhost kernel: [fglrx] free       LFB  = 116387840
Jun 10 18:16:54 localhost kernel: [fglrx] max single LFB  = 116387840
Jun 10 18:16:54 localhost kernel: [fglrx] total      Inv  = 134217728
Jun 10 18:16:54 localhost kernel: [fglrx] free       Inv  = 134217728
Jun 10 18:16:54 localhost kernel: [fglrx] max single Inv  = 134217728
Jun 10 18:16:54 localhost kernel: [fglrx] total      TIM  = 0
Jun 10 18:16:55 localhost dm: rundm shutdown succeeded
Jun 10 18:16:55 localhost ntpd[10800]: ntpd exiting on signal 15
Jun 10 18:16:55 localhost ntpd: ntpd shutdown succeeded
Jun 10 18:16:55 localhost xfs: xfs shutdown succeeded
Jun 10 18:16:55 localhost upsmon: upsmon shutdown succeeded
Jun 10 18:16:55 localhost upsd[11245]: Signal 15: exiting
Jun 10 18:16:55 localhost upsd: upsd shutdown succeeded
Jun 10 18:16:56 localhost upsdrv: mge-shut shutdown succeeded
Jun 10 18:16:56 localhost httpd: libhttpd.ep shutdown succeeded
Jun 10 18:16:57 localhost httpd-perl: httpd-perl shutdown succeeded
Jun 10 18:16:57 localhost sshd[10770]: Received signal 15; terminating.
Jun 10 18:16:57 localhost sshd: sshd shutdown succeeded
Jun 10 18:16:57 localhost postfix: Shutting down postfix: succeeded
Jun 10 18:16:57 localhost rc: Stopping rawdevices: succeeded
Jun 10 18:16:57 localhost openct-control: 0 processes killed.
Jun 10 18:16:57 localhost openct: Shutdown OpenCT control succeeded
Jun 10 18:16:57 localhost autofs: Stopping automounter: succeeded
Jun 10 18:16:57 localhost dictd: dictd shutdown succeeded
Jun 10 18:16:58 localhost dnsmasq[8397]: exiting on receipt of SIGTERM
Jun 10 18:16:58 localhost dnsmasq: dnsmasq shutdown succeeded
Jun 10 18:16:58 localhost anacron: anacron shutdown succeeded
Jun 10 18:16:58 localhost crond: crond shutdown succeeded
Jun 10 18:16:58 localhost rc: Stopping keytable: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (cpu.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (sound.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (usb.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (pnp.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (pci.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (misc.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (block.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (acpi.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (pcmcia_socket.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (pcmcia.rc) stop: succeeded
Jun 10 18:16:58 localhost hotplug: Hotplug (pcmcia_socket.rc) stop: succeeded
Jun 10 18:16:58 localhost pppoe: Shutting down PPPoE link
Jun 10 18:16:58 localhost pppoe-stop: Killing pppd
Jun 10 18:16:58 localhost pppd[8253]: Terminating on signal 15.
Jun 10 18:16:58 localhost pppoe-stop: Killing pppoe-connect
Jun 10 18:16:58 localhost pppoe: ^[[73G
Jun 10 18:16:58 localhost pppoe: [
Jun 10 18:16:58 localhost pppoe: ^[[1m^[[32m
Jun 10 18:16:58 localhost pppoe: DONE
Jun 10 18:16:58 localhost pppoe: ^[[39;49m^[[0;10m
Jun 10 18:16:58 localhost rc: Stopping pppoe: succeeded
Jun 10 18:16:58 localhost pppoe[8263]: read (asyncReadFromPPP): Session 57966: 
Input/output error
Jun 10 18:16:58 localhost pppoe[8263]: Sent PADT
Jun 10 18:16:58 localhost dd: 1+0 records in
Jun 10 18:16:58 localhost dd: 1+0 records out
Jun 10 18:16:58 localhost dd: 512 bytes (512 B) copied
Jun 10 18:16:58 localhost dd: , 0.00055 seconds, 931 kB/s
Jun 10 18:16:58 localhost random: Saving random seed: succeeded
Jun 10 18:16:59 localhost network: Shutting down interface eth0: succeeded
Jun 10 18:16:59 localhost udevd: udevd shutdown succeeded
Jun 10 18:16:59 localhost kernel: Kernel logging (proc) stopped.
Jun 10 18:16:59 localhost kernel: Kernel log daemon terminating.
Jun 10 18:17:00 localhost exiting on signal 15


-- 
Иван