Hi,
在 2024/11/01 16:33, Christian Theune 写道:
I dug out a different one that goes back longer but even that one seems like
something was missing early on when I didn’t have the serial console attached.
I’m wondering whether this indicates an issue during initialization? I’m going
to reboot the machine and make sure i get the early logs with those numbers.
[ 405.347345] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22301786792+8) 4294967259
For this log, let's assume the firt start is from here.
[ 432.542465] __add_stripe_bio: md127: start ff2721beec8c2fa0(22837701992+8)
4294967260
[ 432.542469] __add_stripe_bio: md127: start ff2721beec8c2fa0(22837701992+8)
4294967261
[ 434.272964] __add_stripe_bio: md127: start ff2721beec8c2fa0(22837701992+8)
4294967262
[ 434.273175] __add_stripe_bio: md127: start ff2721beec8c2fa0(22837701992+8)
4294967263
[ 434.273189] __add_stripe_bio: md127: start ff2721beec8c2fa0(22837701992+8)
4294967264
[ 434.273285] __add_stripe_bio: md127: start ff2721beec8c2fa0(22837701992+8)
4294967265
[ 434.274063] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22837701992+8) 4294967264
[ 434.274066] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22837701992+8) 4294967263
[ 434.274070] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22837701992+8) 4294967262
[ 434.274073] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22837701992+8) 4294967261
[ 434.274078] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22837701992+8) 4294967260
[ 434.274083] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(22837701992+8) 4294967259
[ 434.276609] __add_stripe_bio: md127: start ff2721beec8c2fa0(23374951848+8)
4294967260
[ 434.278939] __add_stripe_bio: md127: start ff2721beec8c2fa0(23374951848+8)
4294967261
[ 464.922354] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(23374951848+8) 4294967260
[ 464.931833] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(23374951848+8) 4294967259
[ 466.964557] __add_stripe_bio: md127: start ff2721beec8c2fa0(23912715112+8)
4294967260
[ 466.964616] __add_stripe_bio: md127: start ff2721beec8c2fa0(23912715112+8)
4294967261
[ 474.399930] __add_stripe_bio: md127: start ff2721beec8c2fa0(23912715112+8)
4294967262
[ 474.451451] __add_stripe_bio: md127: start ff2721beec8c2fa0(23912715112+8)
4294967263
[ 489.447079] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(23912715112+8) 4294967262
[ 489.456574] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(23912715112+8) 4294967261
[ 489.466069] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(23912715112+8) 4294967260
[ 489.475565] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(23912715112+8) 4294967259
[ 491.235517] __add_stripe_bio: md127: start ff2721beec8c2fa0(24448073512+8)
4294967260
[ 491.235602] __add_stripe_bio: md127: start ff2721beec8c2fa0(24448073512+8)
4294967261
[ 498.153108] __add_stripe_bio: md127: start ff2721beec8c2fa0(24716445096+8)
4294967262
[ 498.156307] __add_stripe_bio: md127: start ff2721beec8c2fa0(24716445096+8)
4294967263
[ 530.332619] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(24716445096+8) 4294967262
[ 530.342110] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(24716445096+8) 4294967261
[ 530.351595] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(24716445096+8) 4294967260
[ 530.361082] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(24716445096+8) 4294967259
[ 535.176774] __add_stripe_bio: md127: start ff2721beec8c2fa0(24985208424+8)
4294967260
[ 549.125326] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(24985208424+8) 4294967259
Then until now, everything is good, start and end is balanced for this
stripe head.
[ 549.635782] __add_stripe_bio: md127: start ff2721beec8c2fa0(25521770024+8)
4294967261
[ 590.875593] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(25521770024+8) 4294967260
[ 590.885081] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(25521770024+8) 4294967259
[ 596.973863] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(26057037928+8) 4294967263
[ 596.973866] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(26057037928+8) 4294967262
[ 596.973869] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(26057037928+8) 4294967261
[ 596.973871] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(26057037928+8) 4294967260
[ 596.973881] handle_stripe_clean_event: md127: end
ff2721beec8c2fa0(26057037928+8) 4294967259
Then, oops, this 'sh' start just once here, and end lots of times. It's
unlikely that those end are corresponding to the log much earlier, so
I'm almost convinced that this problem is due to unbalanced start and
end. And the huge number is due to underflow.
Let me dig more. :)
Thanks,
Kuai
