https://bugs.kde.org/show_bug.cgi?id=500951

--- Comment #5 from Jona Wagner <j...@jonawagner.me> ---
installed libddcutil branch 2.2.1-dev, set config option `--trace api`,
reproduced the issue by running `kscreen-doctor -d off` 4 times. seems like
this happens if the screen reconnects (as indicated by the sound effect KDE
plays) while the screen is looking for other inputs and shortly before the
screen goes to sleep. usually this reconnect just happens after it wakes up.
the screen in question is an Acer CB281HK connected via HDMI.

here are the logs:

```
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: [1764508] Watching for
display connection changes, resolved watch mode = Watch_Mode_Xevent, poll loop
interval = 100 millisec
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: [1764508]                   
                      extra_stabilization_millisec: 0, 
stabilization_poll_millisec: 100
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: [1764508] libddcutil recheck
thread (nil) started
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: [1764508] libddcutil watch
thread 0x5a8509513510 started
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
org.kde.powerdevil.chargethresholdhelper.getthreshold failed "Charge thresholds
are not supported by the kernel for this hardware"
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
org.kde.powerdevil.backlighthelper.brightness failed
Mar 07 23:04:48 r9950 systemd[1421]: Started Powerdevil.
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil: Handle
button events action could not check for screen configuration
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
org.kde.powerdevil.chargethresholdhelper.getthreshold failed "Charge thresholds
are not supported by the kernel for this hardware"
Mar 07 23:04:48 r9950 org_kde_powerdevil[1764508]: [1764611]
(dw_recheck_displays_func) Recheck interval: Slept for 200 millisec
Mar 07 23:05:18 r9950 org_kde_powerdevil[1764508]: [1764612] Removing connected
display on bus 6
Mar 07 23:05:18 r9950 org_kde_powerdevil[1764508]: [1764612]
(dw_remove_display_by_businfo) No Display_Ref found for i2c bus: 6
Mar 07 23:05:20 r9950 org_kde_powerdevil[1764508]: [1764612] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764612] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764612] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764612] Adding connected
display with bus 6
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764612] Emitting
DDCA_Display_Status_Event[ 36.615:  DDCA_EVENT_DISPLAY_CONNECTED,
card1-HDMI-A-2, dref: DDCA_Display_Ref[3], io_path:/dev/i2c-6, ddc working:
false]
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764612] libddcutil
callback thread 0x768858011d00 started
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764612] Started 1 event
callback thread(s)
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1765730] Invoking callback
function 0x7688835c91b0 for event DDCA_Display_Status_Event[ 36.615: 
DDCA_EVENT_DISPLAY_CONNECTED, card1-HDMI-A-2, dref: DDCA_Display_Ref[3],
io_path:/dev/i2c-6, ddc working: false] in this thread [1765730]
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1765730] Callback function
0x7688835c91b0 for event DDCA_Display_Status_Event[ 36.615: 
DDCA_EVENT_DISPLAY_CONNECTED, card1-HDMI-A-2, dref: DDCA_Display_Ref[3],
io_path:/dev/i2c-6, ddc working: false] complete
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764508] Quiescing
libddcutil API...
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764508] Quiesce libddcutil
API complete
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764508] Display
redetection starting.
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764611] ddc became enabled
for Display_Ref[3:bus /dev/i2c-6 @0x76885800d630] after 0 milliseconds
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764611] Emitting
DDCA_Display_Status_Event[ 36.615:  DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2,
dref: DDCA_Display_Ref[3], io_path:/dev/i2c-6, ddc working: false]
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764611] libddcutil
callback thread 0x768854002a30 started
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764611] Started 1 event
callback thread(s)
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764611] recheck thread
terminating because watch thread terminated
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1765731] Invoking callback
function 0x7688835c91b0 for event DDCA_Display_Status_Event[ 36.615: 
DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2, dref: DDCA_Display_Ref[3],
io_path:/dev/i2c-6, ddc working: false] in this thread [1765731]
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1765731] Callback function
0x7688835c91b0 for event DDCA_Display_Status_Event[ 36.615: 
DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2, dref: DDCA_Display_Ref[3],
io_path:/dev/i2c-6, ddc working: false] complete
Mar 07 23:05:21 r9950 org_kde_powerdevil[1764508]: [1764508] Watch thread
terminated.
Mar 07 23:05:22 r9950 org_kde_powerdevil[1764508]: [1764508] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:05:22 r9950 org_kde_powerdevil[1764508]: [1764508] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:05:22 r9950 org_kde_powerdevil[1764508]: [1764508] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508]
(check_supported_feature) busno=5, sleep-multiplier =  2,00. Testing for
supported feature 0x10 returned Error_Info[DDCRC_RETRIES in
ddc_write_read_with_retry, causes: DDCRC_DDC_DATA(10)]
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508] Watching for
display connection changes, resolved watch mode = Watch_Mode_Xevent, poll loop
interval = 100 millisec
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508]                   
                      extra_stabilization_millisec: 0, 
stabilization_poll_millisec: 100
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508] libddcutil recheck
thread (nil) started
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508] libddcutil watch
thread 0x5a8509508f20 started
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508] Display
redetection finished.
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1764508] Unquiescing
libddcutil API...
Mar 07 23:05:25 r9950 org_kde_powerdevil[1764508]: [1765896]
(dw_recheck_displays_func) Recheck interval: Slept for 200 millisec
Mar 07 23:06:42 r9950 org_kde_powerdevil[1764508]: [1765897] Removing connected
display on bus 6
Mar 07 23:06:42 r9950 org_kde_powerdevil[1764508]: [1765897]
(dw_remove_display_by_businfo) No Display_Ref found for i2c bus: 6
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] Adding connected
display with bus 6
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] Emitting
DDCA_Display_Status_Event[119.939:  DDCA_EVENT_DISPLAY_CONNECTED,
card1-HDMI-A-2, dref: DDCA_Display_Ref[6], io_path:/dev/i2c-6, ddc working:
false]
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] libddcutil
callback thread 0x768854001c70 started
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765897] Started 1 event
callback thread(s)
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1768384] Invoking callback
function 0x7688835c91b0 for event DDCA_Display_Status_Event[119.939: 
DDCA_EVENT_DISPLAY_CONNECTED, card1-HDMI-A-2, dref: DDCA_Display_Ref[6],
io_path:/dev/i2c-6, ddc working: false] in this thread [1768384]
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1768384] Callback function
0x7688835c91b0 for event DDCA_Display_Status_Event[119.939: 
DDCA_EVENT_DISPLAY_CONNECTED, card1-HDMI-A-2, dref: DDCA_Display_Ref[6],
io_path:/dev/i2c-6, ddc working: false] complete
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1764508] Quiescing
libddcutil API...
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1764508] Quiesce libddcutil
API complete
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1764508] Display
redetection starting.
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765896] ddc became enabled
for Display_Ref[6:bus /dev/i2c-6 @0x768854014e60] after 0 milliseconds
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765896] Emitting
DDCA_Display_Status_Event[119.939:  DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2,
dref: DDCA_Display_Ref[6], io_path:/dev/i2c-6, ddc working: false]
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765896] libddcutil
callback thread 0x7688580024f0 started
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765896] Started 1 event
callback thread(s)
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1765896] recheck thread
terminating because watch thread terminated
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1768385] Invoking callback
function 0x7688835c91b0 for event DDCA_Display_Status_Event[119.939: 
DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2, dref: DDCA_Display_Ref[6],
io_path:/dev/i2c-6, ddc working: false] in this thread [1768385]
Mar 07 23:06:44 r9950 org_kde_powerdevil[1764508]: [1768385] Callback function
0x7688835c91b0 for event DDCA_Display_Status_Event[119.939: 
DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2, dref: DDCA_Display_Ref[6],
io_path:/dev/i2c-6, ddc working: false] complete
Mar 07 23:06:45 r9950 org_kde_powerdevil[1764508]: [1764508] Watch thread
terminated.
Mar 07 23:06:45 r9950 org_kde_powerdevil[1764508]: [1764508] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:06:45 r9950 org_kde_powerdevil[1764508]: [1764508] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:06:46 r9950 org_kde_powerdevil[1764508]: [1764508] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:06:48 r9950 org_kde_powerdevil[1764508]: [1764508] Watching for
display connection changes, resolved watch mode = Watch_Mode_Xevent, poll loop
interval = 100 millisec
Mar 07 23:06:48 r9950 org_kde_powerdevil[1764508]: [1764508]                   
                      extra_stabilization_millisec: 0, 
stabilization_poll_millisec: 100
Mar 07 23:06:48 r9950 org_kde_powerdevil[1764508]: [1764508] libddcutil recheck
thread (nil) started
Mar 07 23:06:48 r9950 org_kde_powerdevil[1764508]: [1764508] libddcutil watch
thread 0x5a8509570d70 started
Mar 07 23:06:48 r9950 org_kde_powerdevil[1764508]: [1764508] Display
redetection finished.
Mar 07 23:06:48 r9950 org_kde_powerdevil[1764508]: [1764508] Unquiescing
libddcutil API...
Mar 07 23:06:49 r9950 org_kde_powerdevil[1764508]: [1768468]
(dw_recheck_displays_func) Recheck interval: Slept for 200 millisec
Mar 07 23:06:51 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: ddca_get_non_table_vcp_value -3007
Mar 07 23:06:51 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDetector]: Display "SMBX2231" does not seem to support brightness
control - wait before retrying initialization
Mar 07 23:06:51 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: "SMBX2231" retrying to initialize DDC/CI brightness in 1000
milliseconds - attempt no. 1
Mar 07 23:06:52 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: "SMBX2231" succeeded to initialize DDC/CI brightness
Mar 07 23:06:55 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: ddca_get_non_table_vcp_value -3007
Mar 07 23:08:28 r9950 org_kde_powerdevil[1764508]: [1768469] Removing connected
display on bus 6
Mar 07 23:08:28 r9950 org_kde_powerdevil[1764508]: [1768469]
(dw_remove_display_by_businfo) No Display_Ref found for i2c bus: 6
Mar 07 23:08:30 r9950 org_kde_powerdevil[1764508]: [1768469] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768469] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: ddca_get_non_table_vcp_value -3007
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768469] (i2c_detect_x37)
Extra x37 sleep: Slept for 400 millisec
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768469] Adding connected
display with bus 6
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768469] Emitting
DDCA_Display_Status_Event[226.576:  DDCA_EVENT_DISPLAY_CONNECTED,
card1-HDMI-A-2, dref: DDCA_Display_Ref[9], io_path:/dev/i2c-6, ddc working:
false]
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768469] libddcutil
callback thread 0x768858012ac0 started
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768469] Started 1 event
callback thread(s)
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1771453] Invoking callback
function 0x7688835c91b0 for event DDCA_Display_Status_Event[226.576: 
DDCA_EVENT_DISPLAY_CONNECTED, card1-HDMI-A-2, dref: DDCA_Display_Ref[9],
io_path:/dev/i2c-6, ddc working: false] in this thread [1771453]
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1771453] Callback function
0x7688835c91b0 for event DDCA_Display_Status_Event[226.576: 
DDCA_EVENT_DISPLAY_CONNECTED, card1-HDMI-A-2, dref: DDCA_Display_Ref[9],
io_path:/dev/i2c-6, ddc working: false] complete
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1764508] Quiescing
libddcutil API...
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768468] ddc became enabled
for Display_Ref[9:bus /dev/i2c-6 @0x76885801a5f0] after 0 milliseconds
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768468] Emitting
DDCA_Display_Status_Event[226.576:  DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2,
dref: DDCA_Display_Ref[9], io_path:/dev/i2c-6, ddc working: false]
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768468] libddcutil
callback thread 0x768854014c60 started
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768468] Started 1 event
callback thread(s)
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1771454] Invoking callback
function 0x7688835c91b0 for event DDCA_Display_Status_Event[226.576: 
DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2, dref: DDCA_Display_Ref[9],
io_path:/dev/i2c-6, ddc working: false] in this thread [1771454]
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1771454] Callback function
0x7688835c91b0 for event DDCA_Display_Status_Event[226.576: 
DDCA_EVENT_DDC_ENABLED, card1-HDMI-A-2, dref: DDCA_Display_Ref[9],
io_path:/dev/i2c-6, ddc working: false] complete
Mar 07 23:08:31 r9950 org_kde_powerdevil[1764508]: [1768468]
(dw_recheck_displays_func) Recheck interval: Slept for 200 millisec
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: Error queiscing libdducitl
API. 1 active API calls outstanding.
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: [1764508] Error queiscing
libdducitl API. 1 active API calls outstanding.
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: ddca_close_display -3032
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: library quiesced,
ddca_close_display temporarily unavailable
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: org.kde.powerdevil:
[DDCutilDisplay]: ddca_open_display2 -3032
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: [1764508] Display
redetection starting.
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: library quiesced,
ddca_open_display3 temporarily unavailable
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: [1768468] recheck thread
terminating because watch thread terminated
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: [1764508] Watch thread
terminated.
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: [1764508] Attempting to
unlock display lock owned by different thread
Mar 07 23:08:34 r9950 org_kde_powerdevil[1764508]: [1764508] Unexpected error
DDCRC_LOCKED from unlock_display_by_dpath(Display_Path[/dev/i2c-5])
```

-- 
You are receiving this mail because:
You are watching all bug changes.

Reply via email to