Comment # 36
on bug 1188896
from Sarah Kriesch
watchman can be more interesting with the information for Gerald:
[ 200s] Your build configuration:
[ 200s]
[ 200s] CC = gcc
[ 200s] CPPFLAGS = -D_REENTRANT -D_FILE_OFFSET_BITS=64
-D_LARGEFILE_SOURCE
[ 200s] CFLAGS = -O2 -g -m64 -fmessage-length=0 -D_FORTIFY_SOURCE=2
-fstack-protector -funwind-tables -fasynchronous-unwind-tables
-fno-strict-aliasing -Wall -Wextra -Wdeclaration-after-statement -g -gdwarf-2
-fno-omit-frame-pointer
[ 200s] CXX = g++
[ 200s] CXXFLAGS = -O2 -g -m64 -fmessage-length=0 -D_FORTIFY_SOURCE=2
-fstack-protector -funwind-tables -fasynchronous-unwind-tables
-Wno-format-truncation -Wall -Wextra -g -gdwarf-2 -fno-omit-frame-pointer
[ 200s] LDFLAGS = -flto=auto
[ 200s] prefix: /usr
[ 200s] version: 4.9.0
[ 200s] state directory: /run/watchman
[ 200s]
[ 200s] + make -j2
[ 201s] make all-am
[ 201s] make[1]: Entering directory
'/home/abuild/rpmbuild/BUILD/watchman-4.9.0'
[ 201s] CXX watchman-ChildProcess.o
[ 201s] CXX watchman-ContentHash.o
[ 223s] CXX watchman-CookieSync.o
[ 233s] CXX watchman-FileDescriptor.o
[ 237s] [ 217.346928][ T31] BUG: Bad rss-counter state mm:000000004df6d83a
type:MM_FILEPAGES val:-704
[ 238s] CXX watchman-FileInformation.o
[ 240s] CXX watchman-InMemoryView.o
[ 242s] CXX watchman-Pipe.o
[ 252s] CXX watchman-PubSub.o
[ 266s] CXX watchman-ThreadPool.o
[ 273s] CXX watchman-QueryableView.o
[ 281s] [ 261.409910][ T4933] BUG: Bad page state in process cc1plus
pfn:a5101
[ 281s] [ 261.548175][ T4933] BUG: Bad page state in process cc1plus
pfn:a5102
[ 281s] [ 261.548328][ T4933] BUG: Bad page state in process cc1plus
pfn:a5103
[ 281s] [ 261.548420][ T4933] BUG: Bad page state in process cc1plus
pfn:a5104
[ 281s] [ 261.548511][ T4933] BUG: Bad page state in process cc1plus
pfn:a5105
[ 281s] [ 261.548625][ T4933] BUG: Bad page state in process cc1plus
pfn:a5106
[ 281s] [ 261.548704][ T4933] BUG: Bad page state in process cc1plus
pfn:a5107
[ 281s] [ 261.548781][ T4933] BUG: Bad page state in process cc1plus
pfn:a5108
[ 281s] [ 261.548858][ T4933] BUG: Bad page state in process cc1plus
pfn:a5109
[ 281s] [ 261.549006][ T4933] BUG: Bad page state in process cc1plus
pfn:a510a
[ 281s] [ 261.549091][ T4933] BUG: Bad page state in process cc1plus
pfn:a510b
[ 281s] [ 261.549172][ T4933] BUG: Bad page state in process cc1plus
pfn:a510c
[ 281s] [ 261.549251][ T4933] BUG: Bad page state in process cc1plus
pfn:a510d
[ 281s] [ 261.549329][ T4933] BUG: Bad page state in process cc1plus
pfn:a510e
[ 281s] [ 261.549407][ T4933] BUG: Bad page state in process cc1plus
pfn:a510f
[ 281s] [ 261.549485][ T4933] BUG: Bad page state in process cc1plus
pfn:a5110
[ 281s] [ 261.549562][ T4933] BUG: Bad page state in process cc1plus
pfn:a5111
[ 281s] [ 261.549639][ T4933] BUG: Bad page state in process cc1plus
pfn:a5112
[ 281s] [ 261.549716][ T4933] BUG: Bad page state in process cc1plus
pfn:a5113
[ 281s] [ 261.549793][ T4933] BUG: Bad page state in process cc1plus
pfn:a5114
[ 281s] [ 261.549870][ T4933] BUG: Bad page state in process cc1plus
pfn:a5115
[ 281s] [ 261.549947][ T4933] BUG: Bad page state in process cc1plus
pfn:a5116
[ 281s] [ 261.550024][ T4933] BUG: Bad page state in process cc1plus
pfn:a5117
[ 281s] [ 261.550111][ T4933] BUG: Bad page state in process cc1plus
pfn:a5118
[ 281s] [ 261.550190][ T4933] BUG: Bad page state in process cc1plus
pfn:a5119
[ 281s] [ 261.550269][ T4933] BUG: Bad page state in process cc1plus
pfn:a511a
[ 281s] [ 261.550348][ T4933] BUG: Bad page state in process cc1plus
pfn:a511b
[ 281s] [ 261.550427][ T4933] BUG: Bad page state in process cc1plus
pfn:a511c
[ 281s] [ 261.550505][ T4933] BUG: Bad page state in process cc1plus
pfn:a511d
[ 281s] [ 261.550583][ T4933] BUG: Bad page state in process cc1plus
pfn:a511e
[ 281s] [ 261.550661][ T4933] BUG: Bad page state in process cc1plus
pfn:a511f
[ 281s] [ 261.550740][ T4933] BUG: Bad page state in process cc1plus
pfn:a5120
[ 281s] [ 261.550818][ T4933] BUG: Bad page state in process cc1plus
pfn:a5121
[ 281s] [ 261.550896][ T4933] BUG: Bad page state in process cc1plus
pfn:a5122
[ 281s] [ 261.550974][ T4933] BUG: Bad page state in process cc1plus
pfn:a5123
[ 281s] [ 261.551052][ T4933] BUG: Bad page state in process cc1plus
pfn:a5124
[ 281s] [ 261.551131][ T4933] BUG: Bad page state in process cc1plus
pfn:a5125
[ 281s] [ 261.551209][ T4933] BUG: Bad page state in process cc1plus
pfn:a5126
[ 281s] [ 261.551287][ T4933] BUG: Bad page state in process cc1plus
pfn:a5127
[ 281s] [ 261.551366][ T4933] BUG: Bad page state in process cc1plus
pfn:a5128
[ 281s] [ 261.551444][ T4933] BUG: Bad page state in process cc1plus
pfn:a5129
[ 281s] [ 261.551523][ T4933] BUG: Bad page state in process cc1plus
pfn:a512a
[ 281s] [ 261.551602][ T4933] BUG: Bad page state in process cc1plus
pfn:a512b
[ 281s] [ 261.551679][ T4933] BUG: Bad page state in process cc1plus
pfn:a512c
[ 281s] [ 261.551757][ T4933] BUG: Bad page state in process cc1plus
pfn:a512d
[ 281s] [ 261.551836][ T4933] BUG: Bad page state in process cc1plus
pfn:a512e
[ 281s] [ 261.551914][ T4933] BUG: Bad page state in process cc1plus
pfn:a512f
[ 281s] [ 261.551993][ T4933] BUG: Bad page state in process cc1plus
pfn:a5130
[ 281s] [ 261.552071][ T4933] BUG: Bad page state in process cc1plus
pfn:a5131
[ 281s] [ 261.552150][ T4933] BUG: Bad page state in process cc1plus
pfn:a5132
[ 281s] [ 261.552228][ T4933] BUG: Bad page state in process cc1plus
pfn:a5133
[ 281s] [ 261.552307][ T4933] BUG: Bad page state in process cc1plus
pfn:a5134
[ 281s] [ 261.552385][ T4933] BUG: Bad page state in process cc1plus
pfn:a5135
[ 281s] [ 261.552463][ T4933] BUG: Bad page state in process cc1plus
pfn:a5136
[ 281s] [ 261.552541][ T4933] BUG: Bad page state in process cc1plus
pfn:a5137
[ 281s] [ 261.552619][ T4933] BUG: Bad page state in process cc1plus
pfn:a5138
[ 281s] [ 261.552697][ T4933] BUG: Bad page state in process cc1plus
pfn:a5139
[ 281s] [ 261.552775][ T4933] BUG: Bad page state in process cc1plus
pfn:a513a
[ 281s] [ 261.552853][ T4933] BUG: Bad page state in process cc1plus
pfn:a513b
[ 281s] [ 261.552931][ T4933] BUG: Bad page state in process cc1plus
pfn:a513c
[ 281s] CXX watchman-spawn.o
[ 281s] CXX watchman-opt.o
[ 341s] [ 321.678657][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 341s] [ 321.835415][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 6009 (4294969041-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 341s] [ 321.999873][ C1] rcu: rcu_sched kthread starved for 6009
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 341s] [ 321.999941][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 341s] [ 321.999991][ C1] rcu: RCU grace-period kthread stack dump:
[ 341s] [ 322.000063][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 521s] [ 501.720088][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 521s] [ 501.745510][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 24014 (4294987046-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 521s] [ 501.745589][ C1] rcu: rcu_sched kthread starved for 24014
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 521s] [ 501.745651][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 521s] [ 501.745703][ C1] rcu: RCU grace-period kthread stack dump:
[ 521s] [ 501.745780][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 701s] [ 681.773572][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 701s] [ 682.108309][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 42019 (4295005051-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 701s] [ 682.108442][ C1] rcu: rcu_sched kthread starved for 42019
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 701s] [ 682.108525][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 701s] [ 682.108591][ C1] rcu: RCU grace-period kthread stack dump:
[ 701s] [ 682.108684][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 881s] [ 861.874041][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 881s] [ 861.882314][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 60029 (4295023061-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 881s] [ 861.882572][ C1] rcu: rcu_sched kthread starved for 60029
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 881s] [ 861.882727][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 881s] [ 861.882871][ C1] rcu: RCU grace-period kthread stack dump:
[ 881s] [ 861.883009][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 1061s] [ 1042.112078][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 1061s] [ 1042.283713][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 78053 (4295041085-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 1061s] [ 1042.283807][ C1] rcu: rcu_sched kthread starved for 78053
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 1061s] [ 1042.283870][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 1061s] [ 1042.283932][ C1] rcu: RCU grace-period kthread stack dump:
[ 1061s] [ 1042.284015][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 1241s] [ 1222.171679][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 1241s] [ 1222.283107][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 96059 (4295059091-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 1241s] [ 1222.283256][ C1] rcu: rcu_sched kthread starved for 96059
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 1241s] [ 1222.283888][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 1241s] [ 1222.284576][ C1] rcu: RCU grace-period kthread stack dump:
[ 1241s] [ 1222.284728][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 1421s] [ 1402.231104][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 1421s] [ 1402.340906][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 114065 (4295077097-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 1421s] [ 1402.341051][ C1] rcu: rcu_sched kthread starved for 114065
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 1421s] [ 1402.341128][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 1421s] [ 1402.341181][ C1] rcu: RCU grace-period kthread stack dump:
[ 1421s] [ 1402.341278][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 1602s] [ 1582.298865][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 1602s] [ 1582.888473][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 132071 (4295095103-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 1602s] [ 1582.888571][ C1] rcu: rcu_sched kthread starved for 132071
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 1602s] [ 1582.888644][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 1602s] [ 1582.888705][ C1] rcu: RCU grace-period kthread stack dump:
[ 1602s] [ 1582.888794][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 1782s] [ 1762.387579][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 1782s] [ 1762.387727][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 150080 (4295113112-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 1782s] [ 1762.387796][ C1] rcu: rcu_sched kthread starved for 150080
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 1782s] [ 1762.387850][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 1782s] [ 1762.387895][ C1] rcu: RCU grace-period kthread stack dump:
[ 1782s] [ 1762.388019][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 1962s] [ 1942.446760][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 1962s] [ 1942.485300][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 168086 (4295131118-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 1962s] [ 1942.485375][ C1] rcu: rcu_sched kthread starved for 168086
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 1962s] [ 1942.485429][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 1962s] [ 1942.485475][ C1] rcu: RCU grace-period kthread stack dump:
[ 1962s] [ 1942.485546][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 2142s] [ 2122.502705][ C0] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 2142s] [ 2122.894815][ C0] rcu: All QSes seen, last rcu_sched kthread
activity 186131 (4295149163-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 2142s] [ 2122.936517][ C0] rcu: rcu_sched kthread starved for 186135
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 2142s] [ 2122.936628][ C0] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 2142s] [ 2122.936718][ C0] rcu: RCU grace-period kthread stack dump:
[ 2142s] [ 2122.936848][ C0] rcu: Stack dump where RCU GP kthread last ran:
[ 2323s] [ 2302.995674][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 2323s] [ 2303.517840][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 204141 (4295167173-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 2323s] [ 2303.517936][ C1] rcu: rcu_sched kthread starved for 204141
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 2323s] [ 2303.518007][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 2323s] [ 2303.518068][ C1] rcu: RCU grace-period kthread stack dump:
[ 2323s] [ 2303.518146][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 2503s] [ 2483.050239][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 2503s] [ 2483.422236][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 222147 (4295185179-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 2503s] [ 2483.422331][ C1] rcu: rcu_sched kthread starved for 222147
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 2503s] [ 2483.422407][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 2503s] [ 2483.422471][ C1] rcu: RCU grace-period kthread stack dump:
[ 2503s] [ 2483.422556][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 2682s] [ 2663.146691][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 2682s] [ 2663.146864][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 240156 (4295203188-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 2683s] [ 2663.355686][ C1] rcu: rcu_sched kthread starved for 240156
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 2683s] [ 2663.540275][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 2683s] [ 2663.540327][ C1] rcu: RCU grace-period kthread stack dump:
[ 2683s] [ 2663.540402][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 2862s] [ 2843.200089][ C0] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 2862s] [ 2843.297826][ C0] rcu: All QSes seen, last rcu_sched kthread
activity 258171 (4295221203-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 2862s] [ 2843.297958][ C0] rcu: rcu_sched kthread starved for 258171
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 2862s] [ 2843.298021][ C0] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 2862s] [ 2843.298076][ C0] rcu: RCU grace-period kthread stack dump:
[ 2862s] [ 2843.298154][ C0] rcu: Stack dump where RCU GP kthread last ran:
[ 3043s] [ 3023.402002][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 3043s] [ 3023.402199][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 276182 (4295239214-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 3043s] [ 3023.402283][ C1] rcu: rcu_sched kthread starved for 276182
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 3043s] [ 3023.402399][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 3043s] [ 3023.402490][ C1] rcu: RCU grace-period kthread stack dump:
[ 3043s] [ 3023.402584][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 3223s] [ 3203.450092][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 3223s] [ 3203.557258][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 294187 (4295257219-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 3223s] [ 3203.608733][ C1] rcu: rcu_sched kthread starved for 294187
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 3223s] [ 3203.608899][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.
[ 3223s] [ 3203.609082][ C1] rcu: RCU grace-period kthread stack dump:
[ 3223s] [ 3203.609171][ C1] rcu: Stack dump where RCU GP kthread last ran:
[ 3403s] [ 3383.506111][ C1] rcu: INFO: rcu_sched detected stalls on
CPUs/tasks:
[ 3403s] [ 3383.509427][ C1] rcu: All QSes seen, last rcu_sched kthread
activity 312192 (4295275224-4294963032), jiffies_till_next_fqs=1, root ->qsmask
0x0
[ 3403s] [ 3383.540655][ C1] rcu: rcu_sched kthread starved for 312192
jiffies! g6677 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
[ 3403s] [ 3383.540723][ C1] rcu: Unless rcu_sched kthread gets
sufficient CPU time, OOM is now expected behavior.