{"level":"warn","ts":"2026-09-19T00:57:51.059846Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:57:51.059946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.205: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://162.253.55.205:2380","--initial-cluster=instance=https://162.253.55.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.205: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-09-19T00:57:51.060011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:57:51.06002Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.205:2380"]} {"level":"info","ts":"2026-09-19T00:57:51.060036Z","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-09-19T00:57:51.060632Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"]} {"level":"info","ts":"2026-09-19T00:57:51.06072Z","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://162.253.55.205:2380"],"listen-peer-urls":["https://162.253.55.205:2380"],"advertise-client-urls":["https://162.253.55.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.205: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-09-19T00:57:51.065084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.107018ms"} {"level":"info","ts":"2026-09-19T00:57:51.070912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f1f73ead33f822e","cluster-id":"eaba2551c55d0fa5"} {"level":"info","ts":"2026-09-19T00:57:51.070992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T00:57:51.071019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became follower at term 0"} {"level":"info","ts":"2026-09-19T00:57:51.071038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f1f73ead33f822e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T00:57:51.071058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became follower at term 1"} {"level":"info","ts":"2026-09-19T00:57:51.071101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=(6854324610284880430)"} {"level":"warn","ts":"2026-09-19T00:57:51.079504Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T00:57:51.081856Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T00:57:51.083459Z","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-09-19T00:57:51.085577Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f1f73ead33f822e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T00:57:51.08572Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f1f73ead33f822e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T00:57:51.086159Z","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-09-19T00:57:51.086351Z","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-09-19T00:57:51.08636Z","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-09-19T00:57:51.087609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=(6854324610284880430)"} {"level":"info","ts":"2026-09-19T00:57:51.087889Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eaba2551c55d0fa5","local-member-id":"5f1f73ead33f822e","added-peer-id":"5f1f73ead33f822e","added-peer-peer-urls":["https://162.253.55.205:2380"]} {"level":"info","ts":"2026-09-19T00:57:51.090075Z","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-09-19T00:57:51.090219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.205:2380"} {"level":"info","ts":"2026-09-19T00:57:51.090233Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.205:2380"} {"level":"info","ts":"2026-09-19T00:57:51.091059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f1f73ead33f822e","initial-advertise-peer-urls":["https://162.253.55.205:2380"],"listen-peer-urls":["https://162.253.55.205:2380"],"advertise-client-urls":["https://162.253.55.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T00:57:51.091089Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T00:57:51.772128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T00:57:51.772187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T00:57:51.77223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e received MsgPreVoteResp from 5f1f73ead33f822e at term 1"} {"level":"info","ts":"2026-09-19T00:57:51.772249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became candidate at term 2"} {"level":"info","ts":"2026-09-19T00:57:51.77226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e received MsgVoteResp from 5f1f73ead33f822e at term 2"} {"level":"info","ts":"2026-09-19T00:57:51.772275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became leader at term 2"} {"level":"info","ts":"2026-09-19T00:57:51.772288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f1f73ead33f822e elected leader 5f1f73ead33f822e at term 2"} {"level":"info","ts":"2026-09-19T00:57:51.775784Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:51.776874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:57:51.776883Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f1f73ead33f822e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.205:2379]}","request-path":"/0/members/5f1f73ead33f822e/attributes","cluster-id":"eaba2551c55d0fa5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T00:57:51.776925Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:57:51.777447Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T00:57:51.777465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T00:57:51.778397Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eaba2551c55d0fa5","local-member-id":"5f1f73ead33f822e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:51.778528Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:51.778565Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:51.780098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.205:2379"} {"level":"info","ts":"2026-09-19T00:57:51.780832Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-19T00:57:53.727469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.362372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T00:57:53.72765Z","caller":"traceutil/trace.go:171","msg":"trace[1201265634] linearizableReadLoop","detail":"{readStateIndex:107; appliedIndex:106; }","duration":"175.369734ms","start":"2026-09-19T00:57:53.552258Z","end":"2026-09-19T00:57:53.727627Z","steps":["trace[1201265634] 'read index received' (duration: 10.27279ms)","trace[1201265634] 'applied index is now lower than readState.Index' (duration: 165.094884ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:53.727709Z","caller":"traceutil/trace.go:171","msg":"trace[2043240045] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"275.69748ms","start":"2026-09-19T00:57:53.451959Z","end":"2026-09-19T00:57:53.727656Z","steps":["trace[2043240045] 'process raft request' (duration: 110.550534ms)","trace[2043240045] 'compare' (duration: 164.164889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:53.727806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.513087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T00:57:53.727847Z","caller":"traceutil/trace.go:171","msg":"trace[1245750802] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:102; }","duration":"175.617868ms","start":"2026-09-19T00:57:53.55221Z","end":"2026-09-19T00:57:53.727828Z","steps":["trace[1245750802] 'agreement among raft nodes before linearized reading' (duration: 175.491566ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:54.408231Z","caller":"traceutil/trace.go:171","msg":"trace[1978678817] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"130.856424ms","start":"2026-09-19T00:57:54.277339Z","end":"2026-09-19T00:57:54.408195Z","steps":["trace[1978678817] 'read index received' (duration: 118.183556ms)","trace[1978678817] 'applied index is now lower than readState.Index' (duration: 12.671298ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:54.408436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.079797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T00:57:54.408588Z","caller":"traceutil/trace.go:171","msg":"trace[466884251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"131.26614ms","start":"2026-09-19T00:57:54.277278Z","end":"2026-09-19T00:57:54.408544Z","steps":["trace[466884251] 'agreement among raft nodes before linearized reading' (duration: 131.051917ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:54.408797Z","caller":"traceutil/trace.go:171","msg":"trace[647359160] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"179.25382ms","start":"2026-09-19T00:57:54.229444Z","end":"2026-09-19T00:57:54.408698Z","steps":["trace[647359160] 'process raft request' (duration: 166.191335ms)","trace[647359160] 'compare' (duration: 12.348414ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:58.081174Z","caller":"traceutil/trace.go:171","msg":"trace[857119312] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"121.126464ms","start":"2026-09-19T00:57:57.95999Z","end":"2026-09-19T00:57:58.081117Z","steps":["trace[857119312] 'process raft request' (duration: 120.86237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.481019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.809953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:57:58.481136Z","caller":"traceutil/trace.go:171","msg":"trace[292198494] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"395.189804ms","start":"2026-09-19T00:57:58.085931Z","end":"2026-09-19T00:57:58.481121Z","steps":["trace[292198494] 'read index received' (duration: 222.161817ms)","trace[292198494] 'applied index is now lower than readState.Index' (duration: 173.026887ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:58.481198Z","caller":"traceutil/trace.go:171","msg":"trace[1175405931] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"396.359492ms","start":"2026-09-19T00:57:58.084827Z","end":"2026-09-19T00:57:58.481187Z","steps":["trace[1175405931] 'process raft request' (duration: 223.305525ms)","trace[1175405931] 'compare' (duration: 172.678021ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:58.481248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:58.084804Z","time spent":"396.418243ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:57:58.481279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.324416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:57:58.481333Z","caller":"traceutil/trace.go:171","msg":"trace[258407940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:302; }","duration":"395.418687ms","start":"2026-09-19T00:57:58.0859Z","end":"2026-09-19T00:57:58.481319Z","steps":["trace[258407940] 'agreement among raft nodes before linearized reading' (duration: 395.302526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.481357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:58.085887Z","time spent":"395.464668ms","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" "} {"level":"info","ts":"2026-09-19T00:57:58.747914Z","caller":"traceutil/trace.go:171","msg":"trace[251031532] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"418.938ms","start":"2026-09-19T00:57:58.32895Z","end":"2026-09-19T00:57:58.747888Z","steps":["trace[251031532] 'process raft request' (duration: 415.421213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.748056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:58.32893Z","time spent":"419.062572ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:57:58.752119Z","caller":"traceutil/trace.go:171","msg":"trace[2020130126] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"270.94288ms","start":"2026-09-19T00:57:58.481158Z","end":"2026-09-19T00:57:58.752101Z","steps":["trace[2020130126] 'read index received' (duration: 263.226735ms)","trace[2020130126] 'applied index is now lower than readState.Index' (duration: 7.715125ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:58.752215Z","caller":"traceutil/trace.go:171","msg":"trace[1154521374] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"267.006486ms","start":"2026-09-19T00:57:58.485172Z","end":"2026-09-19T00:57:58.752178Z","steps":["trace[1154521374] 'process raft request' (duration: 266.844944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.752286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.917447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"warn","ts":"2026-09-19T00:57:58.752304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.303871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T00:57:58.752342Z","caller":"traceutil/trace.go:171","msg":"trace[1036886673] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:303; }","duration":"424.980878ms","start":"2026-09-19T00:57:58.327347Z","end":"2026-09-19T00:57:58.752328Z","steps":["trace[1036886673] 'agreement among raft nodes before linearized reading' (duration: 424.845995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.75233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.696197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-19T00:57:58.752353Z","caller":"traceutil/trace.go:171","msg":"trace[1237089032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:303; }","duration":"267.393493ms","start":"2026-09-19T00:57:58.484945Z","end":"2026-09-19T00:57:58.752339Z","steps":["trace[1237089032] 'agreement among raft nodes before linearized reading' (duration: 267.302131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.752383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:58.32728Z","time spent":"425.091249ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-09-19T00:57:58.752402Z","caller":"traceutil/trace.go:171","msg":"trace[108223670] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:303; }","duration":"267.798249ms","start":"2026-09-19T00:57:58.484588Z","end":"2026-09-19T00:57:58.752387Z","steps":["trace[108223670] 'agreement among raft nodes before linearized reading' (duration: 267.618437ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:58.752151Z","caller":"traceutil/trace.go:171","msg":"trace[395506929] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"422.865614ms","start":"2026-09-19T00:57:58.32927Z","end":"2026-09-19T00:57:58.752136Z","steps":["trace[395506929] 'process raft request' (duration: 422.687451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:58.752817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:58.329257Z","time spent":"423.519026ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:29.938401Z","caller":"traceutil/trace.go:171","msg":"trace[1944830683] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"238.437183ms","start":"2026-09-19T00:58:29.699937Z","end":"2026-09-19T00:58:29.938374Z","steps":["trace[1944830683] 'process raft request' (duration: 226.44558ms)","trace[1944830683] 'compare' (duration: 11.525156ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:31.998847Z","caller":"traceutil/trace.go:171","msg":"trace[1359540548] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"125.06492ms","start":"2026-09-19T00:58:31.873766Z","end":"2026-09-19T00:58:31.998831Z","steps":["trace[1359540548] 'process raft request' (duration: 124.609472ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.99894Z","caller":"traceutil/trace.go:171","msg":"trace[1749631220] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"125.10919ms","start":"2026-09-19T00:58:31.873827Z","end":"2026-09-19T00:58:31.998936Z","steps":["trace[1749631220] 'process raft request' (duration: 124.621172ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.998996Z","caller":"traceutil/trace.go:171","msg":"trace[374163747] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"121.348503ms","start":"2026-09-19T00:58:31.877644Z","end":"2026-09-19T00:58:31.998993Z","steps":["trace[374163747] 'process raft request' (duration: 120.829124ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.999052Z","caller":"traceutil/trace.go:171","msg":"trace[286228063] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"118.49051ms","start":"2026-09-19T00:58:31.880557Z","end":"2026-09-19T00:58:31.999047Z","steps":["trace[286228063] 'process raft request' (duration: 117.937131ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.999113Z","caller":"traceutil/trace.go:171","msg":"trace[303827741] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"115.774919ms","start":"2026-09-19T00:58:31.883334Z","end":"2026-09-19T00:58:31.999109Z","steps":["trace[303827741] 'process raft request' (duration: 115.179989ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.999177Z","caller":"traceutil/trace.go:171","msg":"trace[296467124] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"115.514234ms","start":"2026-09-19T00:58:31.883645Z","end":"2026-09-19T00:58:31.999159Z","steps":["trace[296467124] 'process raft request' (duration: 114.888584ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.999235Z","caller":"traceutil/trace.go:171","msg":"trace[798810930] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"108.557888ms","start":"2026-09-19T00:58:31.890673Z","end":"2026-09-19T00:58:31.999231Z","steps":["trace[798810930] 'process raft request' (duration: 107.879758ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:31.999288Z","caller":"traceutil/trace.go:171","msg":"trace[1799643093] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"106.03846ms","start":"2026-09-19T00:58:31.893245Z","end":"2026-09-19T00:58:31.999284Z","steps":["trace[1799643093] 'process raft request' (duration: 105.32873ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:32.360859Z","caller":"traceutil/trace.go:171","msg":"trace[1514207055] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"427.102747ms","start":"2026-09-19T00:58:31.933723Z","end":"2026-09-19T00:58:32.360825Z","steps":["trace[1514207055] 'process raft request' (duration: 374.692823ms)","trace[1514207055] 'compare' (duration: 51.905886ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:32.360991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.933695Z","time spent":"427.235539ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:32.361072Z","caller":"traceutil/trace.go:171","msg":"trace[1372919944] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"405.681451ms","start":"2026-09-19T00:58:31.955365Z","end":"2026-09-19T00:58:32.361047Z","steps":["trace[1372919944] 'process raft request' (duration: 405.561339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.361242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.955348Z","time spent":"405.795823ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:32.3614Z","caller":"traceutil/trace.go:171","msg":"trace[784901206] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"423.598904ms","start":"2026-09-19T00:58:31.937746Z","end":"2026-09-19T00:58:32.361345Z","steps":["trace[784901206] 'process raft request' (duration: 422.899513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.36195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.937719Z","time spent":"423.813218ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:32.361453Z","caller":"traceutil/trace.go:171","msg":"trace[1003786817] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"423.633005ms","start":"2026-09-19T00:58:31.937804Z","end":"2026-09-19T00:58:32.361437Z","steps":["trace[1003786817] 'process raft request' (duration: 423.011485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.362386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.937719Z","time spent":"424.591739ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:32.454526Z","caller":"traceutil/trace.go:171","msg":"trace[1971819120] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"446.352268ms","start":"2026-09-19T00:58:32.008125Z","end":"2026-09-19T00:58:32.454477Z","steps":["trace[1971819120] 'process raft request' (duration: 446.111814ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:32.454557Z","caller":"traceutil/trace.go:171","msg":"trace[928903919] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"446.308977ms","start":"2026-09-19T00:58:32.008213Z","end":"2026-09-19T00:58:32.454522Z","steps":["trace[928903919] 'process raft request' (duration: 446.186246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.454677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.008101Z","time spent":"446.50741ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:32.454695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.008209Z","time spent":"446.419179ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:32.454819Z","caller":"traceutil/trace.go:171","msg":"trace[1590903629] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"443.708738ms","start":"2026-09-19T00:58:32.011101Z","end":"2026-09-19T00:58:32.45481Z","steps":["trace[1590903629] 'process raft request' (duration: 443.408723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.454866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.011091Z","time spent":"443.756598ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:32.45494Z","caller":"traceutil/trace.go:171","msg":"trace[1299693593] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"444.039242ms","start":"2026-09-19T00:58:32.010893Z","end":"2026-09-19T00:58:32.454932Z","steps":["trace[1299693593] 'process raft request' (duration: 443.558835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.455007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.010883Z","time spent":"444.083653ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:32.455091Z","caller":"traceutil/trace.go:171","msg":"trace[1857274180] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"440.257356ms","start":"2026-09-19T00:58:32.014826Z","end":"2026-09-19T00:58:32.455083Z","steps":["trace[1857274180] 'process raft request' (duration: 439.881149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.455141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.0148Z","time spent":"440.313606ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:32.455249Z","caller":"traceutil/trace.go:171","msg":"trace[1734913492] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"435.544213ms","start":"2026-09-19T00:58:32.019696Z","end":"2026-09-19T00:58:32.45524Z","steps":["trace[1734913492] 'process raft request' (duration: 435.074835ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:32.455196Z","caller":"traceutil/trace.go:171","msg":"trace[119311173] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"442.401557ms","start":"2026-09-19T00:58:32.012737Z","end":"2026-09-19T00:58:32.455139Z","steps":["trace[119311173] 'process raft request' (duration: 441.885549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.455284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.019679Z","time spent":"435.587254ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:32.455488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.012729Z","time spent":"442.660841ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:32.455674Z","caller":"traceutil/trace.go:171","msg":"trace[1035863059] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"430.068991ms","start":"2026-09-19T00:58:32.02558Z","end":"2026-09-19T00:58:32.455649Z","steps":["trace[1035863059] 'process raft request' (duration: 429.354739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.45576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.025566Z","time spent":"430.152132ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:32.456235Z","caller":"traceutil/trace.go:171","msg":"trace[1060534740] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"387.21299ms","start":"2026-09-19T00:58:32.068999Z","end":"2026-09-19T00:58:32.456212Z","steps":["trace[1060534740] 'process raft request' (duration: 387.075218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.456337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.068968Z","time spent":"387.323902ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:32.519799Z","caller":"traceutil/trace.go:171","msg":"trace[297532573] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"148.140706ms","start":"2026-09-19T00:58:32.371634Z","end":"2026-09-19T00:58:32.519775Z","steps":["trace[297532573] 'process raft request' (duration: 145.606228ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:32.520292Z","caller":"traceutil/trace.go:171","msg":"trace[270113883] transaction","detail":"{read_only:false; number_of_response:1; response_revision:553; }","duration":"147.8332ms","start":"2026-09-19T00:58:32.372439Z","end":"2026-09-19T00:58:32.520272Z","steps":["trace[270113883] 'process raft request' (duration: 147.289662ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:32.520257Z","caller":"traceutil/trace.go:171","msg":"trace[1140873897] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"136.361147ms","start":"2026-09-19T00:58:32.383878Z","end":"2026-09-19T00:58:32.520239Z","steps":["trace[1140873897] 'process raft request' (duration: 136.041242ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:39.212882Z","caller":"traceutil/trace.go:171","msg":"trace[1118203302] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"109.912498ms","start":"2026-09-19T00:58:39.102939Z","end":"2026-09-19T00:58:39.212851Z","steps":["trace[1118203302] 'read index received' (duration: 108.815021ms)","trace[1118203302] 'applied index is now lower than readState.Index' (duration: 1.096137ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:39.212999Z","caller":"traceutil/trace.go:171","msg":"trace[1537851040] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"217.164837ms","start":"2026-09-19T00:58:38.995806Z","end":"2026-09-19T00:58:39.212971Z","steps":["trace[1537851040] 'process raft request' (duration: 216.313335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:39.213186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.227892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T00:58:39.21325Z","caller":"traceutil/trace.go:171","msg":"trace[1628271558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"110.297213ms","start":"2026-09-19T00:58:39.102924Z","end":"2026-09-19T00:58:39.213222Z","steps":["trace[1628271558] 'agreement among raft nodes before linearized reading' (duration: 110.09017ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:39.68423Z","caller":"traceutil/trace.go:171","msg":"trace[1782328199] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"467.386579ms","start":"2026-09-19T00:58:39.216817Z","end":"2026-09-19T00:58:39.684203Z","steps":["trace[1782328199] 'process raft request' (duration: 461.258947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:39.684364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:39.216796Z","time spent":"467.499761ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:39.700355Z","caller":"traceutil/trace.go:171","msg":"trace[533809511] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"264.490247ms","start":"2026-09-19T00:58:39.435815Z","end":"2026-09-19T00:58:39.700305Z","steps":["trace[533809511] 'read index received' (duration: 242.319694ms)","trace[533809511] 'applied index is now lower than readState.Index' (duration: 22.168803ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:39.700361Z","caller":"traceutil/trace.go:171","msg":"trace[11860310] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"413.271448ms","start":"2026-09-19T00:58:39.287065Z","end":"2026-09-19T00:58:39.700336Z","steps":["trace[11860310] 'process raft request' (duration: 413.116166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:39.700537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:39.287021Z","time spent":"413.4546ms","remote":"127.0.0.1:49756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:39.700576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.74127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-75wdl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T00:58:39.700651Z","caller":"traceutil/trace.go:171","msg":"trace[1676888268] range","detail":"{range_begin:/registry/pods/kube-system/cilium-75wdl; range_end:; response_count:1; response_revision:643; }","duration":"264.851582ms","start":"2026-09-19T00:58:39.435779Z","end":"2026-09-19T00:58:39.700631Z","steps":["trace[1676888268] 'agreement among raft nodes before linearized reading' (duration: 264.64899ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:39.700749Z","caller":"traceutil/trace.go:171","msg":"trace[590345347] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"483.255819ms","start":"2026-09-19T00:58:39.217461Z","end":"2026-09-19T00:58:39.700717Z","steps":["trace[590345347] 'process raft request' (duration: 482.614438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:39.700889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:39.217435Z","time spent":"483.38363ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:39.825794Z","caller":"traceutil/trace.go:171","msg":"trace[711103616] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"106.322195ms","start":"2026-09-19T00:58:39.719449Z","end":"2026-09-19T00:58:39.825771Z","steps":["trace[711103616] 'process raft request' (duration: 106.261494ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:39.826435Z","caller":"traceutil/trace.go:171","msg":"trace[807136601] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"121.784047ms","start":"2026-09-19T00:58:39.704634Z","end":"2026-09-19T00:58:39.826418Z","steps":["trace[807136601] 'process raft request' (duration: 114.198053ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:39.825983Z","caller":"traceutil/trace.go:171","msg":"trace[1054191496] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"120.089621ms","start":"2026-09-19T00:58:39.705865Z","end":"2026-09-19T00:58:39.825955Z","steps":["trace[1054191496] 'read index received' (duration: 112.977855ms)","trace[1054191496] 'applied index is now lower than readState.Index' (duration: 7.109706ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:39.826757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.875383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-09-19T00:58:39.826803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.60411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T00:58:39.826815Z","caller":"traceutil/trace.go:171","msg":"trace[9790984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:645; }","duration":"121.000785ms","start":"2026-09-19T00:58:39.705799Z","end":"2026-09-19T00:58:39.8268Z","steps":["trace[9790984] 'agreement among raft nodes before linearized reading' (duration: 120.885254ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:39.826858Z","caller":"traceutil/trace.go:171","msg":"trace[1705324681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"102.719471ms","start":"2026-09-19T00:58:39.724125Z","end":"2026-09-19T00:58:39.826845Z","steps":["trace[1705324681] 'agreement among raft nodes before linearized reading' (duration: 102.543428ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:56.27285Z","caller":"traceutil/trace.go:171","msg":"trace[1995510154] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"336.248583ms","start":"2026-09-19T00:58:55.936578Z","end":"2026-09-19T00:58:56.272826Z","steps":["trace[1995510154] 'read index received' (duration: 299.400852ms)","trace[1995510154] 'applied index is now lower than readState.Index' (duration: 36.846191ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:56.272939Z","caller":"traceutil/trace.go:171","msg":"trace[987344773] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"342.612506ms","start":"2026-09-19T00:58:55.930297Z","end":"2026-09-19T00:58:56.272909Z","steps":["trace[987344773] 'process raft request' (duration: 305.646003ms)","trace[987344773] 'compare' (duration: 36.76367ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:56.273017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.411341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:58:56.273071Z","caller":"traceutil/trace.go:171","msg":"trace[826704790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"239.532553ms","start":"2026-09-19T00:58:56.033523Z","end":"2026-09-19T00:58:56.273056Z","steps":["trace[826704790] 'agreement among raft nodes before linearized reading' (duration: 239.448072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.273083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.93026Z","time spent":"342.742798ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:58:56.273094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.524896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T00:58:56.27316Z","caller":"traceutil/trace.go:171","msg":"trace[1212535575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"336.632349ms","start":"2026-09-19T00:58:55.936498Z","end":"2026-09-19T00:58:56.27313Z","steps":["trace[1212535575] 'agreement among raft nodes before linearized reading' (duration: 336.477147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.273205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.936464Z","time spent":"336.72877ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T01:00:23.967935Z","caller":"traceutil/trace.go:171","msg":"trace[357544820] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"163.994382ms","start":"2026-09-19T01:00:23.803915Z","end":"2026-09-19T01:00:23.967909Z","steps":["trace[357544820] 'read index received' (duration: 163.83681ms)","trace[357544820] 'applied index is now lower than readState.Index' (duration: 156.022µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:23.968011Z","caller":"traceutil/trace.go:171","msg":"trace[955246994] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"212.967661ms","start":"2026-09-19T01:00:23.755009Z","end":"2026-09-19T01:00:23.967977Z","steps":["trace[955246994] 'process raft request' (duration: 212.757959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:23.968115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.184475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-19T01:00:23.968155Z","caller":"traceutil/trace.go:171","msg":"trace[1582686693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1911; }","duration":"164.238626ms","start":"2026-09-19T01:00:23.803906Z","end":"2026-09-19T01:00:23.968144Z","steps":["trace[1582686693] 'agreement among raft nodes before linearized reading' (duration: 164.099844ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:36.18596Z","caller":"traceutil/trace.go:171","msg":"trace[1268554739] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2101; }","duration":"152.236763ms","start":"2026-09-19T01:00:36.033691Z","end":"2026-09-19T01:00:36.185928Z","steps":["trace[1268554739] 'read index received' (duration: 151.914109ms)","trace[1268554739] 'applied index is now lower than readState.Index' (duration: 320.454µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:36.18603Z","caller":"traceutil/trace.go:171","msg":"trace[1816555336] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"168.315644ms","start":"2026-09-19T01:00:36.017687Z","end":"2026-09-19T01:00:36.186002Z","steps":["trace[1816555336] 'process raft request' (duration: 168.02504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:36.186329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.579757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-19T01:00:36.186385Z","caller":"traceutil/trace.go:171","msg":"trace[462284135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2023; }","duration":"152.76634ms","start":"2026-09-19T01:00:36.033606Z","end":"2026-09-19T01:00:36.186372Z","steps":["trace[462284135] 'agreement among raft nodes before linearized reading' (duration: 152.488346ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:02.834988Z","caller":"traceutil/trace.go:171","msg":"trace[691238895] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"188.332428ms","start":"2026-09-19T01:01:02.646621Z","end":"2026-09-19T01:01:02.834954Z","steps":["trace[691238895] 'process raft request' (duration: 108.878476ms)","trace[691238895] 'compare' (duration: 79.219873ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:01:02.835191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.452239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:01:02.835279Z","caller":"traceutil/trace.go:171","msg":"trace[1544268967] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2174; }","duration":"151.590515ms","start":"2026-09-19T01:01:02.68367Z","end":"2026-09-19T01:01:02.835261Z","steps":["trace[1544268967] 'agreement among raft nodes before linearized reading' (duration: 151.365716ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:02.834876Z","caller":"traceutil/trace.go:171","msg":"trace[1012710493] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"151.126667ms","start":"2026-09-19T01:01:02.683722Z","end":"2026-09-19T01:01:02.834849Z","steps":["trace[1012710493] 'read index received' (duration: 71.702296ms)","trace[1012710493] 'applied index is now lower than readState.Index' (duration: 79.423251ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:03.154465Z","caller":"traceutil/trace.go:171","msg":"trace[385965153] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"141.071974ms","start":"2026-09-19T01:01:03.013364Z","end":"2026-09-19T01:01:03.154436Z","steps":["trace[385965153] 'process raft request' (duration: 123.285053ms)","trace[385965153] 'compare' (duration: 17.621225ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:03.894582Z","caller":"traceutil/trace.go:171","msg":"trace[1530291889] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"111.705888ms","start":"2026-09-19T01:01:03.782831Z","end":"2026-09-19T01:01:03.894537Z","steps":["trace[1530291889] 'process raft request' (duration: 111.488148ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:54.265879Z","caller":"traceutil/trace.go:171","msg":"trace[1330299200] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"101.069615ms","start":"2026-09-19T01:01:54.164775Z","end":"2026-09-19T01:01:54.265845Z","steps":["trace[1330299200] 'process raft request' (duration: 36.525135ms)","trace[1330299200] 'compare' (duration: 64.407176ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:40.657431Z","caller":"traceutil/trace.go:171","msg":"trace[783970940] linearizableReadLoop","detail":"{readStateIndex:2912; appliedIndex:2910; }","duration":"158.53225ms","start":"2026-09-19T01:02:40.498867Z","end":"2026-09-19T01:02:40.6574Z","steps":["trace[783970940] 'read index received' (duration: 53.927263ms)","trace[783970940] 'applied index is now lower than readState.Index' (duration: 104.603797ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:40.657558Z","caller":"traceutil/trace.go:171","msg":"trace[2093935398] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"202.421378ms","start":"2026-09-19T01:02:40.455102Z","end":"2026-09-19T01:02:40.657523Z","steps":["trace[2093935398] 'process raft request' (duration: 202.09054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:40.657692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.800288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-19T01:02:40.657751Z","caller":"traceutil/trace.go:171","msg":"trace[282423903] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2805; }","duration":"158.899471ms","start":"2026-09-19T01:02:40.498831Z","end":"2026-09-19T01:02:40.65773Z","steps":["trace[282423903] 'agreement among raft nodes before linearized reading' (duration: 158.703825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:40.972939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.755713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:02:40.973106Z","caller":"traceutil/trace.go:171","msg":"trace[1606413900] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"182.375162ms","start":"2026-09-19T01:02:40.790706Z","end":"2026-09-19T01:02:40.973081Z","steps":["trace[1606413900] 'process raft request' (duration: 182.31764ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:40.973207Z","caller":"traceutil/trace.go:171","msg":"trace[932310204] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"291.526362ms","start":"2026-09-19T01:02:40.681628Z","end":"2026-09-19T01:02:40.973155Z","steps":["trace[932310204] 'process raft request' (duration: 138.479612ms)","trace[932310204] 'compare' (duration: 152.580858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:40.973615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.640102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:40.973799Z","caller":"traceutil/trace.go:171","msg":"trace[1772103252] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2808; }","duration":"218.871428ms","start":"2026-09-19T01:02:40.754911Z","end":"2026-09-19T01:02:40.973783Z","steps":["trace[1772103252] 'agreement among raft nodes before linearized reading' (duration: 218.645572ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:40.974253Z","caller":"traceutil/trace.go:171","msg":"trace[849910377] linearizableReadLoop","detail":"{readStateIndex:2914; appliedIndex:2913; }","duration":"219.2606ms","start":"2026-09-19T01:02:40.754954Z","end":"2026-09-19T01:02:40.974215Z","steps":["trace[849910377] 'read index received' (duration: 65.169931ms)","trace[849910377] 'applied index is now lower than readState.Index' (duration: 153.213434ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:41.234574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.461525ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:41.234716Z","caller":"traceutil/trace.go:171","msg":"trace[263977941] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2809; }","duration":"115.613689ms","start":"2026-09-19T01:02:41.119081Z","end":"2026-09-19T01:02:41.234694Z","steps":["trace[263977941] 'range keys from in-memory index tree' (duration: 115.430514ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:41.236532Z","caller":"traceutil/trace.go:171","msg":"trace[1155207192] linearizableReadLoop","detail":"{readStateIndex:2917; appliedIndex:2916; }","duration":"200.959823ms","start":"2026-09-19T01:02:41.03555Z","end":"2026-09-19T01:02:41.236509Z","steps":["trace[1155207192] 'read index received' (duration: 200.726397ms)","trace[1155207192] 'applied index is now lower than readState.Index' (duration: 232.196µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:41.2367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.126868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:41.23676Z","caller":"traceutil/trace.go:171","msg":"trace[662834606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2810; }","duration":"201.237511ms","start":"2026-09-19T01:02:41.035509Z","end":"2026-09-19T01:02:41.236746Z","steps":["trace[662834606] 'agreement among raft nodes before linearized reading' (duration: 201.126208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:41.236796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.741743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:02:41.236828Z","caller":"traceutil/trace.go:171","msg":"trace[1177347973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2810; }","duration":"176.807725ms","start":"2026-09-19T01:02:41.06001Z","end":"2026-09-19T01:02:41.236818Z","steps":["trace[1177347973] 'agreement among raft nodes before linearized reading' (duration: 176.654601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:41.236854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.164544ms","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-09-19T01:02:41.236896Z","caller":"traceutil/trace.go:171","msg":"trace[484112219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2810; }","duration":"166.228367ms","start":"2026-09-19T01:02:41.070654Z","end":"2026-09-19T01:02:41.236883Z","steps":["trace[484112219] 'agreement among raft nodes before linearized reading' (duration: 166.084914ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:41.237061Z","caller":"traceutil/trace.go:171","msg":"trace[800003514] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"227.30844ms","start":"2026-09-19T01:02:41.009268Z","end":"2026-09-19T01:02:41.236577Z","steps":["trace[800003514] 'process raft request' (duration: 227.113745ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.312886Z","caller":"traceutil/trace.go:171","msg":"trace[1022227486] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"101.288411ms","start":"2026-09-19T01:07:14.211575Z","end":"2026-09-19T01:07:14.312864Z","steps":["trace[1022227486] 'process raft request' (duration: 97.28622ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.517635Z","caller":"traceutil/trace.go:171","msg":"trace[222173906] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"117.38303ms","start":"2026-09-19T01:07:14.400224Z","end":"2026-09-19T01:07:14.517607Z","steps":["trace[222173906] 'process raft request' (duration: 24.222045ms)","trace[222173906] 'compare' (duration: 93.019473ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:14.526756Z","caller":"traceutil/trace.go:171","msg":"trace[143002201] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"109.688021ms","start":"2026-09-19T01:07:14.417044Z","end":"2026-09-19T01:07:14.526732Z","steps":["trace[143002201] 'process raft request' (duration: 109.588409ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:16.718945Z","caller":"traceutil/trace.go:171","msg":"trace[729688528] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"163.902782ms","start":"2026-09-19T01:07:16.555026Z","end":"2026-09-19T01:07:16.718928Z","steps":["trace[729688528] 'read index received' (duration: 163.810501ms)","trace[729688528] 'applied index is now lower than readState.Index' (duration: 91.671µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:16.719021Z","caller":"traceutil/trace.go:171","msg":"trace[665132594] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"194.429281ms","start":"2026-09-19T01:07:16.524585Z","end":"2026-09-19T01:07:16.719014Z","steps":["trace[665132594] 'process raft request' (duration: 194.154096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:16.719204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.163607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-ktl2j.18d6930f21a56eb0\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-19T01:07:16.719232Z","caller":"traceutil/trace.go:171","msg":"trace[61410136] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-ktl2j.18d6930f21a56eb0; range_end:; response_count:1; response_revision:4777; }","duration":"164.227249ms","start":"2026-09-19T01:07:16.554998Z","end":"2026-09-19T01:07:16.719225Z","steps":["trace[61410136] 'agreement among raft nodes before linearized reading' (duration: 164.091876ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:16.723676Z","caller":"traceutil/trace.go:171","msg":"trace[1237688686] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"125.436632ms","start":"2026-09-19T01:07:16.598217Z","end":"2026-09-19T01:07:16.723654Z","steps":["trace[1237688686] 'process raft request' (duration: 125.374821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:16.723931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.035784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-09-19T01:07:16.723942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.958946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-19T01:07:16.723968Z","caller":"traceutil/trace.go:171","msg":"trace[1759567664] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4779; }","duration":"136.100154ms","start":"2026-09-19T01:07:16.587859Z","end":"2026-09-19T01:07:16.723959Z","steps":["trace[1759567664] 'agreement among raft nodes before linearized reading' (duration: 135.964111ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:16.723983Z","caller":"traceutil/trace.go:171","msg":"trace[1922599844] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4779; }","duration":"134.016746ms","start":"2026-09-19T01:07:16.589953Z","end":"2026-09-19T01:07:16.72397Z","steps":["trace[1922599844] 'agreement among raft nodes before linearized reading' (duration: 133.831592ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:16.72391Z","caller":"traceutil/trace.go:171","msg":"trace[1162887378] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"125.812399ms","start":"2026-09-19T01:07:16.598067Z","end":"2026-09-19T01:07:16.72388Z","steps":["trace[1162887378] 'process raft request' (duration: 125.445452ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:30.275388Z","caller":"traceutil/trace.go:171","msg":"trace[759375714] linearizableReadLoop","detail":"{readStateIndex:5273; appliedIndex:5272; }","duration":"244.649142ms","start":"2026-09-19T01:07:30.03071Z","end":"2026-09-19T01:07:30.275359Z","steps":["trace[759375714] 'read index received' (duration: 203.004699ms)","trace[759375714] 'applied index is now lower than readState.Index' (duration: 41.642863ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:30.2757Z","caller":"traceutil/trace.go:171","msg":"trace[1947387052] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"269.083476ms","start":"2026-09-19T01:07:30.006442Z","end":"2026-09-19T01:07:30.275526Z","steps":["trace[1947387052] 'process raft request' (duration: 227.140139ms)","trace[1947387052] 'compare' (duration: 41.332397ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:30.275714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.997587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:07:30.275762Z","caller":"traceutil/trace.go:171","msg":"trace[1212903044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5077; }","duration":"245.104079ms","start":"2026-09-19T01:07:30.030643Z","end":"2026-09-19T01:07:30.275748Z","steps":["trace[1212903044] 'agreement among raft nodes before linearized reading' (duration: 245.023468ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:30.526232Z","caller":"traceutil/trace.go:171","msg":"trace[1977879620] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"379.662249ms","start":"2026-09-19T01:07:30.146553Z","end":"2026-09-19T01:07:30.526215Z","steps":["trace[1977879620] 'process raft request' (duration: 353.062344ms)","trace[1977879620] 'compare' (duration: 26.483323ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:30.526373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:07:30.146531Z","time spent":"379.77446ms","remote":"127.0.0.1:49696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10683,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:07:30.526361Z","caller":"traceutil/trace.go:171","msg":"trace[287865379] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"309.209682ms","start":"2026-09-19T01:07:30.217127Z","end":"2026-09-19T01:07:30.526336Z","steps":["trace[287865379] 'process raft request' (duration: 309.057139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:30.526471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:07:30.217111Z","time spent":"309.301024ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:07:30.559396Z","caller":"traceutil/trace.go:171","msg":"trace[236372161] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"284.243987ms","start":"2026-09-19T01:07:30.275131Z","end":"2026-09-19T01:07:30.559375Z","steps":["trace[236372161] 'process raft request' (duration: 284.091625ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:30.55967Z","caller":"traceutil/trace.go:171","msg":"trace[1932599577] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"283.339071ms","start":"2026-09-19T01:07:30.27631Z","end":"2026-09-19T01:07:30.559649Z","steps":["trace[1932599577] 'process raft request' (duration: 283.030556ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:30.559693Z","caller":"traceutil/trace.go:171","msg":"trace[271638573] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"278.101027ms","start":"2026-09-19T01:07:30.281582Z","end":"2026-09-19T01:07:30.559683Z","steps":["trace[271638573] 'process raft request' (duration: 277.966855ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:30.559798Z","caller":"traceutil/trace.go:171","msg":"trace[922960374] linearizableReadLoop","detail":"{readStateIndex:5276; appliedIndex:5273; }","duration":"284.321589ms","start":"2026-09-19T01:07:30.275468Z","end":"2026-09-19T01:07:30.559789Z","steps":["trace[922960374] 'read index received' (duration: 224.086405ms)","trace[922960374] 'applied index is now lower than readState.Index' (duration: 60.234544ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:30.56003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.216123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:07:30.560063Z","caller":"traceutil/trace.go:171","msg":"trace[629399166] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5083; }","duration":"401.292495ms","start":"2026-09-19T01:07:30.158762Z","end":"2026-09-19T01:07:30.560055Z","steps":["trace[629399166] 'agreement among raft nodes before linearized reading' (duration: 401.178912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:30.560081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:07:30.158751Z","time spent":"401.325085ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-09-19T01:07:30.560203Z","caller":"traceutil/trace.go:171","msg":"trace[861334692] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"278.092678ms","start":"2026-09-19T01:07:30.282105Z","end":"2026-09-19T01:07:30.560198Z","steps":["trace[861334692] 'process raft request' (duration: 277.531558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:30.560298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.080881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f087d871-45fe-4575-88f9-685a3e1770e4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-19T01:07:30.560311Z","caller":"traceutil/trace.go:171","msg":"trace[1528565441] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f087d871-45fe-4575-88f9-685a3e1770e4; range_end:; response_count:1; response_revision:5083; }","duration":"364.126122ms","start":"2026-09-19T01:07:30.19618Z","end":"2026-09-19T01:07:30.560306Z","steps":["trace[1528565441] 'agreement among raft nodes before linearized reading' (duration: 364.061041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:30.560322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:07:30.196167Z","time spent":"364.152372ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f087d871-45fe-4575-88f9-685a3e1770e4\" "} {"level":"warn","ts":"2026-09-19T01:07:30.560354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.82965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:07:30.560419Z","caller":"traceutil/trace.go:171","msg":"trace[533144200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5083; }","duration":"283.931082ms","start":"2026-09-19T01:07:30.276473Z","end":"2026-09-19T01:07:30.560404Z","steps":["trace[533144200] 'agreement among raft nodes before linearized reading' (duration: 283.8071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:30.560504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.256699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:07:30.560529Z","caller":"traceutil/trace.go:171","msg":"trace[390073527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5083; }","duration":"219.321491ms","start":"2026-09-19T01:07:30.341203Z","end":"2026-09-19T01:07:30.560524Z","steps":["trace[390073527] 'agreement among raft nodes before linearized reading' (duration: 219.28113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:30.560659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.476356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-19T01:07:30.560678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.039244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f087d871-45fe-4575-88f9-685a3e1770e4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-19T01:07:30.560693Z","caller":"traceutil/trace.go:171","msg":"trace[1997649372] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f087d871-45fe-4575-88f9-685a3e1770e4; range_end:; response_count:1; response_revision:5083; }","duration":"279.086106ms","start":"2026-09-19T01:07:30.281603Z","end":"2026-09-19T01:07:30.560689Z","steps":["trace[1997649372] 'agreement among raft nodes before linearized reading' (duration: 279.040935ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:30.560694Z","caller":"traceutil/trace.go:171","msg":"trace[1886324137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5083; }","duration":"190.567178ms","start":"2026-09-19T01:07:30.370116Z","end":"2026-09-19T01:07:30.560683Z","steps":["trace[1886324137] 'agreement among raft nodes before linearized reading' (duration: 190.457866ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:31.018816Z","caller":"traceutil/trace.go:171","msg":"trace[1274525393] linearizableReadLoop","detail":"{readStateIndex:5282; appliedIndex:5281; }","duration":"201.996981ms","start":"2026-09-19T01:07:30.816796Z","end":"2026-09-19T01:07:31.018793Z","steps":["trace[1274525393] 'read index received' (duration: 129.714003ms)","trace[1274525393] 'applied index is now lower than readState.Index' (duration: 72.281878ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:31.019041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.236725ms","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-09-19T01:07:31.019067Z","caller":"traceutil/trace.go:171","msg":"trace[389601310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5085; }","duration":"202.322467ms","start":"2026-09-19T01:07:30.816737Z","end":"2026-09-19T01:07:31.01906Z","steps":["trace[389601310] 'agreement among raft nodes before linearized reading' (duration: 202.167604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:31.019095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.583045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-19T01:07:31.019156Z","caller":"traceutil/trace.go:171","msg":"trace[153207635] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5085; }","duration":"177.671956ms","start":"2026-09-19T01:07:30.841467Z","end":"2026-09-19T01:07:31.019139Z","steps":["trace[153207635] 'agreement among raft nodes before linearized reading' (duration: 177.526144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:31.01919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.01368ms","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-09-19T01:07:31.019234Z","caller":"traceutil/trace.go:171","msg":"trace[111593992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5085; }","duration":"198.078671ms","start":"2026-09-19T01:07:30.821141Z","end":"2026-09-19T01:07:31.01922Z","steps":["trace[111593992] 'agreement among raft nodes before linearized reading' (duration: 197.793816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:31.019093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.423371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-19T01:07:31.01933Z","caller":"traceutil/trace.go:171","msg":"trace[209008164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5085; }","duration":"159.693117ms","start":"2026-09-19T01:07:30.859627Z","end":"2026-09-19T01:07:31.01932Z","steps":["trace[209008164] 'agreement among raft nodes before linearized reading' (duration: 159.31782ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:33.291587Z","caller":"traceutil/trace.go:171","msg":"trace[241874302] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"203.273629ms","start":"2026-09-19T01:07:33.088293Z","end":"2026-09-19T01:07:33.291566Z","steps":["trace[241874302] 'process raft request' (duration: 203.104966ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:33.315378Z","caller":"traceutil/trace.go:171","msg":"trace[1186162015] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5312; }","duration":"109.456539ms","start":"2026-09-19T01:07:33.205904Z","end":"2026-09-19T01:07:33.315361Z","steps":["trace[1186162015] 'read index received' (duration: 85.502502ms)","trace[1186162015] 'applied index is now lower than readState.Index' (duration: 23.953107ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:33.315438Z","caller":"traceutil/trace.go:171","msg":"trace[1840732396] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"226.994951ms","start":"2026-09-19T01:07:33.088416Z","end":"2026-09-19T01:07:33.315411Z","steps":["trace[1840732396] 'process raft request' (duration: 226.820718ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:33.315467Z","caller":"traceutil/trace.go:171","msg":"trace[1283283028] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"225.894352ms","start":"2026-09-19T01:07:33.089548Z","end":"2026-09-19T01:07:33.315442Z","steps":["trace[1283283028] 'process raft request' (duration: 225.784069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:33.315532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.621062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T01:07:33.315552Z","caller":"traceutil/trace.go:171","msg":"trace[340779569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5118; }","duration":"109.654903ms","start":"2026-09-19T01:07:33.20589Z","end":"2026-09-19T01:07:33.315545Z","steps":["trace[340779569] 'agreement among raft nodes before linearized reading' (duration: 109.558391ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:43.837722Z","caller":"traceutil/trace.go:171","msg":"trace[51204867] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"118.373929ms","start":"2026-09-19T01:07:43.719316Z","end":"2026-09-19T01:07:43.83769Z","steps":["trace[51204867] 'process raft request' (duration: 118.250247ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.321703Z","caller":"traceutil/trace.go:171","msg":"trace[1130490211] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"112.138666ms","start":"2026-09-19T01:07:44.209547Z","end":"2026-09-19T01:07:44.321686Z","steps":["trace[1130490211] 'process raft request' (duration: 111.991814ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.322406Z","caller":"traceutil/trace.go:171","msg":"trace[919001037] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"111.848591ms","start":"2026-09-19T01:07:44.21054Z","end":"2026-09-19T01:07:44.322389Z","steps":["trace[919001037] 'process raft request' (duration: 111.104047ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.323482Z","caller":"traceutil/trace.go:171","msg":"trace[1051052789] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"111.541816ms","start":"2026-09-19T01:07:44.21191Z","end":"2026-09-19T01:07:44.323452Z","steps":["trace[1051052789] 'process raft request' (duration: 110.140561ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.692681Z","caller":"traceutil/trace.go:171","msg":"trace[1864897769] linearizableReadLoop","detail":"{readStateIndex:5452; appliedIndex:5451; }","duration":"172.917123ms","start":"2026-09-19T01:07:44.519736Z","end":"2026-09-19T01:07:44.692653Z","steps":["trace[1864897769] 'read index received' (duration: 165.810007ms)","trace[1864897769] 'applied index is now lower than readState.Index' (duration: 7.105856ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:44.692951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.206339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:07:44.692997Z","caller":"traceutil/trace.go:171","msg":"trace[164563418] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5250; }","duration":"173.299091ms","start":"2026-09-19T01:07:44.519691Z","end":"2026-09-19T01:07:44.69299Z","steps":["trace[164563418] 'agreement among raft nodes before linearized reading' (duration: 173.212769ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.692744Z","caller":"traceutil/trace.go:171","msg":"trace[1539397915] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"231.151215ms","start":"2026-09-19T01:07:44.461558Z","end":"2026-09-19T01:07:44.69271Z","steps":["trace[1539397915] 'process raft request' (duration: 223.903847ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.694035Z","caller":"traceutil/trace.go:171","msg":"trace[2143490502] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"115.1543ms","start":"2026-09-19T01:07:44.57886Z","end":"2026-09-19T01:07:44.694015Z","steps":["trace[2143490502] 'process raft request' (duration: 114.858715ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:51.809342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2868} {"level":"info","ts":"2026-09-19T01:07:51.883783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2868,"took":"73.032939ms","hash":920360011,"current-db-size-bytes":51843072,"current-db-size":"52 MB","current-db-size-in-use-bytes":27443200,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-19T01:07:51.883857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":920360011,"revision":2868,"compact-revision":-1} {"level":"info","ts":"2026-09-19T01:08:22.897352Z","caller":"traceutil/trace.go:171","msg":"trace[111856586] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"159.834061ms","start":"2026-09-19T01:08:22.737482Z","end":"2026-09-19T01:08:22.897317Z","steps":["trace[111856586] 'process raft request' (duration: 159.348022ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:23.087963Z","caller":"traceutil/trace.go:171","msg":"trace[377462497] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"207.421202ms","start":"2026-09-19T01:08:22.880517Z","end":"2026-09-19T01:08:23.087938Z","steps":["trace[377462497] 'process raft request' (duration: 207.353501ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:23.08797Z","caller":"traceutil/trace.go:171","msg":"trace[1042653742] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"213.803144ms","start":"2026-09-19T01:08:22.874137Z","end":"2026-09-19T01:08:23.08794Z","steps":["trace[1042653742] 'process raft request' (duration: 213.57197ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:23.088593Z","caller":"traceutil/trace.go:171","msg":"trace[56346430] linearizableReadLoop","detail":"{readStateIndex:5826; appliedIndex:5823; }","duration":"163.089547ms","start":"2026-09-19T01:08:22.92549Z","end":"2026-09-19T01:08:23.088579Z","steps":["trace[56346430] 'read index received' (duration: 162.212963ms)","trace[56346430] 'applied index is now lower than readState.Index' (duration: 875.904µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:23.088681Z","caller":"traceutil/trace.go:171","msg":"trace[1571874538] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"203.752177ms","start":"2026-09-19T01:08:22.884919Z","end":"2026-09-19T01:08:23.088671Z","steps":["trace[1571874538] 'process raft request' (duration: 202.992114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:23.088959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.453784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T01:08:23.088995Z","caller":"traceutil/trace.go:171","msg":"trace[1559482979] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5614; }","duration":"163.542386ms","start":"2026-09-19T01:08:22.925439Z","end":"2026-09-19T01:08:23.088981Z","steps":["trace[1559482979] 'agreement among raft nodes before linearized reading' (duration: 163.304652ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:12:51.816218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5325} {"level":"info","ts":"2026-09-19T01:12:51.949157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5325,"took":"131.444495ms","hash":3591946317,"current-db-size-bytes":51843072,"current-db-size":"52 MB","current-db-size-in-use-bytes":18731008,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T01:12:51.949228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3591946317,"revision":5325,"compact-revision":2868}