{"level":"warn","ts":"2026-04-01T02:33:05.963174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T02:33:05.963286Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-04-01T02:33:05.96337Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T02:33:05.96339Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-01T02:33:05.963412Z","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-04-01T02:33:05.964165Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-01T02:33:05.964259Z","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":16,"max-cpu-available":16,"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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-04-01T02:33:05.97057Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.018546ms"} {"level":"info","ts":"2026-04-01T02:33:05.982505Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-01T02:33:05.982604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T02:33:05.982637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-01T02:33:05.98267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T02:33:05.982695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-01T02:33:05.98277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-01T02:33:05.9932Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T02:33:05.999585Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T02:33:06.001341Z","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-04-01T02:33:06.004304Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T02:33:06.004431Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T02:33:06.00452Z","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-04-01T02:33:06.004645Z","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-04-01T02:33:06.00467Z","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-04-01T02:33:06.00534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-01T02:33:06.005461Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-01T02:33:06.009166Z","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-04-01T02:33:06.009299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-01T02:33:06.00932Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-01T02:33:06.009471Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T02:33:06.009514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T02:33:06.383234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T02:33:06.383299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T02:33:06.383353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-01T02:33:06.38338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-01T02:33:06.383399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-01T02:33:06.383421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-01T02:33:06.383439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-01T02:33:06.384642Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T02:33:06.38466Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T02:33:06.384681Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T02:33:06.384743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T02:33:06.385161Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T02:33:06.385228Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T02:33:06.385596Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T02:33:06.385754Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T02:33:06.385806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T02:33:06.388383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T02:33:06.388544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-01T02:33:24.019922Z","caller":"traceutil/trace.go:171","msg":"trace[1285880217] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"172.109666ms","start":"2026-04-01T02:33:23.847789Z","end":"2026-04-01T02:33:24.019899Z","steps":["trace[1285880217] 'process raft request' (duration: 171.985973ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:24.968691Z","caller":"traceutil/trace.go:171","msg":"trace[1578533631] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"1.119981765s","start":"2026-04-01T02:33:23.848685Z","end":"2026-04-01T02:33:24.968667Z","steps":["trace[1578533631] 'process raft request' (duration: 1.119832371s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:24.969305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:23.848674Z","time spent":"1.120092688s","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:33:25.005651Z","caller":"traceutil/trace.go:171","msg":"trace[1054811722] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:464; }","duration":"400.886778ms","start":"2026-04-01T02:33:24.604743Z","end":"2026-04-01T02:33:25.00563Z","steps":["trace[1054811722] 'read index received' (duration: 363.771309ms)","trace[1054811722] 'applied index is now lower than readState.Index' (duration: 37.114139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:25.005785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.780318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:25.005842Z","caller":"traceutil/trace.go:171","msg":"trace[714838484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:450; }","duration":"340.893181ms","start":"2026-04-01T02:33:24.66494Z","end":"2026-04-01T02:33:25.005833Z","steps":["trace[714838484] 'agreement among raft nodes before linearized reading' (duration: 340.795918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:25.005837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.069383ms","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-04-01T02:33:25.005928Z","caller":"traceutil/trace.go:171","msg":"trace[1852268715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:450; }","duration":"401.215288ms","start":"2026-04-01T02:33:24.604692Z","end":"2026-04-01T02:33:25.005907Z","steps":["trace[1852268715] 'agreement among raft nodes before linearized reading' (duration: 401.024143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:25.005868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:24.664915Z","time spent":"340.946583ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T02:33:25.005979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:24.60467Z","time spent":"401.30039ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T02:33:25.241658Z","caller":"traceutil/trace.go:171","msg":"trace[1978377355] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"224.876415ms","start":"2026-04-01T02:33:25.016758Z","end":"2026-04-01T02:33:25.241634Z","steps":["trace[1978377355] 'process raft request' (duration: 224.773242ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:35.123609Z","caller":"traceutil/trace.go:171","msg":"trace[1368433096] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"108.346935ms","start":"2026-04-01T02:33:35.015229Z","end":"2026-04-01T02:33:35.123576Z","steps":["trace[1368433096] 'process raft request' (duration: 107.966225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:36.981618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675754674733652,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T02:33:37.094397Z","caller":"traceutil/trace.go:171","msg":"trace[622741951] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"743.700741ms","start":"2026-04-01T02:33:36.350651Z","end":"2026-04-01T02:33:37.094352Z","steps":["trace[622741951] 'process raft request' (duration: 653.596731ms)","trace[622741951] 'compare' (duration: 89.911206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:37.094635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:36.350632Z","time spent":"743.878366ms","remote":"127.0.0.1:42076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:33:37.100819Z","caller":"traceutil/trace.go:171","msg":"trace[635794099] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"619.792048ms","start":"2026-04-01T02:33:36.481Z","end":"2026-04-01T02:33:37.100792Z","steps":["trace[635794099] 'read index received' (duration: 523.319535ms)","trace[635794099] 'applied index is now lower than readState.Index' (duration: 96.471493ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:33:37.100871Z","caller":"traceutil/trace.go:171","msg":"trace[652582154] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"748.063846ms","start":"2026-04-01T02:33:36.352765Z","end":"2026-04-01T02:33:37.100829Z","steps":["trace[652582154] 'process raft request' (duration: 747.799189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:37.100956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.943211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:37.100911Z","caller":"traceutil/trace.go:171","msg":"trace[48541843] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"646.271457ms","start":"2026-04-01T02:33:36.454579Z","end":"2026-04-01T02:33:37.100851Z","steps":["trace[48541843] 'process raft request' (duration: 646.137784ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:37.100982Z","caller":"traceutil/trace.go:171","msg":"trace[1973812881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"620.065154ms","start":"2026-04-01T02:33:36.480909Z","end":"2026-04-01T02:33:37.100975Z","steps":["trace[1973812881] 'agreement among raft nodes before linearized reading' (duration: 619.979052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:37.10104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:36.48088Z","time spent":"620.154106ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T02:33:37.101049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:36.352736Z","time spent":"748.205349ms","remote":"127.0.0.1:42076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:33:37.101138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:36.454539Z","time spent":"646.479932ms","remote":"127.0.0.1:42076","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-04-01T02:33:43.269243Z","caller":"traceutil/trace.go:171","msg":"trace[1170658265] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"167.303536ms","start":"2026-04-01T02:33:43.101856Z","end":"2026-04-01T02:33:43.269159Z","steps":["trace[1170658265] 'read index received' (duration: 166.201679ms)","trace[1170658265] 'applied index is now lower than readState.Index' (duration: 1.100447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:43.269442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.545701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gk2q8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-01T02:33:43.269501Z","caller":"traceutil/trace.go:171","msg":"trace[989646381] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gk2q8; range_end:; response_count:1; response_revision:620; }","duration":"167.693714ms","start":"2026-04-01T02:33:43.101793Z","end":"2026-04-01T02:33:43.269487Z","steps":["trace[989646381] 'agreement among raft nodes before linearized reading' (duration: 167.532601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:43.269516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.50289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T02:33:43.269563Z","caller":"traceutil/trace.go:171","msg":"trace[2081858670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"111.584352ms","start":"2026-04-01T02:33:43.157953Z","end":"2026-04-01T02:33:43.269538Z","steps":["trace[2081858670] 'agreement among raft nodes before linearized reading' (duration: 111.431398ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:43.269293Z","caller":"traceutil/trace.go:171","msg":"trace[2080420575] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"279.85886ms","start":"2026-04-01T02:33:42.989413Z","end":"2026-04-01T02:33:43.269272Z","steps":["trace[2080420575] 'process raft request' (duration: 279.158514ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:43.40988Z","caller":"traceutil/trace.go:171","msg":"trace[1520415801] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"134.431555ms","start":"2026-04-01T02:33:43.27541Z","end":"2026-04-01T02:33:43.409841Z","steps":["trace[1520415801] 'process raft request' (duration: 91.428463ms)","trace[1520415801] 'compare' (duration: 42.870768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:43.775232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.053276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:33:43.775541Z","caller":"traceutil/trace.go:171","msg":"trace[1299922696] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"499.95337ms","start":"2026-04-01T02:33:43.275525Z","end":"2026-04-01T02:33:43.775478Z","steps":["trace[1299922696] 'process raft request' (duration: 285.203597ms)","trace[1299922696] 'compare' (duration: 213.820841ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:33:43.775601Z","caller":"traceutil/trace.go:171","msg":"trace[1107330059] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"489.412289ms","start":"2026-04-01T02:33:43.286161Z","end":"2026-04-01T02:33:43.775574Z","steps":["trace[1107330059] 'process raft request' (duration: 489.189874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:43.775694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:43.286126Z","time spent":"489.519652ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:33:43.775766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:43.275504Z","time spent":"500.141144ms","remote":"127.0.0.1:42308","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-04-01T02:33:43.775817Z","caller":"traceutil/trace.go:171","msg":"trace[1945063143] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"447.7984ms","start":"2026-04-01T02:33:43.32796Z","end":"2026-04-01T02:33:43.775759Z","steps":["trace[1945063143] 'process raft request' (duration: 447.475803ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:43.775999Z","caller":"traceutil/trace.go:171","msg":"trace[1754769113] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"291.599458ms","start":"2026-04-01T02:33:43.48438Z","end":"2026-04-01T02:33:43.77598Z","steps":["trace[1754769113] 'read index received' (duration: 76.20264ms)","trace[1754769113] 'applied index is now lower than readState.Index' (duration: 215.341287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:43.776052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:43.327923Z","time spent":"448.029816ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T02:33:43.776229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.848775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:43.776331Z","caller":"traceutil/trace.go:171","msg":"trace[1312160940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"292.011119ms","start":"2026-04-01T02:33:43.484307Z","end":"2026-04-01T02:33:43.776318Z","steps":["trace[1312160940] 'agreement among raft nodes before linearized reading' (duration: 291.837934ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:44.644416Z","caller":"traceutil/trace.go:171","msg":"trace[816254399] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"524.960271ms","start":"2026-04-01T02:33:44.119383Z","end":"2026-04-01T02:33:44.644344Z","steps":["trace[816254399] 'read index received' (duration: 500.053863ms)","trace[816254399] 'applied index is now lower than readState.Index' (duration: 24.905148ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:33:44.644468Z","caller":"traceutil/trace.go:171","msg":"trace[737207748] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"843.481703ms","start":"2026-04-01T02:33:43.800944Z","end":"2026-04-01T02:33:44.644426Z","steps":["trace[737207748] 'process raft request' (duration: 818.350079ms)","trace[737207748] 'compare' (duration: 24.475715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:44.644701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.301181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T02:33:44.644758Z","caller":"traceutil/trace.go:171","msg":"trace[1823566919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"525.424384ms","start":"2026-04-01T02:33:44.119318Z","end":"2026-04-01T02:33:44.644742Z","steps":["trace[1823566919] 'agreement among raft nodes before linearized reading' (duration: 525.239039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:44.644803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:44.119287Z","time spent":"525.505767ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T02:33:44.645069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:43.800923Z","time spent":"843.773192ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:33:45.13067Z","caller":"traceutil/trace.go:171","msg":"trace[1926662810] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"969.935704ms","start":"2026-04-01T02:33:44.160697Z","end":"2026-04-01T02:33:45.130633Z","steps":["trace[1926662810] 'process raft request' (duration: 969.330047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.130935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:44.160655Z","time spent":"970.11327ms","remote":"127.0.0.1:42114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:33:45.153571Z","caller":"traceutil/trace.go:171","msg":"trace[1324076628] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"503.098711ms","start":"2026-04-01T02:33:44.650452Z","end":"2026-04-01T02:33:45.153551Z","steps":["trace[1324076628] 'process raft request' (duration: 502.956577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.153739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:44.650423Z","time spent":"503.243005ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:33:45.153707Z","caller":"traceutil/trace.go:171","msg":"trace[1257348952] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"509.169359ms","start":"2026-04-01T02:33:44.6445Z","end":"2026-04-01T02:33:45.15367Z","steps":["trace[1257348952] 'read index received' (duration: 486.122156ms)","trace[1257348952] 'applied index is now lower than readState.Index' (duration: 23.041573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:33:45.153985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T02:33:45.15396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.413843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T02:33:45.154016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.077946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:45.154048Z","caller":"traceutil/trace.go:171","msg":"trace[1213097775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"201.173329ms","start":"2026-04-01T02:33:44.952867Z","end":"2026-04-01T02:33:45.15404Z","steps":["trace[1213097775] 'agreement among raft nodes before linearized reading' (duration: 201.070126ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:45.154072Z","caller":"traceutil/trace.go:171","msg":"trace[1816582324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"668.534596ms","start":"2026-04-01T02:33:44.485501Z","end":"2026-04-01T02:33:45.154035Z","steps":["trace[1816582324] 'agreement among raft nodes before linearized reading' (duration: 668.33709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.154133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:44.485422Z","time spent":"668.701922ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T02:33:45.154235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.284263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:45.154327Z","caller":"traceutil/trace.go:171","msg":"trace[1970374148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"175.444367ms","start":"2026-04-01T02:33:44.978858Z","end":"2026-04-01T02:33:45.154303Z","steps":["trace[1970374148] 'agreement among raft nodes before linearized reading' (duration: 175.278492ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:45.154014Z","caller":"traceutil/trace.go:171","msg":"trace[744304629] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"115.742482ms","start":"2026-04-01T02:33:45.038261Z","end":"2026-04-01T02:33:45.154003Z","steps":["trace[744304629] 'agreement among raft nodes before linearized reading' (duration: 115.700291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.154459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.56469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:45.154493Z","caller":"traceutil/trace.go:171","msg":"trace[2054396061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"175.645703ms","start":"2026-04-01T02:33:44.97884Z","end":"2026-04-01T02:33:45.154486Z","steps":["trace[2054396061] 'agreement among raft nodes before linearized reading' (duration: 175.598072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.154548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.33535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:45.154582Z","caller":"traceutil/trace.go:171","msg":"trace[1730595406] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:627; }","duration":"116.395312ms","start":"2026-04-01T02:33:45.038176Z","end":"2026-04-01T02:33:45.154571Z","steps":["trace[1730595406] 'agreement among raft nodes before linearized reading' (duration: 116.34377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.154711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.607752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:45.154758Z","caller":"traceutil/trace.go:171","msg":"trace[1046885763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"175.658933ms","start":"2026-04-01T02:33:44.979092Z","end":"2026-04-01T02:33:45.154751Z","steps":["trace[1046885763] 'agreement among raft nodes before linearized reading' (duration: 175.595521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:45.154683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.54005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:45.154855Z","caller":"traceutil/trace.go:171","msg":"trace[29903061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"201.754846ms","start":"2026-04-01T02:33:44.953094Z","end":"2026-04-01T02:33:45.154848Z","steps":["trace[29903061] 'agreement among raft nodes before linearized reading' (duration: 201.53862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:46.532936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.480035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T02:33:46.532973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.303129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:33:46.53305Z","caller":"traceutil/trace.go:171","msg":"trace[1656290265] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"308.685381ms","start":"2026-04-01T02:33:46.224343Z","end":"2026-04-01T02:33:46.533028Z","steps":["trace[1656290265] 'range keys from in-memory index tree' (duration: 308.384263ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:33:46.533067Z","caller":"traceutil/trace.go:171","msg":"trace[1361063405] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:641; }","duration":"308.532696ms","start":"2026-04-01T02:33:46.224516Z","end":"2026-04-01T02:33:46.533048Z","steps":["trace[1361063405] 'count revisions from in-memory index tree' (duration: 308.226918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:33:46.5331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:46.224315Z","time spent":"308.774644ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T02:33:46.533118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:33:46.224495Z","time spent":"308.610749ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-01T02:33:46.533105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.223274ms","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-04-01T02:33:46.533208Z","caller":"traceutil/trace.go:171","msg":"trace[741425673] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:641; }","duration":"300.541963ms","start":"2026-04-01T02:33:46.232655Z","end":"2026-04-01T02:33:46.533197Z","steps":["trace[741425673] 'range keys from in-memory index tree' (duration: 300.212543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:34:00.945501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.669555ms","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-04-01T02:34:00.945583Z","caller":"traceutil/trace.go:171","msg":"trace[190116202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"238.913022ms","start":"2026-04-01T02:34:00.706654Z","end":"2026-04-01T02:34:00.945567Z","steps":["trace[190116202] 'range keys from in-memory index tree' (duration: 238.431299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:34:00.945817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.10317ms","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-04-01T02:34:00.945911Z","caller":"traceutil/trace.go:171","msg":"trace[198747678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"223.207894ms","start":"2026-04-01T02:34:00.72268Z","end":"2026-04-01T02:34:00.945887Z","steps":["trace[198747678] 'range keys from in-memory index tree' (duration: 222.883623ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:34:01.137924Z","caller":"traceutil/trace.go:171","msg":"trace[888015010] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"187.087018ms","start":"2026-04-01T02:34:00.950806Z","end":"2026-04-01T02:34:01.137893Z","steps":["trace[888015010] 'process raft request' (duration: 186.914043ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:34:01.150814Z","caller":"traceutil/trace.go:171","msg":"trace[39595643] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"199.848791ms","start":"2026-04-01T02:34:00.950939Z","end":"2026-04-01T02:34:01.150787Z","steps":["trace[39595643] 'process raft request' (duration: 199.694516ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:34:43.478781Z","caller":"traceutil/trace.go:171","msg":"trace[1276068124] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"108.859269ms","start":"2026-04-01T02:34:43.369889Z","end":"2026-04-01T02:34:43.478748Z","steps":["trace[1276068124] 'read index received' (duration: 108.631664ms)","trace[1276068124] 'applied index is now lower than readState.Index' (duration: 226.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:34:43.478828Z","caller":"traceutil/trace.go:171","msg":"trace[499576613] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"125.087266ms","start":"2026-04-01T02:34:43.353707Z","end":"2026-04-01T02:34:43.478795Z","steps":["trace[499576613] 'process raft request' (duration: 124.85868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:34:43.479124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.188257ms","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":"warn","ts":"2026-04-01T02:34:43.479199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.834993ms","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-04-01T02:34:43.479242Z","caller":"traceutil/trace.go:171","msg":"trace[339535144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1309; }","duration":"109.366202ms","start":"2026-04-01T02:34:43.369849Z","end":"2026-04-01T02:34:43.479215Z","steps":["trace[339535144] 'agreement among raft nodes before linearized reading' (duration: 109.027574ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:34:43.479301Z","caller":"traceutil/trace.go:171","msg":"trace[1142931925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1309; }","duration":"106.015468ms","start":"2026-04-01T02:34:43.373265Z","end":"2026-04-01T02:34:43.479281Z","steps":["trace[1142931925] 'agreement among raft nodes before linearized reading' (duration: 105.681709ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:35:14.696763Z","caller":"traceutil/trace.go:171","msg":"trace[469605361] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"314.191093ms","start":"2026-04-01T02:35:14.382539Z","end":"2026-04-01T02:35:14.69673Z","steps":["trace[469605361] 'read index received' (duration: 314.054111ms)","trace[469605361] 'applied index is now lower than readState.Index' (duration: 135.562µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:35:14.696882Z","caller":"traceutil/trace.go:171","msg":"trace[2081485094] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"369.224944ms","start":"2026-04-01T02:35:14.327592Z","end":"2026-04-01T02:35:14.696817Z","steps":["trace[2081485094] 'process raft request' (duration: 368.938128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:35:14.69707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.507418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a87c40e-6f55-420a-9f86-475858c3e5c9\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-01T02:35:14.697148Z","caller":"traceutil/trace.go:171","msg":"trace[1314381646] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8a87c40e-6f55-420a-9f86-475858c3e5c9; range_end:; response_count:1; response_revision:1838; }","duration":"314.57722ms","start":"2026-04-01T02:35:14.38253Z","end":"2026-04-01T02:35:14.697107Z","steps":["trace[1314381646] 'agreement among raft nodes before linearized reading' (duration: 314.358126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:35:14.697213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:35:14.382478Z","time spent":"314.725462ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a87c40e-6f55-420a-9f86-475858c3e5c9\" "} {"level":"warn","ts":"2026-04-01T02:35:14.69763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:35:14.327561Z","time spent":"369.54492ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:35:14.807719Z","caller":"traceutil/trace.go:171","msg":"trace[86998506] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"110.846056ms","start":"2026-04-01T02:35:14.696851Z","end":"2026-04-01T02:35:14.807697Z","steps":["trace[86998506] 'read index received' (duration: 55.732564ms)","trace[86998506] 'applied index is now lower than readState.Index' (duration: 55.112212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:35:14.807977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.461559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a87c40e-6f55-420a-9f86-475858c3e5c9\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-01T02:35:14.808082Z","caller":"traceutil/trace.go:171","msg":"trace[1316450684] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8a87c40e-6f55-420a-9f86-475858c3e5c9; range_end:; response_count:1; response_revision:1838; }","duration":"191.630882ms","start":"2026-04-01T02:35:14.616428Z","end":"2026-04-01T02:35:14.808059Z","steps":["trace[1316450684] 'agreement among raft nodes before linearized reading' (duration: 191.363257ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:35:14.808082Z","caller":"traceutil/trace.go:171","msg":"trace[1557109186] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"107.500827ms","start":"2026-04-01T02:35:14.700526Z","end":"2026-04-01T02:35:14.808027Z","steps":["trace[1557109186] 'process raft request' (duration: 107.051929ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:35:15.236092Z","caller":"traceutil/trace.go:171","msg":"trace[1004568184] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"226.341224ms","start":"2026-04-01T02:35:15.009722Z","end":"2026-04-01T02:35:15.236064Z","steps":["trace[1004568184] 'read index received' (duration: 226.194452ms)","trace[1004568184] 'applied index is now lower than readState.Index' (duration: 145.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:35:15.236166Z","caller":"traceutil/trace.go:171","msg":"trace[1728586285] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"380.122217ms","start":"2026-04-01T02:35:14.855985Z","end":"2026-04-01T02:35:15.236107Z","steps":["trace[1728586285] 'process raft request' (duration: 379.911863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:35:15.236379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.846988ms","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":"warn","ts":"2026-04-01T02:35:15.236397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.295288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T02:35:15.236413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:35:14.85597Z","time spent":"380.32433ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:35:15.236439Z","caller":"traceutil/trace.go:171","msg":"trace[1208216638] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"160.96157ms","start":"2026-04-01T02:35:15.075454Z","end":"2026-04-01T02:35:15.236415Z","steps":["trace[1208216638] 'agreement among raft nodes before linearized reading' (duration: 160.800798ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:35:15.236475Z","caller":"traceutil/trace.go:171","msg":"trace[1623017806] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1842; }","duration":"143.37711ms","start":"2026-04-01T02:35:15.093057Z","end":"2026-04-01T02:35:15.236434Z","steps":["trace[1623017806] 'agreement among raft nodes before linearized reading' (duration: 143.245057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:35:15.236499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.748741ms","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-04-01T02:35:15.236626Z","caller":"traceutil/trace.go:171","msg":"trace[1017561766] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1842; }","duration":"226.952394ms","start":"2026-04-01T02:35:15.009635Z","end":"2026-04-01T02:35:15.236587Z","steps":["trace[1017561766] 'agreement among raft nodes before linearized reading' (duration: 226.595568ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:35:53.1279Z","caller":"traceutil/trace.go:171","msg":"trace[1743708317] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"105.55413ms","start":"2026-04-01T02:35:53.022313Z","end":"2026-04-01T02:35:53.127867Z","steps":["trace[1743708317] 'process raft request' (duration: 105.338765ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:35:59.252377Z","caller":"traceutil/trace.go:171","msg":"trace[672274541] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"103.610514ms","start":"2026-04-01T02:35:59.148727Z","end":"2026-04-01T02:35:59.252337Z","steps":["trace[672274541] 'process raft request' (duration: 103.319497ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:36:05.690193Z","caller":"traceutil/trace.go:171","msg":"trace[1900736064] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2244; }","duration":"404.750647ms","start":"2026-04-01T02:36:05.285412Z","end":"2026-04-01T02:36:05.690163Z","steps":["trace[1900736064] 'read index received' (duration: 404.540672ms)","trace[1900736064] 'applied index is now lower than readState.Index' (duration: 208.755µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:36:05.690334Z","caller":"traceutil/trace.go:171","msg":"trace[1430420052] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"414.479714ms","start":"2026-04-01T02:36:05.275822Z","end":"2026-04-01T02:36:05.690302Z","steps":["trace[1430420052] 'process raft request' (duration: 414.164816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:05.690519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.275792Z","time spent":"414.629717ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:36:05.690497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.053095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T02:36:05.690585Z","caller":"traceutil/trace.go:171","msg":"trace[63522218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2164; }","duration":"405.211209ms","start":"2026-04-01T02:36:05.285356Z","end":"2026-04-01T02:36:05.690567Z","steps":["trace[63522218] 'agreement among raft nodes before linearized reading' (duration: 404.956232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:05.690619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.28533Z","time spent":"405.2833ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T02:36:05.690658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.004998ms","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-04-01T02:36:05.690722Z","caller":"traceutil/trace.go:171","msg":"trace[1421498240] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2164; }","duration":"142.138982ms","start":"2026-04-01T02:36:05.548563Z","end":"2026-04-01T02:36:05.690702Z","steps":["trace[1421498240] 'agreement among raft nodes before linearized reading' (duration: 141.932557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:05.691055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.490864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:36:05.691165Z","caller":"traceutil/trace.go:171","msg":"trace[542422213] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2164; }","duration":"182.654758ms","start":"2026-04-01T02:36:05.50848Z","end":"2026-04-01T02:36:05.691135Z","steps":["trace[542422213] 'agreement among raft nodes before linearized reading' (duration: 181.89717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:05.899467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.584231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:36:05.899727Z","caller":"traceutil/trace.go:171","msg":"trace[1428784207] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"203.935356ms","start":"2026-04-01T02:36:05.695762Z","end":"2026-04-01T02:36:05.899697Z","steps":["trace[1428784207] 'process raft request' (duration: 74.959045ms)","trace[1428784207] 'compare' (duration: 128.398977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:36:06.456871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.760288ms","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-04-01T02:36:06.457027Z","caller":"traceutil/trace.go:171","msg":"trace[963688518] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2165; }","duration":"223.935272ms","start":"2026-04-01T02:36:06.23307Z","end":"2026-04-01T02:36:06.457005Z","steps":["trace[963688518] 'range keys from in-memory index tree' (duration: 223.743358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:06.457238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.904387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:36:06.457575Z","caller":"traceutil/trace.go:171","msg":"trace[2064909900] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"299.121754ms","start":"2026-04-01T02:36:06.158431Z","end":"2026-04-01T02:36:06.457552Z","steps":["trace[2064909900] 'process raft request' (duration: 299.063542ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:36:06.457603Z","caller":"traceutil/trace.go:171","msg":"trace[1088442532] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"761.374901ms","start":"2026-04-01T02:36:05.696182Z","end":"2026-04-01T02:36:06.457557Z","steps":["trace[1088442532] 'process raft request' (duration: 321.985792ms)","trace[1088442532] 'compare' (duration: 438.61848ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:36:06.457637Z","caller":"traceutil/trace.go:171","msg":"trace[1989914175] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"552.067674ms","start":"2026-04-01T02:36:05.905535Z","end":"2026-04-01T02:36:06.457602Z","steps":["trace[1989914175] 'process raft request' (duration: 551.833778ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:36:06.457656Z","caller":"traceutil/trace.go:171","msg":"trace[1139047747] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2246; }","duration":"505.238043ms","start":"2026-04-01T02:36:05.952377Z","end":"2026-04-01T02:36:06.457615Z","steps":["trace[1139047747] 'read index received' (duration: 65.805583ms)","trace[1139047747] 'applied index is now lower than readState.Index' (duration: 439.42786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:36:06.457823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.696154Z","time spent":"761.544915ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:36:06.457839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.905508Z","time spent":"552.220717ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:36:06.457946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.565921ms","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-04-01T02:36:06.458051Z","caller":"traceutil/trace.go:171","msg":"trace[1367836518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2168; }","duration":"505.726134ms","start":"2026-04-01T02:36:05.952308Z","end":"2026-04-01T02:36:06.458034Z","steps":["trace[1367836518] 'agreement among raft nodes before linearized reading' (duration: 505.406157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:06.458101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.952286Z","time spent":"505.803217ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T02:36:06.45811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.65726ms","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":"warn","ts":"2026-04-01T02:36:06.458114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.311325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T02:36:06.45815Z","caller":"traceutil/trace.go:171","msg":"trace[587098909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2168; }","duration":"502.718072ms","start":"2026-04-01T02:36:05.95542Z","end":"2026-04-01T02:36:06.458138Z","steps":["trace[587098909] 'agreement among raft nodes before linearized reading' (duration: 502.554028ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:36:06.458174Z","caller":"traceutil/trace.go:171","msg":"trace[892436972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2168; }","duration":"505.422707ms","start":"2026-04-01T02:36:05.952732Z","end":"2026-04-01T02:36:06.458155Z","steps":["trace[892436972] 'agreement among raft nodes before linearized reading' (duration: 505.221262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:06.458182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.955409Z","time spent":"502.766513ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T02:36:06.458236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:05.952703Z","time spent":"505.52065ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T02:36:06.458773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.426987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T02:36:06.458832Z","caller":"traceutil/trace.go:171","msg":"trace[1276060103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2168; }","duration":"321.534719ms","start":"2026-04-01T02:36:06.137283Z","end":"2026-04-01T02:36:06.458817Z","steps":["trace[1276060103] 'agreement among raft nodes before linearized reading' (duration: 320.668258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:06.458877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:36:06.137255Z","time spent":"321.609132ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T02:36:52.862513Z","caller":"traceutil/trace.go:171","msg":"trace[2054310638] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"247.255771ms","start":"2026-04-01T02:36:52.615227Z","end":"2026-04-01T02:36:52.862482Z","steps":["trace[2054310638] 'process raft request' (duration: 247.044166ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:36:52.86678Z","caller":"traceutil/trace.go:171","msg":"trace[556042354] linearizableReadLoop","detail":"{readStateIndex:2691; appliedIndex:2689; }","duration":"200.127413ms","start":"2026-04-01T02:36:52.666636Z","end":"2026-04-01T02:36:52.866764Z","steps":["trace[556042354] 'read index received' (duration: 195.630334ms)","trace[556042354] 'applied index is now lower than readState.Index' (duration: 4.496179ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:36:52.866868Z","caller":"traceutil/trace.go:171","msg":"trace[1645295059] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"245.635102ms","start":"2026-04-01T02:36:52.621212Z","end":"2026-04-01T02:36:52.866847Z","steps":["trace[1645295059] 'process raft request' (duration: 245.456878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:52.866999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.3515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T02:36:52.867026Z","caller":"traceutil/trace.go:171","msg":"trace[116592599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2593; }","duration":"200.430561ms","start":"2026-04-01T02:36:52.666588Z","end":"2026-04-01T02:36:52.867018Z","steps":["trace[116592599] 'agreement among raft nodes before linearized reading' (duration: 200.256826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:52.867047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.185503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T02:36:52.867154Z","caller":"traceutil/trace.go:171","msg":"trace[780184104] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2593; }","duration":"188.370527ms","start":"2026-04-01T02:36:52.678754Z","end":"2026-04-01T02:36:52.867124Z","steps":["trace[780184104] 'agreement among raft nodes before linearized reading' (duration: 188.144722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:36:52.867196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.578739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T02:36:52.867232Z","caller":"traceutil/trace.go:171","msg":"trace[2006593545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2593; }","duration":"100.661632ms","start":"2026-04-01T02:36:52.76656Z","end":"2026-04-01T02:36:52.867222Z","steps":["trace[2006593545] 'agreement among raft nodes before linearized reading' (duration: 100.534478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:37:27.337728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.878996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:37:27.337939Z","caller":"traceutil/trace.go:171","msg":"trace[55928981] linearizableReadLoop","detail":"{readStateIndex:2906; appliedIndex:2905; }","duration":"301.308999ms","start":"2026-04-01T02:37:27.03661Z","end":"2026-04-01T02:37:27.337919Z","steps":["trace[55928981] 'read index received' (duration: 192.173186ms)","trace[55928981] 'applied index is now lower than readState.Index' (duration: 109.134443ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:37:27.338036Z","caller":"traceutil/trace.go:171","msg":"trace[1700156895] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"304.055201ms","start":"2026-04-01T02:37:27.033943Z","end":"2026-04-01T02:37:27.337999Z","steps":["trace[1700156895] 'process raft request' (duration: 194.815506ms)","trace[1700156895] 'compare' (duration: 108.752143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:37:27.338232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:37:27.033927Z","time spent":"304.195833ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:37:27.338278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.653913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-01T02:37:27.338278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.640236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T02:37:27.338338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.23528ms","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-04-01T02:37:27.338391Z","caller":"traceutil/trace.go:171","msg":"trace[142533206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2800; }","duration":"300.783447ms","start":"2026-04-01T02:37:27.037583Z","end":"2026-04-01T02:37:27.338366Z","steps":["trace[142533206] 'agreement among raft nodes before linearized reading' (duration: 300.600523ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:37:27.338408Z","caller":"traceutil/trace.go:171","msg":"trace[861447896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2800; }","duration":"294.346551ms","start":"2026-04-01T02:37:27.044046Z","end":"2026-04-01T02:37:27.338393Z","steps":["trace[861447896] 'agreement among raft nodes before linearized reading' (duration: 294.146047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:37:27.338457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:37:27.037571Z","time spent":"300.872599ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T02:37:27.338428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.107866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T02:37:27.338473Z","caller":"traceutil/trace.go:171","msg":"trace[804801487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2800; }","duration":"301.82355ms","start":"2026-04-01T02:37:27.036563Z","end":"2026-04-01T02:37:27.338386Z","steps":["trace[804801487] 'agreement among raft nodes before linearized reading' (duration: 301.487333ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:37:27.338736Z","caller":"traceutil/trace.go:171","msg":"trace[751736549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2800; }","duration":"294.431944ms","start":"2026-04-01T02:37:27.044291Z","end":"2026-04-01T02:37:27.338723Z","steps":["trace[751736549] 'agreement among raft nodes before linearized reading' (duration: 293.889632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:37:27.33883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:37:27.036542Z","time spent":"302.208039ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T02:37:27.627928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.47618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:37:27.628031Z","caller":"traceutil/trace.go:171","msg":"trace[359735996] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"284.55914ms","start":"2026-04-01T02:37:27.343453Z","end":"2026-04-01T02:37:27.628013Z","steps":["trace[359735996] 'process raft request' (duration: 174.908226ms)","trace[359735996] 'compare' (duration: 109.088062ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:37:27.633464Z","caller":"traceutil/trace.go:171","msg":"trace[1969850006] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"289.923512ms","start":"2026-04-01T02:37:27.343519Z","end":"2026-04-01T02:37:27.633443Z","steps":["trace[1969850006] 'process raft request' (duration: 289.883931ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:37:27.633498Z","caller":"traceutil/trace.go:171","msg":"trace[2047357339] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"289.958422ms","start":"2026-04-01T02:37:27.343505Z","end":"2026-04-01T02:37:27.633459Z","steps":["trace[2047357339] 'process raft request' (duration: 289.808599ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:37:27.633782Z","caller":"traceutil/trace.go:171","msg":"trace[575817674] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"290.186708ms","start":"2026-04-01T02:37:27.343567Z","end":"2026-04-01T02:37:27.633754Z","steps":["trace[575817674] 'process raft request' (duration: 289.85579ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:37:27.634998Z","caller":"traceutil/trace.go:171","msg":"trace[1604311762] linearizableReadLoop","detail":"{readStateIndex:2910; appliedIndex:2906; }","duration":"149.570034ms","start":"2026-04-01T02:37:27.484114Z","end":"2026-04-01T02:37:27.633684Z","steps":["trace[1604311762] 'read index received' (duration: 34.265603ms)","trace[1604311762] 'applied index is now lower than readState.Index' (duration: 115.303061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:37:27.63527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.11605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T02:37:27.635436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.036607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-01T02:37:27.635433Z","caller":"traceutil/trace.go:171","msg":"trace[599566289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2804; }","duration":"151.295513ms","start":"2026-04-01T02:37:27.484075Z","end":"2026-04-01T02:37:27.63537Z","steps":["trace[599566289] 'agreement among raft nodes before linearized reading' (duration: 149.695567ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:37:27.635504Z","caller":"traceutil/trace.go:171","msg":"trace[735494425] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2804; }","duration":"127.132749ms","start":"2026-04-01T02:37:27.508338Z","end":"2026-04-01T02:37:27.635471Z","steps":["trace[735494425] 'agreement among raft nodes before linearized reading' (duration: 126.969295ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:39:52.623569Z","caller":"traceutil/trace.go:171","msg":"trace[2008958379] transaction","detail":"{read_only:false; response_revision:3765; number_of_response:1; }","duration":"240.363504ms","start":"2026-04-01T02:39:52.383167Z","end":"2026-04-01T02:39:52.623531Z","steps":["trace[2008958379] 'process raft request' (duration: 240.128778ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:39:52.735385Z","caller":"traceutil/trace.go:171","msg":"trace[1888638160] transaction","detail":"{read_only:false; response_revision:3766; number_of_response:1; }","duration":"350.808076ms","start":"2026-04-01T02:39:52.384547Z","end":"2026-04-01T02:39:52.735356Z","steps":["trace[1888638160] 'process raft request' (duration: 346.926524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:39:52.735606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:39:52.384525Z","time spent":"350.94066ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:39:52.735637Z","caller":"traceutil/trace.go:171","msg":"trace[2095878057] transaction","detail":"{read_only:false; response_revision:3767; number_of_response:1; }","duration":"350.515219ms","start":"2026-04-01T02:39:52.385093Z","end":"2026-04-01T02:39:52.735608Z","steps":["trace[2095878057] 'process raft request' (duration: 350.164211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:39:52.735794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:39:52.385073Z","time spent":"350.638502ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:39:52.736141Z","caller":"traceutil/trace.go:171","msg":"trace[1128627594] linearizableReadLoop","detail":"{readStateIndex:3909; appliedIndex:3905; }","duration":"252.416008ms","start":"2026-04-01T02:39:52.48369Z","end":"2026-04-01T02:39:52.736106Z","steps":["trace[1128627594] 'read index received' (duration: 139.661051ms)","trace[1128627594] 'applied index is now lower than readState.Index' (duration: 112.753467ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:39:52.736305Z","caller":"traceutil/trace.go:171","msg":"trace[1840626618] transaction","detail":"{read_only:false; response_revision:3769; number_of_response:1; }","duration":"237.909026ms","start":"2026-04-01T02:39:52.498347Z","end":"2026-04-01T02:39:52.736256Z","steps":["trace[1840626618] 'process raft request' (duration: 237.63277ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:39:52.736277Z","caller":"traceutil/trace.go:171","msg":"trace[17175825] transaction","detail":"{read_only:false; response_revision:3768; number_of_response:1; }","duration":"351.023642ms","start":"2026-04-01T02:39:52.385211Z","end":"2026-04-01T02:39:52.736234Z","steps":["trace[17175825] 'process raft request' (duration: 350.325385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:39:52.736336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.623532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:39:52.736386Z","caller":"traceutil/trace.go:171","msg":"trace[1926825606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3769; }","duration":"252.740895ms","start":"2026-04-01T02:39:52.483633Z","end":"2026-04-01T02:39:52.736374Z","steps":["trace[1926825606] 'agreement among raft nodes before linearized reading' (duration: 252.636463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:39:52.736481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:39:52.385203Z","time spent":"351.167475ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:41:17.604617Z","caller":"traceutil/trace.go:171","msg":"trace[580019235] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"136.629985ms","start":"2026-04-01T02:41:17.467852Z","end":"2026-04-01T02:41:17.604482Z","steps":["trace[580019235] 'process raft request' (duration: 136.440521ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:41:17.608253Z","caller":"traceutil/trace.go:171","msg":"trace[713616957] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"132.635362ms","start":"2026-04-01T02:41:17.475582Z","end":"2026-04-01T02:41:17.608218Z","steps":["trace[713616957] 'process raft request' (duration: 132.359486ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:41:17.6084Z","caller":"traceutil/trace.go:171","msg":"trace[1933038295] linearizableReadLoop","detail":"{readStateIndex:5011; appliedIndex:5010; }","duration":"124.86016ms","start":"2026-04-01T02:41:17.483502Z","end":"2026-04-01T02:41:17.608362Z","steps":["trace[1933038295] 'read index received' (duration: 120.962359ms)","trace[1933038295] 'applied index is now lower than readState.Index' (duration: 3.895731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:41:17.608731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.195699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:41:17.608818Z","caller":"traceutil/trace.go:171","msg":"trace[1840697400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4828; }","duration":"125.359683ms","start":"2026-04-01T02:41:17.48344Z","end":"2026-04-01T02:41:17.608799Z","steps":["trace[1840697400] 'agreement among raft nodes before linearized reading' (duration: 125.175998ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:41:20.53522Z","caller":"traceutil/trace.go:171","msg":"trace[503701200] linearizableReadLoop","detail":"{readStateIndex:5136; appliedIndex:5135; }","duration":"247.693294ms","start":"2026-04-01T02:41:20.287499Z","end":"2026-04-01T02:41:20.535193Z","steps":["trace[503701200] 'read index received' (duration: 247.50839ms)","trace[503701200] 'applied index is now lower than readState.Index' (duration: 183.774µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:41:20.535299Z","caller":"traceutil/trace.go:171","msg":"trace[2040276523] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"404.699745ms","start":"2026-04-01T02:41:20.130556Z","end":"2026-04-01T02:41:20.535256Z","steps":["trace[2040276523] 'process raft request' (duration: 404.435128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:20.535515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:20.130524Z","time spent":"404.873799ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:41:20.535975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.39852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719265"} {"level":"info","ts":"2026-04-01T02:41:20.536085Z","caller":"traceutil/trace.go:171","msg":"trace[1133221582] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4949; }","duration":"248.633597ms","start":"2026-04-01T02:41:20.287436Z","end":"2026-04-01T02:41:20.53607Z","steps":["trace[1133221582] 'agreement among raft nodes before linearized reading' (duration: 247.949501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:22.84491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.980986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777450"} {"level":"info","ts":"2026-04-01T02:41:22.845026Z","caller":"traceutil/trace.go:171","msg":"trace[1693942915] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4991; }","duration":"147.175351ms","start":"2026-04-01T02:41:22.697827Z","end":"2026-04-01T02:41:22.845002Z","steps":["trace[1693942915] 'range keys from in-memory index tree' (duration: 146.401474ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:41:41.048969Z","caller":"traceutil/trace.go:171","msg":"trace[615858674] linearizableReadLoop","detail":"{readStateIndex:5550; appliedIndex:5549; }","duration":"311.02482ms","start":"2026-04-01T02:41:40.737905Z","end":"2026-04-01T02:41:41.04893Z","steps":["trace[615858674] 'read index received' (duration: 310.734143ms)","trace[615858674] 'applied index is now lower than readState.Index' (duration: 289.057µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:41:41.049088Z","caller":"traceutil/trace.go:171","msg":"trace[893207894] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"311.191363ms","start":"2026-04-01T02:41:40.73785Z","end":"2026-04-01T02:41:41.049041Z","steps":["trace[893207894] 'process raft request' (duration: 310.833205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.049311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.375598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-01T02:41:41.049365Z","caller":"traceutil/trace.go:171","msg":"trace[790218468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5351; }","duration":"311.493721ms","start":"2026-04-01T02:41:40.737858Z","end":"2026-04-01T02:41:41.049351Z","steps":["trace[790218468] 'agreement among raft nodes before linearized reading' (duration: 311.232305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.049416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:40.737818Z","time spent":"311.395078ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:41:41.049425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:40.737836Z","time spent":"311.563012ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T02:41:41.397073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.629042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:41:41.397366Z","caller":"traceutil/trace.go:171","msg":"trace[1626536140] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"453.783629ms","start":"2026-04-01T02:41:40.943548Z","end":"2026-04-01T02:41:41.397331Z","steps":["trace[1626536140] 'process raft request' (duration: 175.795248ms)","trace[1626536140] 'compare' (duration: 277.50874ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:41:41.397439Z","caller":"traceutil/trace.go:171","msg":"trace[284202240] linearizableReadLoop","detail":"{readStateIndex:5551; appliedIndex:5550; }","duration":"343.206401ms","start":"2026-04-01T02:41:41.0542Z","end":"2026-04-01T02:41:41.397406Z","steps":["trace[284202240] 'read index received' (duration: 65.120928ms)","trace[284202240] 'applied index is now lower than readState.Index' (duration: 278.080283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:41:41.39758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:40.943521Z","time spent":"453.953102ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:41:41.397623Z","caller":"traceutil/trace.go:171","msg":"trace[1140158233] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"341.169463ms","start":"2026-04-01T02:41:41.056386Z","end":"2026-04-01T02:41:41.397556Z","steps":["trace[1140158233] 'process raft request' (duration: 340.988539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.397819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:41.056353Z","time spent":"341.370638ms","remote":"127.0.0.1:42308","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-04-01T02:41:41.397903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.654531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-6wf54\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-04-01T02:41:41.398046Z","caller":"traceutil/trace.go:171","msg":"trace[2117257300] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-6wf54; range_end:; response_count:1; response_revision:5353; }","duration":"343.896397ms","start":"2026-04-01T02:41:41.054115Z","end":"2026-04-01T02:41:41.398011Z","steps":["trace[2117257300] 'agreement among raft nodes before linearized reading' (duration: 343.381975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.398109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:41.054086Z","time spent":"344.01346ms","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5014,"request content":"key:\"/registry/pods/monitoring/goldpinger-6wf54\" "} {"level":"info","ts":"2026-04-01T02:41:41.416222Z","caller":"traceutil/trace.go:171","msg":"trace[14618445] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"295.101429ms","start":"2026-04-01T02:41:41.121086Z","end":"2026-04-01T02:41:41.416187Z","steps":["trace[14618445] 'process raft request' (duration: 294.895164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.416297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.995962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-01T02:41:41.416346Z","caller":"traceutil/trace.go:171","msg":"trace[422712570] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5354; }","duration":"182.098955ms","start":"2026-04-01T02:41:41.234233Z","end":"2026-04-01T02:41:41.416332Z","steps":["trace[422712570] 'agreement among raft nodes before linearized reading' (duration: 181.962181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.416369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.646631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T02:41:41.41658Z","caller":"traceutil/trace.go:171","msg":"trace[1684753070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5354; }","duration":"198.917777ms","start":"2026-04-01T02:41:41.217639Z","end":"2026-04-01T02:41:41.416556Z","steps":["trace[1684753070] 'agreement among raft nodes before linearized reading' (duration: 198.565749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.416786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.799325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:41:41.416903Z","caller":"traceutil/trace.go:171","msg":"trace[1938897313] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5354; }","duration":"304.975209ms","start":"2026-04-01T02:41:41.111896Z","end":"2026-04-01T02:41:41.416871Z","steps":["trace[1938897313] 'agreement among raft nodes before linearized reading' (duration: 304.227442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:41:41.416959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:41:41.111877Z","time spent":"305.070642ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-01T02:43:06.439602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-04-01T02:43:06.531848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"89.647988ms","hash":2089394745,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-01T02:43:06.531927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2089394745,"revision":3014,"compact-revision":-1} {"level":"warn","ts":"2026-04-01T02:47:29.668387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.797829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T02:47:29.668448Z","caller":"traceutil/trace.go:171","msg":"trace[1097655821] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8103; }","duration":"402.87292ms","start":"2026-04-01T02:47:29.265563Z","end":"2026-04-01T02:47:29.668436Z","steps":["trace[1097655821] 'count revisions from in-memory index tree' (duration: 402.713897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:29.668472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:29.265496Z","time spent":"402.969413ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-01T02:47:29.668569Z","caller":"traceutil/trace.go:171","msg":"trace[1851469686] transaction","detail":"{read_only:false; response_revision:8104; number_of_response:1; }","duration":"331.794937ms","start":"2026-04-01T02:47:29.336743Z","end":"2026-04-01T02:47:29.668538Z","steps":["trace[1851469686] 'process raft request' (duration: 232.553211ms)","trace[1851469686] 'compare' (duration: 98.921088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:47:29.668705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:29.336721Z","time spent":"331.903119ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:47:29.812842Z","caller":"traceutil/trace.go:171","msg":"trace[434992511] linearizableReadLoop","detail":"{readStateIndex:8387; appliedIndex:8385; }","duration":"417.292055ms","start":"2026-04-01T02:47:29.395526Z","end":"2026-04-01T02:47:29.812818Z","steps":["trace[434992511] 'read index received' (duration: 173.783081ms)","trace[434992511] 'applied index is now lower than readState.Index' (duration: 243.507954ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:47:29.813081Z","caller":"traceutil/trace.go:171","msg":"trace[1694437009] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"475.328848ms","start":"2026-04-01T02:47:29.337741Z","end":"2026-04-01T02:47:29.81307Z","steps":["trace[1694437009] 'process raft request' (duration: 469.459482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:29.813212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:29.337721Z","time spent":"475.388028ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:47:29.813349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.82339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:47:29.81343Z","caller":"traceutil/trace.go:171","msg":"trace[1618994973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8105; }","duration":"329.940513ms","start":"2026-04-01T02:47:29.483464Z","end":"2026-04-01T02:47:29.813404Z","steps":["trace[1618994973] 'agreement among raft nodes before linearized reading' (duration: 329.79807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:29.813479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:29.483451Z","time spent":"330.020406ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T02:47:29.813482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.458802ms","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-04-01T02:47:29.81352Z","caller":"traceutil/trace.go:171","msg":"trace[1905387502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8105; }","duration":"176.535124ms","start":"2026-04-01T02:47:29.636972Z","end":"2026-04-01T02:47:29.813507Z","steps":["trace[1905387502] 'agreement among raft nodes before linearized reading' (duration: 176.38048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:29.813559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.031442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T02:47:29.813614Z","caller":"traceutil/trace.go:171","msg":"trace[1542660980] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:8105; }","duration":"418.090523ms","start":"2026-04-01T02:47:29.395511Z","end":"2026-04-01T02:47:29.813601Z","steps":["trace[1542660980] 'agreement among raft nodes before linearized reading' (duration: 417.939659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:29.81365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:29.395456Z","time spent":"418.186945ms","remote":"127.0.0.1:42058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-04-01T02:47:29.813705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.828988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T02:47:29.813757Z","caller":"traceutil/trace.go:171","msg":"trace[743884508] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8105; }","duration":"207.92826ms","start":"2026-04-01T02:47:29.605815Z","end":"2026-04-01T02:47:29.813743Z","steps":["trace[743884508] 'agreement among raft nodes before linearized reading' (duration: 207.795837ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:47:41.008717Z","caller":"traceutil/trace.go:171","msg":"trace[770151332] transaction","detail":"{read_only:false; response_revision:8161; number_of_response:1; }","duration":"189.938825ms","start":"2026-04-01T02:47:40.818752Z","end":"2026-04-01T02:47:41.008691Z","steps":["trace[770151332] 'process raft request' (duration: 189.794361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:42.245868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.7145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T02:47:42.245933Z","caller":"traceutil/trace.go:171","msg":"trace[1773632387] linearizableReadLoop","detail":"{readStateIndex:8453; appliedIndex:8452; }","duration":"379.639413ms","start":"2026-04-01T02:47:41.866283Z","end":"2026-04-01T02:47:42.245922Z","steps":["trace[1773632387] 'read index received' (duration: 224.812451ms)","trace[1773632387] 'applied index is now lower than readState.Index' (duration: 154.826122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:47:42.246031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.757116ms","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-04-01T02:47:42.246055Z","caller":"traceutil/trace.go:171","msg":"trace[2077619571] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8168; }","duration":"379.814537ms","start":"2026-04-01T02:47:41.866232Z","end":"2026-04-01T02:47:42.246046Z","steps":["trace[2077619571] 'agreement among raft nodes before linearized reading' (duration: 379.717985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:47:42.246072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:41.866211Z","time spent":"379.856539ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-01T02:47:42.246039Z","caller":"traceutil/trace.go:171","msg":"trace[449660413] transaction","detail":"{read_only:false; response_revision:8168; number_of_response:1; }","duration":"390.995626ms","start":"2026-04-01T02:47:41.855002Z","end":"2026-04-01T02:47:42.245998Z","steps":["trace[449660413] 'process raft request' (duration: 236.099123ms)","trace[449660413] 'compare' (duration: 154.621687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:47:42.246191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:47:41.854977Z","time spent":"391.134099ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T02:47:43.174367Z","caller":"traceutil/trace.go:171","msg":"trace[1306640178] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"159.631223ms","start":"2026-04-01T02:47:43.014706Z","end":"2026-04-01T02:47:43.174337Z","steps":["trace[1306640178] 'process raft request' (duration: 159.445799ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:47:43.497957Z","caller":"traceutil/trace.go:171","msg":"trace[806845217] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"149.419667ms","start":"2026-04-01T02:47:43.348515Z","end":"2026-04-01T02:47:43.497935Z","steps":["trace[806845217] 'process raft request' (duration: 149.257374ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:47:43.498123Z","caller":"traceutil/trace.go:171","msg":"trace[434677640] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"149.205912ms","start":"2026-04-01T02:47:43.348892Z","end":"2026-04-01T02:47:43.498098Z","steps":["trace[434677640] 'process raft request' (duration: 148.999477ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:48:06.45053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6131} {"level":"info","ts":"2026-04-01T02:48:06.552442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6131,"took":"100.444476ms","hash":293453670,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":23719936,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-01T02:48:06.552516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":293453670,"revision":6131,"compact-revision":3014} {"level":"info","ts":"2026-04-01T02:48:07.135089Z","caller":"traceutil/trace.go:171","msg":"trace[582183304] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"113.788799ms","start":"2026-04-01T02:48:07.021276Z","end":"2026-04-01T02:48:07.135064Z","steps":["trace[582183304] 'process raft request' (duration: 113.646576ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:48:10.691797Z","caller":"traceutil/trace.go:171","msg":"trace[752071003] linearizableReadLoop","detail":"{readStateIndex:8673; appliedIndex:8672; }","duration":"293.038409ms","start":"2026-04-01T02:48:10.398733Z","end":"2026-04-01T02:48:10.691771Z","steps":["trace[752071003] 'read index received' (duration: 292.835994ms)","trace[752071003] 'applied index is now lower than readState.Index' (duration: 201.545µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:48:10.692158Z","caller":"traceutil/trace.go:171","msg":"trace[361534605] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"349.957574ms","start":"2026-04-01T02:48:10.342183Z","end":"2026-04-01T02:48:10.69214Z","steps":["trace[361534605] 'process raft request' (duration: 349.445602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:48:10.692332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T02:48:10.34216Z","time spent":"350.039635ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T02:48:10.69238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.86167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:48:10.692503Z","caller":"traceutil/trace.go:171","msg":"trace[1261927757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8378; }","duration":"208.056335ms","start":"2026-04-01T02:48:10.484418Z","end":"2026-04-01T02:48:10.692475Z","steps":["trace[1261927757] 'agreement among raft nodes before linearized reading' (duration: 207.84254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:48:10.692975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.235606ms","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-04-01T02:48:10.693026Z","caller":"traceutil/trace.go:171","msg":"trace[1707777137] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8378; }","duration":"294.337618ms","start":"2026-04-01T02:48:10.398672Z","end":"2026-04-01T02:48:10.693009Z","steps":["trace[1707777137] 'agreement among raft nodes before linearized reading' (duration: 294.117543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:48:10.83428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.734043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:48:10.834365Z","caller":"traceutil/trace.go:171","msg":"trace[1206043254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8378; }","duration":"140.827025ms","start":"2026-04-01T02:48:10.693519Z","end":"2026-04-01T02:48:10.834346Z","steps":["trace[1206043254] 'range keys from in-memory index tree' (duration: 140.62361ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:48:10.835434Z","caller":"traceutil/trace.go:171","msg":"trace[1674558539] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"136.475406ms","start":"2026-04-01T02:48:10.698935Z","end":"2026-04-01T02:48:10.83541Z","steps":["trace[1674558539] 'process raft request' (duration: 134.693096ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:48:23.012032Z","caller":"traceutil/trace.go:171","msg":"trace[1885195451] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"103.771521ms","start":"2026-04-01T02:48:22.908231Z","end":"2026-04-01T02:48:23.012002Z","steps":["trace[1885195451] 'process raft request' (duration: 36.552321ms)","trace[1885195451] 'compare' (duration: 66.997795ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:48:23.457107Z","caller":"traceutil/trace.go:171","msg":"trace[489114572] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"299.481104ms","start":"2026-04-01T02:48:23.157574Z","end":"2026-04-01T02:48:23.457055Z","steps":["trace[489114572] 'process raft request' (duration: 211.84966ms)","trace[489114572] 'compare' (duration: 87.347238ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:48:23.457518Z","caller":"traceutil/trace.go:171","msg":"trace[2071240737] linearizableReadLoop","detail":"{readStateIndex:8789; appliedIndex:8788; }","duration":"112.308276ms","start":"2026-04-01T02:48:23.345192Z","end":"2026-04-01T02:48:23.4575Z","steps":["trace[2071240737] 'read index received' (duration: 24.211651ms)","trace[2071240737] 'applied index is now lower than readState.Index' (duration: 88.094005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:48:23.457654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.468159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T02:48:23.457723Z","caller":"traceutil/trace.go:171","msg":"trace[1034531203] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8489; }","duration":"112.585542ms","start":"2026-04-01T02:48:23.34512Z","end":"2026-04-01T02:48:23.457706Z","steps":["trace[1034531203] 'agreement among raft nodes before linearized reading' (duration: 112.461319ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:48:24.729362Z","caller":"traceutil/trace.go:171","msg":"trace[1963879091] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"192.373008ms","start":"2026-04-01T02:48:24.536961Z","end":"2026-04-01T02:48:24.729334Z","steps":["trace[1963879091] 'process raft request' (duration: 192.194254ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:49:12.579562Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243d.wal"} {"level":"info","ts":"2026-04-01T02:49:23.889616Z","caller":"traceutil/trace.go:171","msg":"trace[373824960] linearizableReadLoop","detail":"{readStateIndex:9457; appliedIndex:9456; }","duration":"113.774169ms","start":"2026-04-01T02:49:23.775821Z","end":"2026-04-01T02:49:23.889595Z","steps":["trace[373824960] 'read index received' (duration: 113.611275ms)","trace[373824960] 'applied index is now lower than readState.Index' (duration: 161.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:49:23.88964Z","caller":"traceutil/trace.go:171","msg":"trace[1282238725] transaction","detail":"{read_only:false; response_revision:9140; number_of_response:1; }","duration":"141.380912ms","start":"2026-04-01T02:49:23.748237Z","end":"2026-04-01T02:49:23.889618Z","steps":["trace[1282238725] 'process raft request' (duration: 141.212398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:49:23.88977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.941004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T02:49:23.889829Z","caller":"traceutil/trace.go:171","msg":"trace[63186075] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:9140; }","duration":"114.014945ms","start":"2026-04-01T02:49:23.775801Z","end":"2026-04-01T02:49:23.889816Z","steps":["trace[63186075] 'agreement among raft nodes before linearized reading' (duration: 113.884932ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:49:36.200789Z","caller":"traceutil/trace.go:171","msg":"trace[1635249347] linearizableReadLoop","detail":"{readStateIndex:9536; appliedIndex:9535; }","duration":"128.317294ms","start":"2026-04-01T02:49:36.072445Z","end":"2026-04-01T02:49:36.200763Z","steps":["trace[1635249347] 'read index received' (duration: 113.612296ms)","trace[1635249347] 'applied index is now lower than readState.Index' (duration: 14.703918ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T02:49:36.200858Z","caller":"traceutil/trace.go:171","msg":"trace[926789436] transaction","detail":"{read_only:false; response_revision:9216; number_of_response:1; }","duration":"163.055205ms","start":"2026-04-01T02:49:36.037753Z","end":"2026-04-01T02:49:36.200808Z","steps":["trace[926789436] 'process raft request' (duration: 148.250715ms)","trace[926789436] 'compare' (duration: 14.641046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T02:49:36.201032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.57175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T02:49:36.201102Z","caller":"traceutil/trace.go:171","msg":"trace[1500266279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9216; }","duration":"128.674842ms","start":"2026-04-01T02:49:36.072411Z","end":"2026-04-01T02:49:36.201086Z","steps":["trace[1500266279] 'agreement among raft nodes before linearized reading' (duration: 128.497307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T02:49:36.201837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.787844ms","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-04-01T02:49:36.201945Z","caller":"traceutil/trace.go:171","msg":"trace[1967602826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9216; }","duration":"128.948918ms","start":"2026-04-01T02:49:36.072974Z","end":"2026-04-01T02:49:36.201923Z","steps":["trace[1967602826] 'agreement among raft nodes before linearized reading' (duration: 128.660111ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:50:39.607372Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-01T02:50:39.613579Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-01T02:50:39.613683Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-01T02:51:56.382425Z","caller":"traceutil/trace.go:171","msg":"trace[474819072] transaction","detail":"{read_only:false; response_revision:10279; number_of_response:1; }","duration":"104.369602ms","start":"2026-04-01T02:51:56.278036Z","end":"2026-04-01T02:51:56.382405Z","steps":["trace[474819072] 'process raft request' (duration: 104.251739ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T02:53:06.456652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8349} {"level":"info","ts":"2026-04-01T02:53:06.537748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8349,"took":"78.954704ms","hash":1618448119,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":24506368,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-01T02:53:06.537817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1618448119,"revision":8349,"compact-revision":6131} {"level":"info","ts":"2026-04-01T02:58:06.462732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10698} {"level":"info","ts":"2026-04-01T02:58:06.536001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10698,"took":"71.468928ms","hash":1081319252,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":20328448,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-01T02:58:06.536062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081319252,"revision":10698,"compact-revision":8349}