{"level":"warn","ts":"2026-08-05T10:59:42.386154Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T10:59:42.386278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.129:2380","--initial-cluster=instance=https://199.19.213.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-05T10:59:42.386556Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T10:59:42.386644Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-08-05T10:59:42.386679Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-05T10:59:42.387834Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"]} {"level":"info","ts":"2026-08-05T10:59:42.388028Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-05T10:59:42.393299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.543492ms"} {"level":"info","ts":"2026-08-05T10:59:42.399389Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d319cd7bf0a1887b","cluster-id":"2ab213cc48e2c85a"} {"level":"info","ts":"2026-08-05T10:59:42.399525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T10:59:42.399549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 0"} {"level":"info","ts":"2026-08-05T10:59:42.39956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d319cd7bf0a1887b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T10:59:42.399569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 1"} {"level":"info","ts":"2026-08-05T10:59:42.39961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"warn","ts":"2026-08-05T10:59:42.406871Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T10:59:42.40887Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T10:59:42.412315Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-05T10:59:42.414598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d319cd7bf0a1887b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T10:59:42.414709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d319cd7bf0a1887b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T10:59:42.415063Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-05T10:59:42.415486Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-05T10:59:42.415502Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-05T10:59:42.415828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"info","ts":"2026-08-05T10:59:42.416123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","added-peer-id":"d319cd7bf0a1887b","added-peer-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-08-05T10:59:42.41835Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-05T10:59:42.418465Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-08-05T10:59:42.418493Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-08-05T10:59:42.418724Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d319cd7bf0a1887b","initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T10:59:42.418766Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T10:59:42.801408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T10:59:42.801542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T10:59:42.801592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgPreVoteResp from d319cd7bf0a1887b at term 1"} {"level":"info","ts":"2026-08-05T10:59:42.801659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became candidate at term 2"} {"level":"info","ts":"2026-08-05T10:59:42.8017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgVoteResp from d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-08-05T10:59:42.801739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became leader at term 2"} {"level":"info","ts":"2026-08-05T10:59:42.80179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d319cd7bf0a1887b elected leader d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-08-05T10:59:42.802774Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d319cd7bf0a1887b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.129:2379]}","request-path":"/0/members/d319cd7bf0a1887b/attributes","cluster-id":"2ab213cc48e2c85a","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T10:59:42.802868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T10:59:42.802959Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T10:59:42.803008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T10:59:42.803183Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T10:59:42.807481Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T10:59:42.806511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.129:2379"} {"level":"info","ts":"2026-08-05T10:59:42.809121Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T10:59:42.815302Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T10:59:42.821282Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T10:59:42.821391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:00:19.810987Z","caller":"traceutil/trace.go:171","msg":"trace[887859855] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"162.778897ms","start":"2026-08-05T11:00:19.648187Z","end":"2026-08-05T11:00:19.810965Z","steps":["trace[887859855] 'process raft request' (duration: 157.609369ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:19.81133Z","caller":"traceutil/trace.go:171","msg":"trace[775551442] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"160.163197ms","start":"2026-08-05T11:00:19.651156Z","end":"2026-08-05T11:00:19.811319Z","steps":["trace[775551442] 'process raft request' (duration: 159.885758ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:19.811348Z","caller":"traceutil/trace.go:171","msg":"trace[343928576] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"145.336964ms","start":"2026-08-05T11:00:19.665985Z","end":"2026-08-05T11:00:19.811322Z","steps":["trace[343928576] 'read index received' (duration: 139.866176ms)","trace[343928576] 'applied index is now lower than readState.Index' (duration: 5.469888ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:00:19.811389Z","caller":"traceutil/trace.go:171","msg":"trace[673221912] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"100.183856ms","start":"2026-08-05T11:00:19.711198Z","end":"2026-08-05T11:00:19.811382Z","steps":["trace[673221912] 'process raft request' (duration: 100.009071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:19.811478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.48921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:00:19.811528Z","caller":"traceutil/trace.go:171","msg":"trace[1870216312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"145.586463ms","start":"2026-08-05T11:00:19.665933Z","end":"2026-08-05T11:00:19.81152Z","steps":["trace[1870216312] 'agreement among raft nodes before linearized reading' (duration: 145.49916ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:19.980023Z","caller":"traceutil/trace.go:171","msg":"trace[458096573] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"150.788854ms","start":"2026-08-05T11:00:19.829201Z","end":"2026-08-05T11:00:19.97999Z","steps":["trace[458096573] 'process raft request' (duration: 142.506358ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:19.980572Z","caller":"traceutil/trace.go:171","msg":"trace[124114995] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"141.908278ms","start":"2026-08-05T11:00:19.838645Z","end":"2026-08-05T11:00:19.980554Z","steps":["trace[124114995] 'process raft request' (duration: 141.715051ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:19.980725Z","caller":"traceutil/trace.go:171","msg":"trace[656908679] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"101.270615ms","start":"2026-08-05T11:00:19.879441Z","end":"2026-08-05T11:00:19.980711Z","steps":["trace[656908679] 'process raft request' (duration: 101.044607ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:26.014305Z","caller":"traceutil/trace.go:171","msg":"trace[51524972] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"222.236733ms","start":"2026-08-05T11:00:25.792042Z","end":"2026-08-05T11:00:26.014279Z","steps":["trace[51524972] 'read index received' (duration: 221.399355ms)","trace[51524972] 'applied index is now lower than readState.Index' (duration: 836.448µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:00:26.014417Z","caller":"traceutil/trace.go:171","msg":"trace[666318572] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"227.082519ms","start":"2026-08-05T11:00:25.787325Z","end":"2026-08-05T11:00:26.014408Z","steps":["trace[666318572] 'process raft request' (duration: 226.364535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:26.014698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.355058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wxkfx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-05T11:00:26.014819Z","caller":"traceutil/trace.go:171","msg":"trace[351752946] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wxkfx; range_end:; response_count:1; response_revision:626; }","duration":"211.501372ms","start":"2026-08-05T11:00:25.803295Z","end":"2026-08-05T11:00:26.014797Z","steps":["trace[351752946] 'agreement among raft nodes before linearized reading' (duration: 211.228583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:26.014829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.757919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-05T11:00:26.014922Z","caller":"traceutil/trace.go:171","msg":"trace[509816103] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; response_count:1; response_revision:626; }","duration":"222.884602ms","start":"2026-08-05T11:00:25.792014Z","end":"2026-08-05T11:00:26.014899Z","steps":["trace[509816103] 'agreement among raft nodes before linearized reading' (duration: 222.693136ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:26.342715Z","caller":"traceutil/trace.go:171","msg":"trace[237279233] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"313.190011ms","start":"2026-08-05T11:00:26.029484Z","end":"2026-08-05T11:00:26.342674Z","steps":["trace[237279233] 'process raft request' (duration: 313.018245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:26.343256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:26.029461Z","time spent":"313.330455ms","remote":"127.0.0.1:38922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:00:26.360918Z","caller":"traceutil/trace.go:171","msg":"trace[1174210133] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"298.61315ms","start":"2026-08-05T11:00:26.062284Z","end":"2026-08-05T11:00:26.360897Z","steps":["trace[1174210133] 'process raft request' (duration: 298.342281ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:26.36094Z","caller":"traceutil/trace.go:171","msg":"trace[284164145] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"290.184078ms","start":"2026-08-05T11:00:26.070706Z","end":"2026-08-05T11:00:26.36089Z","steps":["trace[284164145] 'process raft request' (duration: 290.099326ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:27.110412Z","caller":"traceutil/trace.go:171","msg":"trace[846214798] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"706.76152ms","start":"2026-08-05T11:00:26.403628Z","end":"2026-08-05T11:00:27.110389Z","steps":["trace[846214798] 'process raft request' (duration: 706.625266ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:00:27.110467Z","caller":"traceutil/trace.go:171","msg":"trace[1254327687] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:652; }","duration":"443.157919ms","start":"2026-08-05T11:00:26.667286Z","end":"2026-08-05T11:00:27.110444Z","steps":["trace[1254327687] 'read index received' (duration: 443.147369ms)","trace[1254327687] 'applied index is now lower than readState.Index' (duration: 8.42µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:00:27.110562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:26.403618Z","time spent":"706.867084ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:00:27.110764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.402477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:00:27.110927Z","caller":"traceutil/trace.go:171","msg":"trace[250592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"443.679176ms","start":"2026-08-05T11:00:26.667224Z","end":"2026-08-05T11:00:27.110903Z","steps":["trace[250592] 'agreement among raft nodes before linearized reading' (duration: 443.320854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:27.111022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:26.667194Z","time spent":"443.81536ms","remote":"127.0.0.1:38756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:00:27.127174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.133204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:00:27.127274Z","caller":"traceutil/trace.go:171","msg":"trace[429839333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"323.280928ms","start":"2026-08-05T11:00:26.803958Z","end":"2026-08-05T11:00:27.127239Z","steps":["trace[429839333] 'agreement among raft nodes before linearized reading' (duration: 320.614092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:27.127311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:26.803929Z","time spent":"323.373851ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T11:00:27.12562Z","caller":"traceutil/trace.go:171","msg":"trace[908840429] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"379.370637ms","start":"2026-08-05T11:00:26.74571Z","end":"2026-08-05T11:00:27.12508Z","steps":["trace[908840429] 'process raft request' (duration: 377.043363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:27.130075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:26.745683Z","time spent":"383.785709ms","remote":"127.0.0.1:38820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:00:27.13172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.391951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T11:00:27.131837Z","caller":"traceutil/trace.go:171","msg":"trace[189430773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"412.523026ms","start":"2026-08-05T11:00:26.719274Z","end":"2026-08-05T11:00:27.131797Z","steps":["trace[189430773] 'agreement among raft nodes before linearized reading' (duration: 405.225222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:27.132434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:26.719255Z","time spent":"412.65129ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:00:41.980674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.316457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T11:00:41.980788Z","caller":"traceutil/trace.go:171","msg":"trace[1896289699] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"465.75362ms","start":"2026-08-05T11:00:41.515017Z","end":"2026-08-05T11:00:41.980771Z","steps":["trace[1896289699] 'read index received' (duration: 153.061662ms)","trace[1896289699] 'applied index is now lower than readState.Index' (duration: 312.690518ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:00:41.981113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.044668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T11:00:41.981234Z","caller":"traceutil/trace.go:171","msg":"trace[117793212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"466.272984ms","start":"2026-08-05T11:00:41.514937Z","end":"2026-08-05T11:00:41.98121Z","steps":["trace[117793212] 'agreement among raft nodes before linearized reading' (duration: 465.879093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:00:41.981285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:00:41.514905Z","time spent":"466.370877ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T11:00:56.165703Z","caller":"traceutil/trace.go:171","msg":"trace[362187545] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"105.390789ms","start":"2026-08-05T11:00:56.060286Z","end":"2026-08-05T11:00:56.165676Z","steps":["trace[362187545] 'process raft request' (duration: 29.984788ms)","trace[362187545] 'compare' (duration: 75.289368ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:01:11.74668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.527998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:01:11.747005Z","caller":"traceutil/trace.go:171","msg":"trace[911901249] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"332.342636ms","start":"2026-08-05T11:01:11.414643Z","end":"2026-08-05T11:01:11.746985Z","steps":["trace[911901249] 'process raft request' (duration: 332.302945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:01:11.747132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:01:11.414633Z","time spent":"332.460789ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:01:11.747298Z","caller":"traceutil/trace.go:171","msg":"trace[1295089071] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"333.293367ms","start":"2026-08-05T11:01:11.413993Z","end":"2026-08-05T11:01:11.747287Z","steps":["trace[1295089071] 'process raft request' (duration: 332.887248ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:01:11.747367Z","caller":"traceutil/trace.go:171","msg":"trace[2059405750] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"333.456381ms","start":"2026-08-05T11:01:11.413879Z","end":"2026-08-05T11:01:11.747336Z","steps":["trace[2059405750] 'process raft request' (duration: 113.172436ms)","trace[2059405750] 'compare' (duration: 219.376564ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:01:11.747397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:01:11.413973Z","time spent":"333.391709ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:01:11.747489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:01:11.413859Z","time spent":"333.572583ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:01:51.939086Z","caller":"traceutil/trace.go:171","msg":"trace[2116164640] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"100.050505ms","start":"2026-08-05T11:01:51.839008Z","end":"2026-08-05T11:01:51.939059Z","steps":["trace[2116164640] 'process raft request' (duration: 99.588737ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:01:58.754062Z","caller":"traceutil/trace.go:171","msg":"trace[1526025324] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"294.693949ms","start":"2026-08-05T11:01:58.459346Z","end":"2026-08-05T11:01:58.75404Z","steps":["trace[1526025324] 'read index received' (duration: 261.098495ms)","trace[1526025324] 'applied index is now lower than readState.Index' (duration: 33.594614ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:01:58.754222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.325291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:01:58.754257Z","caller":"traceutil/trace.go:171","msg":"trace[1851503755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"245.418343ms","start":"2026-08-05T11:01:58.50883Z","end":"2026-08-05T11:01:58.754248Z","steps":["trace[1851503755] 'agreement among raft nodes before linearized reading' (duration: 245.346602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:01:58.754368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.970684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T11:01:58.754228Z","caller":"traceutil/trace.go:171","msg":"trace[148188120] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"462.657204ms","start":"2026-08-05T11:01:58.291452Z","end":"2026-08-05T11:01:58.754109Z","steps":["trace[148188120] 'process raft request' (duration: 428.921466ms)","trace[148188120] 'compare' (duration: 33.32048ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:01:58.754488Z","caller":"traceutil/trace.go:171","msg":"trace[586855806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"295.176398ms","start":"2026-08-05T11:01:58.459291Z","end":"2026-08-05T11:01:58.754468Z","steps":["trace[586855806] 'agreement among raft nodes before linearized reading' (duration: 294.841732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:01:58.754669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:01:58.291428Z","time spent":"463.129852ms","remote":"127.0.0.1:38922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:01:59.226673Z","caller":"traceutil/trace.go:171","msg":"trace[748820643] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"152.233659ms","start":"2026-08-05T11:01:59.074405Z","end":"2026-08-05T11:01:59.226639Z","steps":["trace[748820643] 'process raft request' (duration: 152.057266ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:01:59.35341Z","caller":"traceutil/trace.go:171","msg":"trace[452246488] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"275.019528ms","start":"2026-08-05T11:01:59.078364Z","end":"2026-08-05T11:01:59.353383Z","steps":["trace[452246488] 'process raft request' (duration: 274.827615ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:01:59.35369Z","caller":"traceutil/trace.go:171","msg":"trace[1092226574] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"232.268796ms","start":"2026-08-05T11:01:59.121374Z","end":"2026-08-05T11:01:59.353642Z","steps":["trace[1092226574] 'process raft request' (duration: 231.963811ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:07.748277Z","caller":"traceutil/trace.go:171","msg":"trace[1341643727] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2024; }","duration":"125.703791ms","start":"2026-08-05T11:02:07.62255Z","end":"2026-08-05T11:02:07.748254Z","steps":["trace[1341643727] 'read index received' (duration: 125.424607ms)","trace[1341643727] 'applied index is now lower than readState.Index' (duration: 278.064µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:02:07.74855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.936356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:02:07.748736Z","caller":"traceutil/trace.go:171","msg":"trace[419560084] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1953; }","duration":"126.13376ms","start":"2026-08-05T11:02:07.622507Z","end":"2026-08-05T11:02:07.748641Z","steps":["trace[419560084] 'agreement among raft nodes before linearized reading' (duration: 125.871334ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:07.748957Z","caller":"traceutil/trace.go:171","msg":"trace[2071390419] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"127.210058ms","start":"2026-08-05T11:02:07.621112Z","end":"2026-08-05T11:02:07.748323Z","steps":["trace[2071390419] 'process raft request' (duration: 126.969924ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:16.327586Z","caller":"traceutil/trace.go:171","msg":"trace[1754431549] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"209.028784ms","start":"2026-08-05T11:02:16.118533Z","end":"2026-08-05T11:02:16.327562Z","steps":["trace[1754431549] 'read index received' (duration: 208.839841ms)","trace[1754431549] 'applied index is now lower than readState.Index' (duration: 187.853µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:02:16.327704Z","caller":"traceutil/trace.go:171","msg":"trace[551902771] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"209.466441ms","start":"2026-08-05T11:02:16.118208Z","end":"2026-08-05T11:02:16.327675Z","steps":["trace[551902771] 'process raft request' (duration: 209.219007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:02:16.327837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.281028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T11:02:16.327876Z","caller":"traceutil/trace.go:171","msg":"trace[35291904] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2007; }","duration":"209.35178ms","start":"2026-08-05T11:02:16.118511Z","end":"2026-08-05T11:02:16.327862Z","steps":["trace[35291904] 'agreement among raft nodes before linearized reading' (duration: 209.190047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:02:16.332094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.311214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T11:02:16.332153Z","caller":"traceutil/trace.go:171","msg":"trace[678698789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2009; }","duration":"170.415386ms","start":"2026-08-05T11:02:16.161724Z","end":"2026-08-05T11:02:16.332139Z","steps":["trace[678698789] 'agreement among raft nodes before linearized reading' (duration: 170.206772ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:16.332364Z","caller":"traceutil/trace.go:171","msg":"trace[455911612] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"212.49539ms","start":"2026-08-05T11:02:16.11979Z","end":"2026-08-05T11:02:16.332285Z","steps":["trace[455911612] 'process raft request' (duration: 211.997981ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:39.626811Z","caller":"traceutil/trace.go:171","msg":"trace[2119279073] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"119.134416ms","start":"2026-08-05T11:02:39.507654Z","end":"2026-08-05T11:02:39.626789Z","steps":["trace[2119279073] 'process raft request' (duration: 118.996314ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:39.640712Z","caller":"traceutil/trace.go:171","msg":"trace[681693196] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2220; }","duration":"118.340283ms","start":"2026-08-05T11:02:39.522352Z","end":"2026-08-05T11:02:39.640693Z","steps":["trace[681693196] 'read index received' (duration: 104.30125ms)","trace[681693196] 'applied index is now lower than readState.Index' (duration: 14.038053ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:02:39.640836Z","caller":"traceutil/trace.go:171","msg":"trace[579456548] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"127.200758ms","start":"2026-08-05T11:02:39.513623Z","end":"2026-08-05T11:02:39.640824Z","steps":["trace[579456548] 'process raft request' (duration: 126.977715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:02:39.64116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.76078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T11:02:39.641276Z","caller":"traceutil/trace.go:171","msg":"trace[1823121427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"118.900282ms","start":"2026-08-05T11:02:39.522339Z","end":"2026-08-05T11:02:39.64124Z","steps":["trace[1823121427] 'agreement among raft nodes before linearized reading' (duration: 118.536396ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:39.843835Z","caller":"traceutil/trace.go:171","msg":"trace[285859391] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"177.07864ms","start":"2026-08-05T11:02:39.666729Z","end":"2026-08-05T11:02:39.843807Z","steps":["trace[285859391] 'read index received' (duration: 122.133952ms)","trace[285859391] 'applied index is now lower than readState.Index' (duration: 54.943278ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:02:39.844046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.299843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:02:39.844074Z","caller":"traceutil/trace.go:171","msg":"trace[737563530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"177.384203ms","start":"2026-08-05T11:02:39.666679Z","end":"2026-08-05T11:02:39.844063Z","steps":["trace[737563530] 'agreement among raft nodes before linearized reading' (duration: 177.224931ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:02:39.844243Z","caller":"traceutil/trace.go:171","msg":"trace[124449184] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"198.286993ms","start":"2026-08-05T11:02:39.645928Z","end":"2026-08-05T11:02:39.844215Z","steps":["trace[124449184] 'process raft request' (duration: 142.98694ms)","trace[124449184] 'compare' (duration: 54.49597ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:02:40.185664Z","caller":"traceutil/trace.go:171","msg":"trace[884606784] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"323.610475ms","start":"2026-08-05T11:02:39.862Z","end":"2026-08-05T11:02:40.185611Z","steps":["trace[884606784] 'process raft request' (duration: 268.00359ms)","trace[884606784] 'compare' (duration: 54.981125ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:02:40.185965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:02:39.861968Z","time spent":"323.802329ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:03:29.924099Z","caller":"traceutil/trace.go:171","msg":"trace[2127129871] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"104.204822ms","start":"2026-08-05T11:03:29.81986Z","end":"2026-08-05T11:03:29.924065Z","steps":["trace[2127129871] 'process raft request' (duration: 32.447831ms)","trace[2127129871] 'compare' (duration: 71.164363ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:03:38.089346Z","caller":"traceutil/trace.go:171","msg":"trace[669827352] linearizableReadLoop","detail":"{readStateIndex:2653; appliedIndex:2652; }","duration":"111.876604ms","start":"2026-08-05T11:03:37.97745Z","end":"2026-08-05T11:03:38.089327Z","steps":["trace[669827352] 'read index received' (duration: 111.704161ms)","trace[669827352] 'applied index is now lower than readState.Index' (duration: 171.563µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:03:38.089563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.091597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-05T11:03:38.089531Z","caller":"traceutil/trace.go:171","msg":"trace[225815113] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"118.697769ms","start":"2026-08-05T11:03:37.970752Z","end":"2026-08-05T11:03:38.08945Z","steps":["trace[225815113] 'process raft request' (duration: 118.445065ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:03:38.089608Z","caller":"traceutil/trace.go:171","msg":"trace[227391760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2561; }","duration":"112.192448ms","start":"2026-08-05T11:03:37.977404Z","end":"2026-08-05T11:03:38.089596Z","steps":["trace[227391760] 'agreement among raft nodes before linearized reading' (duration: 112.033276ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:04:11.736315Z","caller":"traceutil/trace.go:171","msg":"trace[239325870] linearizableReadLoop","detail":"{readStateIndex:2846; appliedIndex:2845; }","duration":"135.73089ms","start":"2026-08-05T11:04:11.600554Z","end":"2026-08-05T11:04:11.736285Z","steps":["trace[239325870] 'read index received' (duration: 135.459606ms)","trace[239325870] 'applied index is now lower than readState.Index' (duration: 269.544µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:04:11.736354Z","caller":"traceutil/trace.go:171","msg":"trace[63514661] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"223.463567ms","start":"2026-08-05T11:04:11.51286Z","end":"2026-08-05T11:04:11.736323Z","steps":["trace[63514661] 'process raft request' (duration: 223.226573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:04:11.736547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.978964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c8e3e68ce5a13c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-05T11:04:11.736589Z","caller":"traceutil/trace.go:171","msg":"trace[1483613170] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c8e3e68ce5a13c; range_end:; response_count:1; response_revision:2746; }","duration":"136.074236ms","start":"2026-08-05T11:04:11.600503Z","end":"2026-08-05T11:04:11.736577Z","steps":["trace[1483613170] 'agreement among raft nodes before linearized reading' (duration: 135.934434ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:04:11.859308Z","caller":"traceutil/trace.go:171","msg":"trace[589498147] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"120.699739ms","start":"2026-08-05T11:04:11.738585Z","end":"2026-08-05T11:04:11.859285Z","steps":["trace[589498147] 'process raft request' (duration: 101.456411ms)","trace[589498147] 'compare' (duration: 18.971504ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:06:20.845396Z","caller":"traceutil/trace.go:171","msg":"trace[48508081] transaction","detail":"{read_only:false; response_revision:3511; number_of_response:1; }","duration":"185.034163ms","start":"2026-08-05T11:06:20.660339Z","end":"2026-08-05T11:06:20.845373Z","steps":["trace[48508081] 'process raft request' (duration: 175.270861ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:06:20.849573Z","caller":"traceutil/trace.go:171","msg":"trace[1855726545] transaction","detail":"{read_only:false; response_revision:3512; number_of_response:1; }","duration":"175.485736ms","start":"2026-08-05T11:06:20.674075Z","end":"2026-08-05T11:06:20.849561Z","steps":["trace[1855726545] 'process raft request' (duration: 175.036114ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:05.883659Z","caller":"traceutil/trace.go:171","msg":"trace[74648433] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3786; }","duration":"101.212886ms","start":"2026-08-05T11:07:05.782422Z","end":"2026-08-05T11:07:05.883635Z","steps":["trace[74648433] 'process raft request' (duration: 59.760721ms)","trace[74648433] 'compare' (duration: 41.086377ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:07:58.100463Z","caller":"traceutil/trace.go:171","msg":"trace[156091796] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"119.489229ms","start":"2026-08-05T11:07:57.980959Z","end":"2026-08-05T11:07:58.100448Z","steps":["trace[156091796] 'process raft request' (duration: 119.148783ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:58.100609Z","caller":"traceutil/trace.go:171","msg":"trace[970981509] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"119.537991ms","start":"2026-08-05T11:07:57.981058Z","end":"2026-08-05T11:07:58.100596Z","steps":["trace[970981509] 'process raft request' (duration: 119.141183ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:58.4068Z","caller":"traceutil/trace.go:171","msg":"trace[956807754] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"124.152536ms","start":"2026-08-05T11:07:58.282623Z","end":"2026-08-05T11:07:58.406776Z","steps":["trace[956807754] 'process raft request' (duration: 123.512994ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:58.543332Z","caller":"traceutil/trace.go:171","msg":"trace[1193398600] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"117.914609ms","start":"2026-08-05T11:07:58.425402Z","end":"2026-08-05T11:07:58.543317Z","steps":["trace[1193398600] 'process raft request' (duration: 117.842038ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:07:58.554052Z","caller":"traceutil/trace.go:171","msg":"trace[1705579016] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"125.170346ms","start":"2026-08-05T11:07:58.428863Z","end":"2026-08-05T11:07:58.554033Z","steps":["trace[1705579016] 'process raft request' (duration: 124.973673ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.306808Z","caller":"traceutil/trace.go:171","msg":"trace[2146218603] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"163.608281ms","start":"2026-08-05T11:08:00.143174Z","end":"2026-08-05T11:08:00.306782Z","steps":["trace[2146218603] 'process raft request' (duration: 163.504909ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.3072Z","caller":"traceutil/trace.go:171","msg":"trace[1837112273] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4806; }","duration":"160.570284ms","start":"2026-08-05T11:08:00.146608Z","end":"2026-08-05T11:08:00.307178Z","steps":["trace[1837112273] 'read index received' (duration: 2.769822ms)","trace[1837112273] 'applied index is now lower than readState.Index' (duration: 157.799602ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:00.30726Z","caller":"traceutil/trace.go:171","msg":"trace[694696736] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"162.615742ms","start":"2026-08-05T11:08:00.144639Z","end":"2026-08-05T11:08:00.307255Z","steps":["trace[694696736] 'process raft request' (duration: 162.103163ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.307306Z","caller":"traceutil/trace.go:171","msg":"trace[1929747008] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"160.984872ms","start":"2026-08-05T11:08:00.146311Z","end":"2026-08-05T11:08:00.307296Z","steps":["trace[1929747008] 'process raft request' (duration: 160.803649ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.306948Z","caller":"traceutil/trace.go:171","msg":"trace[1392813089] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"164.687121ms","start":"2026-08-05T11:08:00.142238Z","end":"2026-08-05T11:08:00.306925Z","steps":["trace[1392813089] 'process raft request' (duration: 143.626786ms)","trace[1392813089] 'compare' (duration: 20.647108ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:00.307235Z","caller":"traceutil/trace.go:171","msg":"trace[348385082] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"160.980152ms","start":"2026-08-05T11:08:00.146244Z","end":"2026-08-05T11:08:00.307224Z","steps":["trace[348385082] 'process raft request' (duration: 160.793198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:00.307706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.074483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-05T11:08:00.307785Z","caller":"traceutil/trace.go:171","msg":"trace[1359109435] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4642; }","duration":"161.179816ms","start":"2026-08-05T11:08:00.146583Z","end":"2026-08-05T11:08:00.307763Z","steps":["trace[1359109435] 'agreement among raft nodes before linearized reading' (duration: 160.89387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:00.309666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.947096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:08:00.309717Z","caller":"traceutil/trace.go:171","msg":"trace[1623316358] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4644; }","duration":"140.038738ms","start":"2026-08-05T11:08:00.169667Z","end":"2026-08-05T11:08:00.309705Z","steps":["trace[1623316358] 'agreement among raft nodes before linearized reading' (duration: 139.946177ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.309921Z","caller":"traceutil/trace.go:171","msg":"trace[351489513] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"160.554614ms","start":"2026-08-05T11:08:00.149355Z","end":"2026-08-05T11:08:00.30991Z","steps":["trace[351489513] 'process raft request' (duration: 160.095766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:00.310185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.007167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:08:00.310233Z","caller":"traceutil/trace.go:171","msg":"trace[6797824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4644; }","duration":"124.095429ms","start":"2026-08-05T11:08:00.186126Z","end":"2026-08-05T11:08:00.310221Z","steps":["trace[6797824] 'agreement among raft nodes before linearized reading' (duration: 123.930486ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.310482Z","caller":"traceutil/trace.go:171","msg":"trace[259794248] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"153.527641ms","start":"2026-08-05T11:08:00.156943Z","end":"2026-08-05T11:08:00.310471Z","steps":["trace[259794248] 'process raft request' (duration: 152.600954ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:00.307506Z","caller":"traceutil/trace.go:171","msg":"trace[801361079] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"160.221747ms","start":"2026-08-05T11:08:00.147238Z","end":"2026-08-05T11:08:00.30746Z","steps":["trace[801361079] 'process raft request' (duration: 159.907481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:01.27989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.080281ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T11:08:01.318291Z","caller":"traceutil/trace.go:171","msg":"trace[408826497] linearizableReadLoop","detail":"{readStateIndex:4833; appliedIndex:4832; }","duration":"230.845997ms","start":"2026-08-05T11:08:01.087423Z","end":"2026-08-05T11:08:01.318269Z","steps":["trace[408826497] 'read index received' (duration: 230.581472ms)","trace[408826497] 'applied index is now lower than readState.Index' (duration: 263.135µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:01.318758Z","caller":"traceutil/trace.go:171","msg":"trace[400953607] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"293.334157ms","start":"2026-08-05T11:08:01.025397Z","end":"2026-08-05T11:08:01.318731Z","steps":["trace[400953607] 'process raft request' (duration: 292.551633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:01.319968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.532497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-05T11:08:01.320094Z","caller":"traceutil/trace.go:171","msg":"trace[2056277616] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4660; }","duration":"232.702651ms","start":"2026-08-05T11:08:01.087371Z","end":"2026-08-05T11:08:01.320073Z","steps":["trace[2056277616] 'agreement among raft nodes before linearized reading' (duration: 231.161162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:06.888523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.532721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:08:06.888681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.382248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7582"} {"level":"info","ts":"2026-08-05T11:08:06.888726Z","caller":"traceutil/trace.go:171","msg":"trace[2084023732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4793; }","duration":"222.725225ms","start":"2026-08-05T11:08:06.665916Z","end":"2026-08-05T11:08:06.888642Z","steps":["trace[2084023732] 'range keys from in-memory index tree' (duration: 222.417139ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:06.888787Z","caller":"traceutil/trace.go:171","msg":"trace[972180843] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4793; }","duration":"280.524291ms","start":"2026-08-05T11:08:06.608235Z","end":"2026-08-05T11:08:06.888759Z","steps":["trace[972180843] 'range keys from in-memory index tree' (duration: 280.220485ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.147968Z","caller":"traceutil/trace.go:171","msg":"trace[1272196510] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"253.911532ms","start":"2026-08-05T11:08:06.894024Z","end":"2026-08-05T11:08:07.147936Z","steps":["trace[1272196510] 'process raft request' (duration: 253.716508ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.161407Z","caller":"traceutil/trace.go:171","msg":"trace[156303509] linearizableReadLoop","detail":"{readStateIndex:4972; appliedIndex:4970; }","duration":"139.567614ms","start":"2026-08-05T11:08:07.021807Z","end":"2026-08-05T11:08:07.161375Z","steps":["trace[156303509] 'read index received' (duration: 126.061553ms)","trace[156303509] 'applied index is now lower than readState.Index' (duration: 13.505151ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:07.161424Z","caller":"traceutil/trace.go:171","msg":"trace[1963603761] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"186.15049ms","start":"2026-08-05T11:08:06.975246Z","end":"2026-08-05T11:08:07.161397Z","steps":["trace[1963603761] 'process raft request' (duration: 186.026597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.161632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.805418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4960f1ad-241c-489d-8c47-abf3058ffc54\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-05T11:08:07.161672Z","caller":"traceutil/trace.go:171","msg":"trace[289049425] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4960f1ad-241c-489d-8c47-abf3058ffc54; range_end:; response_count:1; response_revision:4795; }","duration":"139.90204ms","start":"2026-08-05T11:08:07.02176Z","end":"2026-08-05T11:08:07.161662Z","steps":["trace[289049425] 'agreement among raft nodes before linearized reading' (duration: 139.733347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.55273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.747132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:08:07.552838Z","caller":"traceutil/trace.go:171","msg":"trace[1745278887] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4975; }","duration":"298.407995ms","start":"2026-08-05T11:08:07.254416Z","end":"2026-08-05T11:08:07.552824Z","steps":["trace[1745278887] 'read index received' (duration: 14.756754ms)","trace[1745278887] 'applied index is now lower than readState.Index' (duration: 283.650071ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:07.552913Z","caller":"traceutil/trace.go:171","msg":"trace[306387950] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"303.138214ms","start":"2026-08-05T11:08:07.249766Z","end":"2026-08-05T11:08:07.552905Z","steps":["trace[306387950] 'process raft request' (duration: 89.109696ms)","trace[306387950] 'compare' (duration: 213.561549ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:08:07.553004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.249746Z","time spent":"303.227995ms","remote":"127.0.0.1:38922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:08:07.553053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.610199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:08:07.553112Z","caller":"traceutil/trace.go:171","msg":"trace[756453037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4803; }","duration":"298.726841ms","start":"2026-08-05T11:08:07.254369Z","end":"2026-08-05T11:08:07.553096Z","steps":["trace[756453037] 'agreement among raft nodes before linearized reading' (duration: 298.603549ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.637121Z","caller":"traceutil/trace.go:171","msg":"trace[141817159] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"323.321568ms","start":"2026-08-05T11:08:07.313769Z","end":"2026-08-05T11:08:07.637091Z","steps":["trace[141817159] 'process raft request' (duration: 323.178485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.637519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.313741Z","time spent":"323.460401ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T11:08:07.792947Z","caller":"traceutil/trace.go:171","msg":"trace[2006237114] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"422.418911ms","start":"2026-08-05T11:08:07.370492Z","end":"2026-08-05T11:08:07.792911Z","steps":["trace[2006237114] 'process raft request' (duration: 406.967253ms)","trace[2006237114] 'compare' (duration: 15.01533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:08:07.793101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.370471Z","time spent":"422.549574ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:08:07.793438Z","caller":"traceutil/trace.go:171","msg":"trace[1867366967] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"233.299746ms","start":"2026-08-05T11:08:07.560102Z","end":"2026-08-05T11:08:07.793402Z","steps":["trace[1867366967] 'process raft request' (duration: 232.784676ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.793466Z","caller":"traceutil/trace.go:171","msg":"trace[154696512] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"421.715007ms","start":"2026-08-05T11:08:07.371719Z","end":"2026-08-05T11:08:07.793434Z","steps":["trace[154696512] 'process raft request' (duration: 420.884522ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.793665Z","caller":"traceutil/trace.go:171","msg":"trace[1960444076] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"231.827839ms","start":"2026-08-05T11:08:07.561814Z","end":"2026-08-05T11:08:07.793641Z","steps":["trace[1960444076] 'process raft request' (duration: 231.123416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.793669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.371713Z","time spent":"421.797679ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:08:07.79445Z","caller":"traceutil/trace.go:171","msg":"trace[552882277] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"125.507512ms","start":"2026-08-05T11:08:07.668929Z","end":"2026-08-05T11:08:07.794436Z","steps":["trace[552882277] 'process raft request' (duration: 125.4536ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.794644Z","caller":"traceutil/trace.go:171","msg":"trace[1595056538] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"232.739466ms","start":"2026-08-05T11:08:07.561892Z","end":"2026-08-05T11:08:07.794632Z","steps":["trace[1595056538] 'process raft request' (duration: 231.086315ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.794748Z","caller":"traceutil/trace.go:171","msg":"trace[2071079227] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"232.249597ms","start":"2026-08-05T11:08:07.562489Z","end":"2026-08-05T11:08:07.794739Z","steps":["trace[2071079227] 'process raft request' (duration: 231.819268ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:07.794892Z","caller":"traceutil/trace.go:171","msg":"trace[848009023] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4980; }","duration":"242.022888ms","start":"2026-08-05T11:08:07.552858Z","end":"2026-08-05T11:08:07.794881Z","steps":["trace[848009023] 'read index received' (duration: 84.155424ms)","trace[848009023] 'applied index is now lower than readState.Index' (duration: 157.865974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:08:07.795549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.950117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-05T11:08:07.795608Z","caller":"traceutil/trace.go:171","msg":"trace[1838215206] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4811; }","duration":"319.028789ms","start":"2026-08-05T11:08:07.476562Z","end":"2026-08-05T11:08:07.79559Z","steps":["trace[1838215206] 'agreement among raft nodes before linearized reading' (duration: 318.817976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.795795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.476549Z","time spent":"319.233153ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T11:08:07.796474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.148589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-05T11:08:07.796555Z","caller":"traceutil/trace.go:171","msg":"trace[2091653297] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4811; }","duration":"525.250171ms","start":"2026-08-05T11:08:07.271268Z","end":"2026-08-05T11:08:07.796518Z","steps":["trace[2091653297] 'agreement among raft nodes before linearized reading' (duration: 523.658962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.796601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.271254Z","time spent":"525.336663ms","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-05T11:08:07.796945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.426066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-05T11:08:07.797768Z","caller":"traceutil/trace.go:171","msg":"trace[1934487357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4811; }","duration":"377.314453ms","start":"2026-08-05T11:08:07.420438Z","end":"2026-08-05T11:08:07.797753Z","steps":["trace[1934487357] 'agreement among raft nodes before linearized reading' (duration: 375.03614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.797905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.420413Z","time spent":"377.480665ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T11:08:07.797939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.412638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:08:07.798078Z","caller":"traceutil/trace.go:171","msg":"trace[1475737196] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4811; }","duration":"353.556431ms","start":"2026-08-05T11:08:07.444507Z","end":"2026-08-05T11:08:07.798064Z","steps":["trace[1475737196] 'agreement among raft nodes before linearized reading' (duration: 350.85802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:07.798324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:08:07.444443Z","time spent":"353.868656ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:08:07.923705Z","caller":"traceutil/trace.go:171","msg":"trace[1025718743] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"122.520397ms","start":"2026-08-05T11:08:07.801158Z","end":"2026-08-05T11:08:07.923679Z","steps":["trace[1025718743] 'process raft request' (duration: 113.867736ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:11.592286Z","caller":"traceutil/trace.go:171","msg":"trace[312953887] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"185.221205ms","start":"2026-08-05T11:08:11.407033Z","end":"2026-08-05T11:08:11.592254Z","steps":["trace[312953887] 'process raft request' (duration: 185.089202ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:21.980353Z","caller":"traceutil/trace.go:171","msg":"trace[218314495] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"140.673118ms","start":"2026-08-05T11:08:21.839655Z","end":"2026-08-05T11:08:21.980328Z","steps":["trace[218314495] 'process raft request' (duration: 140.497835ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:21.999024Z","caller":"traceutil/trace.go:171","msg":"trace[385335096] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5149; }","duration":"131.854726ms","start":"2026-08-05T11:08:21.867139Z","end":"2026-08-05T11:08:21.998994Z","steps":["trace[385335096] 'read index received' (duration: 113.118864ms)","trace[385335096] 'applied index is now lower than readState.Index' (duration: 18.734742ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:21.999103Z","caller":"traceutil/trace.go:171","msg":"trace[1993699881] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"159.276937ms","start":"2026-08-05T11:08:21.839784Z","end":"2026-08-05T11:08:21.999061Z","steps":["trace[1993699881] 'process raft request' (duration: 158.93206ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:21.999127Z","caller":"traceutil/trace.go:171","msg":"trace[48975453] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"154.846845ms","start":"2026-08-05T11:08:21.844256Z","end":"2026-08-05T11:08:21.999103Z","steps":["trace[48975453] 'process raft request' (duration: 154.670782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:08:22.000939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.248393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-05T11:08:22.001085Z","caller":"traceutil/trace.go:171","msg":"trace[245918103] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4968; }","duration":"133.951785ms","start":"2026-08-05T11:08:21.867087Z","end":"2026-08-05T11:08:22.001038Z","steps":["trace[245918103] 'agreement among raft nodes before linearized reading' (duration: 132.07931ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:22.389085Z","caller":"traceutil/trace.go:171","msg":"trace[183759902] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"139.39339ms","start":"2026-08-05T11:08:22.249658Z","end":"2026-08-05T11:08:22.389051Z","steps":["trace[183759902] 'process raft request' (duration: 41.74753ms)","trace[183759902] 'compare' (duration: 97.310634ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:08:22.389642Z","caller":"traceutil/trace.go:171","msg":"trace[1598997973] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"138.656358ms","start":"2026-08-05T11:08:22.250964Z","end":"2026-08-05T11:08:22.38962Z","steps":["trace[1598997973] 'process raft request' (duration: 137.944694ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:22.389673Z","caller":"traceutil/trace.go:171","msg":"trace[422066080] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"138.455014ms","start":"2026-08-05T11:08:22.251188Z","end":"2026-08-05T11:08:22.389643Z","steps":["trace[422066080] 'process raft request' (duration: 138.121197ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:22.39033Z","caller":"traceutil/trace.go:171","msg":"trace[496415086] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"139.088025ms","start":"2026-08-05T11:08:22.251224Z","end":"2026-08-05T11:08:22.390312Z","steps":["trace[496415086] 'process raft request' (duration: 138.229959ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:08:22.390075Z","caller":"traceutil/trace.go:171","msg":"trace[1729607202] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5155; }","duration":"129.389778ms","start":"2026-08-05T11:08:22.26067Z","end":"2026-08-05T11:08:22.39006Z","steps":["trace[1729607202] 'read index received' (duration: 9.644235ms)","trace[1729607202] 'applied index is now lower than readState.Index' (duration: 119.743853ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:08:22.391333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.03751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-05T11:08:22.391401Z","caller":"traceutil/trace.go:171","msg":"trace[1116686387] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4977; }","duration":"130.745522ms","start":"2026-08-05T11:08:22.260634Z","end":"2026-08-05T11:08:22.39138Z","steps":["trace[1116686387] 'agreement among raft nodes before linearized reading' (duration: 129.939228ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:09:42.838305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2910} {"level":"info","ts":"2026-08-05T11:09:42.940908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2910,"took":"101.27043ms","hash":692202729,"current-db-size-bytes":53923840,"current-db-size":"54 MB","current-db-size-in-use-bytes":29282304,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-05T11:09:42.94126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":692202729,"revision":2910,"compact-revision":-1} {"level":"info","ts":"2026-08-05T11:12:05.219816Z","caller":"traceutil/trace.go:171","msg":"trace[1824412670] transaction","detail":"{read_only:false; response_revision:6601; number_of_response:1; }","duration":"102.162639ms","start":"2026-08-05T11:12:05.117626Z","end":"2026-08-05T11:12:05.219789Z","steps":["trace[1824412670] 'process raft request' (duration: 102.020947ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:14:42.845154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5624} {"level":"info","ts":"2026-08-05T11:14:42.92146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5624,"took":"74.738003ms","hash":3105351290,"current-db-size-bytes":53923840,"current-db-size":"54 MB","current-db-size-in-use-bytes":18399232,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-05T11:14:42.921528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3105351290,"revision":5624,"compact-revision":2910}