Running it now, will report back in a bit.
Captured one
Bitcoind:
2015-12-12 23:45:46.055397 UpdateTip: new best=0000000000000000057896223e152a585b48c27bfd0757f901116f4df1a026b2
2015-12-12 23:46:04.861979 CreateNewBlock()
p2pool:
2015-12-12 18:45:43.226373 work_poller sleeping
2015-12-12 18:45:44.580614 P2Pool: 17510 shares in chain (10184 verified/17514 total) Peers: 7 (0 incoming)
2015-12-12 18:45:44.580730 Local: 859GH/s in last 2.8 minutes Local dead on arrival: ~4.9% (3-7%) Expected time to share: 2.3 hours
2015-12-12 18:45:44.580780 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0018)=0.0018 BTC
2015-12-12 18:45:44.580821 Pool: 613TH/s Stale rate: 20.5% Expected time to block: 6.4 days
2015-12-12 18:45:46.060562 work_poller triggered 0
2015-12-12 18:45:46.591346 Skipping from block c961a121526cc3eea1788faec200b43ab71db3853833a8e to block 57896223e152a585b48c27bfd0757f901116f4df1a026b2!
2015-12-12 18:45:46.604193 New work for worker! Difficulty: 1300.617269 Share difficulty: 9373917.033071 Total block value: 25.000000 BTC including 0 transactions
2015-12-12 18:45:46.862936 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:45:49.709867 work_poller sleeping
2015-12-12 18:45:49.723776 P2Pool: 17509 shares in chain (10184 verified/17514 total) Peers: 7 (0 incoming)
2015-12-12 18:45:49.723931 Local: 863GH/s in last 2.9 minutes Local dead on arrival: ~4.9% (3-7%) Expected time to share: 2.3 hours
2015-12-12 18:45:49.723991 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0017)=0.0017 BTC
2015-12-12 18:45:49.724073 Pool: 616TH/s Stale rate: 20.5% Expected time to block: 6.4 days
2015-12-12 18:45:51.569227 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:45:51.578247 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:45:52.095941 Peer sent entire transaction a2f01bd80b0b08eac507b98b7d4cfcebf291148f350e8d48f344b6d8dc295308 that was already received
2015-12-12 18:45:52.341543 Peer sent entire transaction c0dea60acb5a945e4dbfe490533555aa747b82cf358e5b81b311f97975061a76 that was already received
2015-12-12 18:45:52.734235 P2Pool: 17509 shares in chain (10184 verified/17514 total) Peers: 7 (0 incoming)
2015-12-12 18:45:52.734375 Local: 877GH/s in last 2.9 minutes Local dead on arrival: ~5.0% (3-7%) Expected time to share: 2.3 hours
2015-12-12 18:45:52.734432 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0017)=0.0017 BTC
2015-12-12 18:45:52.734493 Pool: 616TH/s Stale rate: 20.5% Expected time to block: 6.4 days
2015-12-12 18:45:55.739050 P2Pool: 17509 shares in chain (10184 verified/17514 total) Peers: 7 (0 incoming)
2015-12-12 18:45:55.739190 Local: 893GH/s in last 3.0 minutes Local dead on arrival: ~5.1% (3-7%) Expected time to share: 2.2 hours
2015-12-12 18:45:55.739244 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0017)=0.0017 BTC
2015-12-12 18:45:55.739283 Pool: 616TH/s Stale rate: 20.5% Expected time to block: 6.4 days
2015-12-12 18:45:56.595158 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:45:56.606212 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:45:57.728314 Peer sent entire transaction b162860a4358618d94e89809bea4f8471e3f581c0dc1f407faecda09676713a5 that was already received
2015-12-12 18:45:58.753944 P2Pool: 17509 shares in chain (10184 verified/17514 total) Peers: 7 (0 incoming)
2015-12-12 18:45:58.754053 Local: 940GH/s in last 3.0 minutes Local dead on arrival: ~5.2% (4-7%) Expected time to share: 2.1 hours
2015-12-12 18:45:58.754113 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0017)=0.0017 BTC
2015-12-12 18:45:58.754161 Pool: 616TH/s Stale rate: 20.5% Expected time to block: 6.4 days
2015-12-12 18:46:01.631496 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:46:01.643085 Punishing share for 'Block-stale detected! height(c961a121526cc3eea1788faec200b43ab71db3853833a8e) < height(57896223e152a585b48c27bfd0757f901116f4df1a026b2) or 180de64f != 180de64f'! Jumping from 18b25839 to 301798e2!
2015-12-12 18:46:01.779099 P2Pool: 17509 shares in chain (10184 verified/17514 total) Peers: 7 (0 incoming)
2015-12-12 18:46:01.779293 Local: 955GH/s in last 3.1 minutes Local dead on arrival: ~5.2% (4-7%) Expected time to share: 2.1 hours
2015-12-12 18:46:01.779401 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0017)=0.0017 BTC
2015-12-12 18:46:01.779491 Pool: 616TH/s Stale rate: 20.5% Expected time to block: 6.4 days
2015-12-12 18:46:04.636870 New work for worker! Difficulty: 1413.903366 Share difficulty: 9448997.950860 Total block value: 25.114565 BTC including 2345 transactions
2015-12-12 18:46:04.823342 work_poller triggered 1
2015-12-12 18:46:04.835841 P2Pool: 17510 shares in chain (10185 verified/17515 total) Peers: 7 (0 incoming)
2015-12-12 18:46:04.835984 Local: 969GH/s in last 3.1 minutes Local dead on arrival: ~5.2% (4-7%) Expected time to share: 2.0 hours
2015-12-12 18:46:04.836040 Shares: 0 (0 orphan, 0 dead) Stale rate: ??? Efficiency: ??? Current payout: (0.0017)=0.0017 BTC
2015-12-12 18:46:04.836098 Pool: 608TH/s Stale rate: 20.5% Expected time to block: 6.5 days
2015-12-12 18:46:06.747933 work_poller sleeping
EDIT: looks ok to me. Maybe it's just bitcoind hiding the call debug message. I'll investigate further at my end with more debugging in bitcoind, thanks.
Investigated further and added debugging to see when GBT was called since calls to CNB are cached and it is indeed calling GBT immediately after a blockchange, so this was all my fault for not noticing, sorry about the noise.