{"level":"warn","ts":"2026-03-09T06:16:29.348343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T06:16:29.348518Z","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-03-09T06:16:29.34866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T06:16:29.348689Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-09T06:16:29.348737Z","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-03-09T06:16:29.350092Z","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-03-09T06:16:29.350248Z","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-03-09T06:16:29.356193Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.47311ms"} {"level":"info","ts":"2026-03-09T06:16:29.363935Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-03-09T06:16:29.364068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T06:16:29.364106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-03-09T06:16:29.364135Z","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-03-09T06:16:29.364155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-03-09T06:16:29.364269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-03-09T06:16:29.372129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T06:16:29.374124Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T06:16:29.375291Z","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-03-09T06:16:29.377811Z","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-03-09T06:16:29.377961Z","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-03-09T06:16:29.37816Z","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-03-09T06:16:29.378399Z","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-03-09T06:16:29.378448Z","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-03-09T06:16:29.380884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-03-09T06:16:29.381319Z","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-03-09T06:16:29.383466Z","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-03-09T06:16:29.383612Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-09T06:16:29.383643Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-09T06:16:29.384126Z","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-03-09T06:16:29.384175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T06:16:29.76497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T06:16:29.765045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T06:16:29.765066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-03-09T06:16:29.765082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-03-09T06:16:29.76509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-09T06:16:29.765101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-03-09T06:16:29.76511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-09T06:16:29.766125Z","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-03-09T06:16:29.766438Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:29.767459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:16:29.768066Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:16:29.768269Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T06:16:29.768294Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T06:16:29.768436Z","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-03-09T06:16:29.768554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:29.768596Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:29.77117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-03-09T06:16:29.773649Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T06:16:44.666698Z","caller":"traceutil/trace.go:171","msg":"trace[55031305] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"138.24238ms","start":"2026-03-09T06:16:44.528428Z","end":"2026-03-09T06:16:44.66667Z","steps":["trace[55031305] 'process raft request' (duration: 138.060447ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:45.135499Z","caller":"traceutil/trace.go:171","msg":"trace[1031444163] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"375.193627ms","start":"2026-03-09T06:16:44.76028Z","end":"2026-03-09T06:16:45.135474Z","steps":["trace[1031444163] 'process raft request' (duration: 277.365824ms)","trace[1031444163] 'compare' (duration: 97.709921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:16:45.136061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:44.760252Z","time spent":"375.32649ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:16:45.433587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.519078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:16:45.433764Z","caller":"traceutil/trace.go:171","msg":"trace[1080832525] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"257.89601ms","start":"2026-03-09T06:16:45.175842Z","end":"2026-03-09T06:16:45.433738Z","steps":["trace[1080832525] 'process raft request' (duration: 114.950935ms)","trace[1080832525] 'compare' (duration: 142.399315ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:01.783755Z","caller":"traceutil/trace.go:171","msg":"trace[1122499243] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"106.133803ms","start":"2026-03-09T06:17:01.677587Z","end":"2026-03-09T06:17:01.783721Z","steps":["trace[1122499243] 'process raft request' (duration: 105.825797ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:01.981376Z","caller":"traceutil/trace.go:171","msg":"trace[125741976] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"192.685692ms","start":"2026-03-09T06:17:01.788646Z","end":"2026-03-09T06:17:01.981332Z","steps":["trace[125741976] 'process raft request' (duration: 177.919072ms)","trace[125741976] 'compare' (duration: 14.559855ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:06.289587Z","caller":"traceutil/trace.go:171","msg":"trace[948658222] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"209.135142ms","start":"2026-03-09T06:17:06.080423Z","end":"2026-03-09T06:17:06.289558Z","steps":["trace[948658222] 'process raft request' (duration: 166.432093ms)","trace[948658222] 'compare' (duration: 42.568005ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:27.426593Z","caller":"traceutil/trace.go:171","msg":"trace[485686668] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"179.981824ms","start":"2026-03-09T06:17:27.246579Z","end":"2026-03-09T06:17:27.42656Z","steps":["trace[485686668] 'process raft request' (duration: 179.804461ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:18.033958Z","caller":"traceutil/trace.go:171","msg":"trace[1671683305] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"118.225522ms","start":"2026-03-09T06:18:17.915706Z","end":"2026-03-09T06:18:18.033931Z","steps":["trace[1671683305] 'process raft request' (duration: 118.050969ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:18.471369Z","caller":"traceutil/trace.go:171","msg":"trace[706296056] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"236.030533ms","start":"2026-03-09T06:18:18.235305Z","end":"2026-03-09T06:18:18.471336Z","steps":["trace[706296056] 'process raft request' (duration: 235.83509ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:18.517731Z","caller":"traceutil/trace.go:171","msg":"trace[177544645] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"227.59313ms","start":"2026-03-09T06:18:18.290108Z","end":"2026-03-09T06:18:18.517701Z","steps":["trace[177544645] 'process raft request' (duration: 227.390876ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:37.369184Z","caller":"traceutil/trace.go:171","msg":"trace[1721913807] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"144.755335ms","start":"2026-03-09T06:18:37.224394Z","end":"2026-03-09T06:18:37.369149Z","steps":["trace[1721913807] 'process raft request' (duration: 144.500921ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:37.598633Z","caller":"traceutil/trace.go:171","msg":"trace[1952865382] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"279.996518ms","start":"2026-03-09T06:18:37.318598Z","end":"2026-03-09T06:18:37.598594Z","steps":["trace[1952865382] 'process raft request' (duration: 253.471493ms)","trace[1952865382] 'compare' (duration: 26.28512ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:18:38.497048Z","caller":"traceutil/trace.go:171","msg":"trace[569492790] linearizableReadLoop","detail":"{readStateIndex:1816; appliedIndex:1815; }","duration":"329.567644ms","start":"2026-03-09T06:18:38.167446Z","end":"2026-03-09T06:18:38.497013Z","steps":["trace[569492790] 'read index received' (duration: 329.242159ms)","trace[569492790] 'applied index is now lower than readState.Index' (duration: 324.035µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:18:38.497121Z","caller":"traceutil/trace.go:171","msg":"trace[210584902] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"388.820297ms","start":"2026-03-09T06:18:38.108262Z","end":"2026-03-09T06:18:38.497082Z","steps":["trace[210584902] 'process raft request' (duration: 388.557783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:38.497227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.748177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:18:38.49727Z","caller":"traceutil/trace.go:171","msg":"trace[1189137797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1749; }","duration":"329.85549ms","start":"2026-03-09T06:18:38.167402Z","end":"2026-03-09T06:18:38.497257Z","steps":["trace[1189137797] 'agreement among raft nodes before linearized reading' (duration: 329.752718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:38.49731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:18:38.167384Z","time spent":"329.9191ms","remote":"127.0.0.1:45440","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-03-09T06:18:38.497303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:18:38.108233Z","time spent":"388.958109ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:19:13.163731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.86598ms","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-03-09T06:19:13.163878Z","caller":"traceutil/trace.go:171","msg":"trace[209840517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1925; }","duration":"103.068314ms","start":"2026-03-09T06:19:13.060787Z","end":"2026-03-09T06:19:13.163856Z","steps":["trace[209840517] 'range keys from in-memory index tree' (duration: 102.644065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:19:13.163765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.202862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-09T06:19:13.164011Z","caller":"traceutil/trace.go:171","msg":"trace[887731691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"111.529989ms","start":"2026-03-09T06:19:13.052443Z","end":"2026-03-09T06:19:13.163973Z","steps":["trace[887731691] 'range keys from in-memory index tree' (duration: 110.962348ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:20:53.206976Z","caller":"traceutil/trace.go:171","msg":"trace[273135458] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"166.771034ms","start":"2026-03-09T06:20:53.040176Z","end":"2026-03-09T06:20:53.206947Z","steps":["trace[273135458] 'process raft request' (duration: 166.294157ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:23:30.09786Z","caller":"traceutil/trace.go:171","msg":"trace[655911422] transaction","detail":"{read_only:false; response_revision:3551; number_of_response:1; }","duration":"258.953652ms","start":"2026-03-09T06:23:29.838861Z","end":"2026-03-09T06:23:30.097814Z","steps":["trace[655911422] 'process raft request' (duration: 190.189622ms)","trace[655911422] 'compare' (duration: 68.579416ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:47.477173Z","caller":"traceutil/trace.go:171","msg":"trace[533126375] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"149.276747ms","start":"2026-03-09T06:24:47.327863Z","end":"2026-03-09T06:24:47.47714Z","steps":["trace[533126375] 'process raft request' (duration: 51.789096ms)","trace[533126375] 'compare' (duration: 97.111634ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:57.334742Z","caller":"traceutil/trace.go:171","msg":"trace[1672113396] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"136.440113ms","start":"2026-03-09T06:24:57.19828Z","end":"2026-03-09T06:24:57.33472Z","steps":["trace[1672113396] 'read index received' (duration: 136.300451ms)","trace[1672113396] 'applied index is now lower than readState.Index' (duration: 139.092µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:57.334797Z","caller":"traceutil/trace.go:171","msg":"trace[511138275] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"137.225667ms","start":"2026-03-09T06:24:57.197541Z","end":"2026-03-09T06:24:57.334766Z","steps":["trace[511138275] 'process raft request' (duration: 137.061994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:57.334912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.618017ms","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-03-09T06:24:57.334942Z","caller":"traceutil/trace.go:171","msg":"trace[1801237897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4711; }","duration":"136.689208ms","start":"2026-03-09T06:24:57.198245Z","end":"2026-03-09T06:24:57.334934Z","steps":["trace[1801237897] 'agreement among raft nodes before linearized reading' (duration: 136.555875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.306251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.795754ms","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-03-09T06:24:58.306393Z","caller":"traceutil/trace.go:171","msg":"trace[1682087589] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4714; }","duration":"307.948636ms","start":"2026-03-09T06:24:57.998423Z","end":"2026-03-09T06:24:58.306371Z","steps":["trace[1682087589] 'range keys from in-memory index tree' (duration: 307.780474ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.306438Z","caller":"traceutil/trace.go:171","msg":"trace[1678075315] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"487.842154ms","start":"2026-03-09T06:24:57.818551Z","end":"2026-03-09T06:24:58.306393Z","steps":["trace[1678075315] 'process raft request' (duration: 394.404507ms)","trace[1678075315] 'compare' (duration: 93.050079ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:58.306488Z","caller":"traceutil/trace.go:171","msg":"trace[846694893] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"487.420045ms","start":"2026-03-09T06:24:57.819051Z","end":"2026-03-09T06:24:58.306471Z","steps":["trace[846694893] 'process raft request' (duration: 487.293003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.306584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:57.818537Z","time spent":"487.969276ms","remote":"127.0.0.1:45720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1618,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:24:58.306627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:57.819031Z","time spent":"487.512797ms","remote":"127.0.0.1:45720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1410,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:24:58.306686Z","caller":"traceutil/trace.go:171","msg":"trace[148521387] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4901; }","duration":"266.58042ms","start":"2026-03-09T06:24:58.040078Z","end":"2026-03-09T06:24:58.306659Z","steps":["trace[148521387] 'read index received' (duration: 172.794076ms)","trace[148521387] 'applied index is now lower than readState.Index' (duration: 93.785073ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:58.306803Z","caller":"traceutil/trace.go:171","msg":"trace[633957626] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"103.651444ms","start":"2026-03-09T06:24:58.203137Z","end":"2026-03-09T06:24:58.306789Z","steps":["trace[633957626] 'process raft request' (duration: 103.43004ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.306814Z","caller":"traceutil/trace.go:171","msg":"trace[818588439] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"487.419775ms","start":"2026-03-09T06:24:57.819376Z","end":"2026-03-09T06:24:58.306795Z","steps":["trace[818588439] 'process raft request' (duration: 487.057499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.306906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:57.819348Z","time spent":"487.504607ms","remote":"127.0.0.1:45720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1602,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:24:58.30707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.947178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:24:58.307136Z","caller":"traceutil/trace.go:171","msg":"trace[482359172] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4718; }","duration":"267.105941ms","start":"2026-03-09T06:24:58.040015Z","end":"2026-03-09T06:24:58.307121Z","steps":["trace[482359172] 'agreement among raft nodes before linearized reading' (duration: 266.835046ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.335131Z","caller":"traceutil/trace.go:171","msg":"trace[447095365] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"119.959331ms","start":"2026-03-09T06:24:58.215144Z","end":"2026-03-09T06:24:58.335103Z","steps":["trace[447095365] 'process raft request' (duration: 119.692186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.335317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.119401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-650f2c73-8376-4dae-bcee-b2410db99397\" ","response":"range_response_count:1 size:3720"} {"level":"warn","ts":"2026-03-09T06:24:58.335302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.692433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:24:58.335378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.959431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:24:58.335419Z","caller":"traceutil/trace.go:171","msg":"trace[1369323372] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4719; }","duration":"259.035162ms","start":"2026-03-09T06:24:58.07637Z","end":"2026-03-09T06:24:58.335405Z","steps":["trace[1369323372] 'agreement among raft nodes before linearized reading' (duration: 258.9319ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.335417Z","caller":"traceutil/trace.go:171","msg":"trace[898340315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4719; }","duration":"167.842755ms","start":"2026-03-09T06:24:58.16755Z","end":"2026-03-09T06:24:58.335392Z","steps":["trace[898340315] 'agreement among raft nodes before linearized reading' (duration: 167.636211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.33531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.433504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hkqw4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-09T06:24:58.335357Z","caller":"traceutil/trace.go:171","msg":"trace[21722145] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-650f2c73-8376-4dae-bcee-b2410db99397; range_end:; response_count:1; response_revision:4719; }","duration":"168.194422ms","start":"2026-03-09T06:24:58.167153Z","end":"2026-03-09T06:24:58.335347Z","steps":["trace[21722145] 'agreement among raft nodes before linearized reading' (duration: 168.034389ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.335661Z","caller":"traceutil/trace.go:171","msg":"trace[1407694662] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hkqw4; range_end:; response_count:1; response_revision:4719; }","duration":"181.73896ms","start":"2026-03-09T06:24:58.153844Z","end":"2026-03-09T06:24:58.335583Z","steps":["trace[1407694662] 'agreement among raft nodes before linearized reading' (duration: 181.275211ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:07.769772Z","caller":"traceutil/trace.go:171","msg":"trace[1088569372] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"194.671157ms","start":"2026-03-09T06:25:07.575061Z","end":"2026-03-09T06:25:07.769732Z","steps":["trace[1088569372] 'process raft request' (duration: 109.528081ms)","trace[1088569372] 'compare' (duration: 84.947112ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:07.776336Z","caller":"traceutil/trace.go:171","msg":"trace[1632359357] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"200.263479ms","start":"2026-03-09T06:25:07.576056Z","end":"2026-03-09T06:25:07.776319Z","steps":["trace[1632359357] 'process raft request' (duration: 199.999554ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:07.776366Z","caller":"traceutil/trace.go:171","msg":"trace[774175616] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"200.678636ms","start":"2026-03-09T06:25:07.575671Z","end":"2026-03-09T06:25:07.776349Z","steps":["trace[774175616] 'process raft request' (duration: 200.30913ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:07.776415Z","caller":"traceutil/trace.go:171","msg":"trace[880621304] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"200.249749ms","start":"2026-03-09T06:25:07.576148Z","end":"2026-03-09T06:25:07.776397Z","steps":["trace[880621304] 'process raft request' (duration: 199.939684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.133851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.174075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:25:15.134109Z","caller":"traceutil/trace.go:171","msg":"trace[272278137] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"196.493539ms","start":"2026-03-09T06:25:14.937591Z","end":"2026-03-09T06:25:15.134085Z","steps":["trace[272278137] 'process raft request' (duration: 92.976918ms)","trace[272278137] 'compare' (duration: 103.043373ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:15.134113Z","caller":"traceutil/trace.go:171","msg":"trace[771176687] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"184.933119ms","start":"2026-03-09T06:25:14.949157Z","end":"2026-03-09T06:25:15.13409Z","steps":["trace[771176687] 'process raft request' (duration: 184.837197ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:15.210969Z","caller":"traceutil/trace.go:171","msg":"trace[1838517159] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"143.640263ms","start":"2026-03-09T06:25:15.067298Z","end":"2026-03-09T06:25:15.210938Z","steps":["trace[1838517159] 'process raft request' (duration: 143.47717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.410339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.65641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:25:15.410439Z","caller":"traceutil/trace.go:171","msg":"trace[1696646779] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"191.605191ms","start":"2026-03-09T06:25:15.218818Z","end":"2026-03-09T06:25:15.410423Z","steps":["trace[1696646779] 'process raft request' (duration: 73.790748ms)","trace[1696646779] 'compare' (duration: 117.405784ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:15.499374Z","caller":"traceutil/trace.go:171","msg":"trace[1179720314] linearizableReadLoop","detail":"{readStateIndex:5262; appliedIndex:5260; }","duration":"271.559142ms","start":"2026-03-09T06:25:15.227787Z","end":"2026-03-09T06:25:15.499346Z","steps":["trace[1179720314] 'read index received' (duration: 64.754834ms)","trace[1179720314] 'applied index is now lower than readState.Index' (duration: 206.803178ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:25:15.499606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.812486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:25:15.499667Z","caller":"traceutil/trace.go:171","msg":"trace[1648008810] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5065; }","duration":"213.942768ms","start":"2026-03-09T06:25:15.285712Z","end":"2026-03-09T06:25:15.499655Z","steps":["trace[1648008810] 'agreement among raft nodes before linearized reading' (duration: 213.814016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.499691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.873897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-09T06:25:15.499785Z","caller":"traceutil/trace.go:171","msg":"trace[768962191] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5065; }","duration":"271.99834ms","start":"2026-03-09T06:25:15.227766Z","end":"2026-03-09T06:25:15.499764Z","steps":["trace[768962191] 'agreement among raft nodes before linearized reading' (duration: 271.733064ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:15.499983Z","caller":"traceutil/trace.go:171","msg":"trace[1819775555] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"274.279881ms","start":"2026-03-09T06:25:15.225673Z","end":"2026-03-09T06:25:15.499953Z","steps":["trace[1819775555] 'process raft request' (duration: 273.508987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.649648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.193999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:25:15.649757Z","caller":"traceutil/trace.go:171","msg":"trace[611480639] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5262; }","duration":"144.493ms","start":"2026-03-09T06:25:15.505248Z","end":"2026-03-09T06:25:15.649741Z","steps":["trace[611480639] 'read index received' (duration: 37.122488ms)","trace[611480639] 'applied index is now lower than readState.Index' (duration: 107.369072ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:15.649835Z","caller":"traceutil/trace.go:171","msg":"trace[613186044] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"145.182292ms","start":"2026-03-09T06:25:15.504642Z","end":"2026-03-09T06:25:15.649824Z","steps":["trace[613186044] 'process raft request' (duration: 37.729419ms)","trace[613186044] 'compare' (duration: 106.841032ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:25:15.650058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.795836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-09T06:25:15.650136Z","caller":"traceutil/trace.go:171","msg":"trace[786000722] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5066; }","duration":"144.901377ms","start":"2026-03-09T06:25:15.50521Z","end":"2026-03-09T06:25:15.650112Z","steps":["trace[786000722] 'agreement among raft nodes before linearized reading' (duration: 144.679344ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:15.80984Z","caller":"traceutil/trace.go:171","msg":"trace[1970695670] linearizableReadLoop","detail":"{readStateIndex:5264; appliedIndex:5263; }","duration":"160.020633ms","start":"2026-03-09T06:25:15.649792Z","end":"2026-03-09T06:25:15.809812Z","steps":["trace[1970695670] 'read index received' (duration: 121.391697ms)","trace[1970695670] 'applied index is now lower than readState.Index' (duration: 38.627966ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:25:15.810107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.371659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-09T06:25:15.810159Z","caller":"traceutil/trace.go:171","msg":"trace[1563787733] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5067; }","duration":"282.450501ms","start":"2026-03-09T06:25:15.527696Z","end":"2026-03-09T06:25:15.810146Z","steps":["trace[1563787733] 'agreement among raft nodes before linearized reading' (duration: 282.238817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.810307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.3751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-03-09T06:25:15.810354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.128011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2592"} {"level":"info","ts":"2026-03-09T06:25:15.810415Z","caller":"traceutil/trace.go:171","msg":"trace[86261229] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"297.414294ms","start":"2026-03-09T06:25:15.512983Z","end":"2026-03-09T06:25:15.810397Z","steps":["trace[86261229] 'process raft request' (duration: 258.172057ms)","trace[86261229] 'compare' (duration: 38.509564ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:15.810439Z","caller":"traceutil/trace.go:171","msg":"trace[55400961] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5067; }","duration":"301.259294ms","start":"2026-03-09T06:25:15.509162Z","end":"2026-03-09T06:25:15.810421Z","steps":["trace[55400961] 'agreement among raft nodes before linearized reading' (duration: 300.951278ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:15.810386Z","caller":"traceutil/trace.go:171","msg":"trace[84050866] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5067; }","duration":"304.492792ms","start":"2026-03-09T06:25:15.505881Z","end":"2026-03-09T06:25:15.810374Z","steps":["trace[84050866] 'agreement among raft nodes before linearized reading' (duration: 304.210857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.810495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.788153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d010b07-3e6f-4e58-954d-a99d02e95134\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-03-09T06:25:15.810563Z","caller":"traceutil/trace.go:171","msg":"trace[527053123] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3d010b07-3e6f-4e58-954d-a99d02e95134; range_end:; response_count:1; response_revision:5067; }","duration":"203.893145ms","start":"2026-03-09T06:25:15.606656Z","end":"2026-03-09T06:25:15.810549Z","steps":["trace[527053123] 'agreement among raft nodes before linearized reading' (duration: 203.67272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.810428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.868546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-03-09T06:25:15.810621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:25:15.505872Z","time spent":"304.702278ms","remote":"127.0.0.1:45612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-09T06:25:15.810684Z","caller":"traceutil/trace.go:171","msg":"trace[1899599965] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5067; }","duration":"157.107141ms","start":"2026-03-09T06:25:15.65355Z","end":"2026-03-09T06:25:15.810657Z","steps":["trace[1899599965] 'agreement among raft nodes before linearized reading' (duration: 156.822635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.810355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.04046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-09T06:25:15.810858Z","caller":"traceutil/trace.go:171","msg":"trace[809503973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5067; }","duration":"289.59154ms","start":"2026-03-09T06:25:15.521258Z","end":"2026-03-09T06:25:15.810849Z","steps":["trace[809503973] 'agreement among raft nodes before linearized reading' (duration: 288.930048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:15.810496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:25:15.509149Z","time spent":"301.337965ms","remote":"127.0.0.1:34406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2615,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-03-09T06:25:27.3462Z","caller":"traceutil/trace.go:171","msg":"trace[1111632433] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"147.746779ms","start":"2026-03-09T06:25:27.198426Z","end":"2026-03-09T06:25:27.346173Z","steps":["trace[1111632433] 'process raft request' (duration: 147.567406ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:26:30.029714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2789} {"level":"info","ts":"2026-03-09T06:26:30.091171Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2789,"took":"59.825937ms","hash":440992430,"current-db-size-bytes":41123840,"current-db-size":"41 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-09T06:26:30.091263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":440992430,"revision":2789,"compact-revision":-1} {"level":"info","ts":"2026-03-09T06:31:17.860544Z","caller":"traceutil/trace.go:171","msg":"trace[1149312265] transaction","detail":"{read_only:false; response_revision:7579; number_of_response:1; }","duration":"132.60416ms","start":"2026-03-09T06:31:17.727908Z","end":"2026-03-09T06:31:17.860512Z","steps":["trace[1149312265] 'process raft request' (duration: 117.835099ms)","trace[1149312265] 'compare' (duration: 14.630288ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:28.447323Z","caller":"traceutil/trace.go:171","msg":"trace[683751516] linearizableReadLoop","detail":"{readStateIndex:8046; appliedIndex:8045; }","duration":"341.721591ms","start":"2026-03-09T06:31:28.105567Z","end":"2026-03-09T06:31:28.447289Z","steps":["trace[683751516] 'read index received' (duration: 341.66491ms)","trace[683751516] 'applied index is now lower than readState.Index' (duration: 54.961µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:28.447429Z","caller":"traceutil/trace.go:171","msg":"trace[684521232] transaction","detail":"{read_only:false; response_revision:7759; number_of_response:1; }","duration":"389.371866ms","start":"2026-03-09T06:31:28.058012Z","end":"2026-03-09T06:31:28.447384Z","steps":["trace[684521232] 'process raft request' (duration: 389.06288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:28.447633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:31:28.05799Z","time spent":"389.535089ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:31:28.447628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.047718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-09T06:31:28.447805Z","caller":"traceutil/trace.go:171","msg":"trace[738682647] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7759; }","duration":"342.255522ms","start":"2026-03-09T06:31:28.105515Z","end":"2026-03-09T06:31:28.44777Z","steps":["trace[738682647] 'agreement among raft nodes before linearized reading' (duration: 341.901984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:28.447925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:31:28.105489Z","time spent":"342.399724ms","remote":"127.0.0.1:45612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-09T06:31:28.691548Z","caller":"traceutil/trace.go:171","msg":"trace[760252880] transaction","detail":"{read_only:false; response_revision:7760; number_of_response:1; }","duration":"580.607528ms","start":"2026-03-09T06:31:28.110888Z","end":"2026-03-09T06:31:28.691495Z","steps":["trace[760252880] 'process raft request' (duration: 568.539338ms)","trace[760252880] 'compare' (duration: 11.901247ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:31:28.69169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:31:28.11087Z","time spent":"580.742581ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:31:28.714364Z","caller":"traceutil/trace.go:171","msg":"trace[873080545] linearizableReadLoop","detail":"{readStateIndex:8048; appliedIndex:8046; }","duration":"266.882759ms","start":"2026-03-09T06:31:28.447429Z","end":"2026-03-09T06:31:28.714312Z","steps":["trace[873080545] 'read index received' (duration: 232.018617ms)","trace[873080545] 'applied index is now lower than readState.Index' (duration: 34.862382ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:31:28.714511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.860666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:31:28.714595Z","caller":"traceutil/trace.go:171","msg":"trace[1977812982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7760; }","duration":"546.966789ms","start":"2026-03-09T06:31:28.167607Z","end":"2026-03-09T06:31:28.714574Z","steps":["trace[1977812982] 'agreement among raft nodes before linearized reading' (duration: 546.848067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:28.714642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:31:28.167598Z","time spent":"547.03675ms","remote":"127.0.0.1:45440","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-03-09T06:31:28.714823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.049285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-09T06:31:28.714883Z","caller":"traceutil/trace.go:171","msg":"trace[909555657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7760; }","duration":"263.154457ms","start":"2026-03-09T06:31:28.451713Z","end":"2026-03-09T06:31:28.714868Z","steps":["trace[909555657] 'agreement among raft nodes before linearized reading' (duration: 262.814621ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:30.037065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5729} {"level":"info","ts":"2026-03-09T06:31:30.128173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5729,"took":"89.008201ms","hash":2649319352,"current-db-size-bytes":42840064,"current-db-size":"43 MB","current-db-size-in-use-bytes":23457792,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-09T06:31:30.128237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2649319352,"revision":5729,"compact-revision":2789} {"level":"warn","ts":"2026-03-09T06:31:38.590243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.387627ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-09T06:31:38.590511Z","caller":"traceutil/trace.go:171","msg":"trace[1079974624] transaction","detail":"{read_only:false; response_revision:7806; number_of_response:1; }","duration":"110.881866ms","start":"2026-03-09T06:31:38.479611Z","end":"2026-03-09T06:31:38.590493Z","steps":["trace[1079974624] 'process raft request' (duration: 110.746644ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:40.161246Z","caller":"traceutil/trace.go:171","msg":"trace[910309791] linearizableReadLoop","detail":"{readStateIndex:8103; appliedIndex:8101; }","duration":"190.808035ms","start":"2026-03-09T06:31:39.970412Z","end":"2026-03-09T06:31:40.16122Z","steps":["trace[910309791] 'read index received' (duration: 28.581724ms)","trace[910309791] 'applied index is now lower than readState.Index' (duration: 162.225041ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:40.16131Z","caller":"traceutil/trace.go:171","msg":"trace[1731154073] transaction","detail":"{read_only:false; response_revision:7812; number_of_response:1; }","duration":"199.114553ms","start":"2026-03-09T06:31:39.96215Z","end":"2026-03-09T06:31:40.161264Z","steps":["trace[1731154073] 'process raft request' (duration: 105.564016ms)","trace[1731154073] 'compare' (duration: 93.385134ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:31:40.161515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.08765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-09T06:31:40.16156Z","caller":"traceutil/trace.go:171","msg":"trace[1317813625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7812; }","duration":"191.182301ms","start":"2026-03-09T06:31:39.970367Z","end":"2026-03-09T06:31:40.16155Z","steps":["trace[1317813625] 'agreement among raft nodes before linearized reading' (duration: 191.000928ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:07.458741Z","caller":"traceutil/trace.go:171","msg":"trace[1489240815] linearizableReadLoop","detail":"{readStateIndex:8300; appliedIndex:8299; }","duration":"186.427321ms","start":"2026-03-09T06:32:07.27229Z","end":"2026-03-09T06:32:07.458718Z","steps":["trace[1489240815] 'read index received' (duration: 186.224077ms)","trace[1489240815] 'applied index is now lower than readState.Index' (duration: 202.174µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:32:07.45906Z","caller":"traceutil/trace.go:171","msg":"trace[1672725119] transaction","detail":"{read_only:false; response_revision:8002; number_of_response:1; }","duration":"298.987808ms","start":"2026-03-09T06:32:07.16004Z","end":"2026-03-09T06:32:07.459028Z","steps":["trace[1672725119] 'process raft request' (duration: 298.502959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:32:07.459107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.794167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T06:32:07.459228Z","caller":"traceutil/trace.go:171","msg":"trace[346036091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8002; }","duration":"186.968781ms","start":"2026-03-09T06:32:07.272246Z","end":"2026-03-09T06:32:07.459215Z","steps":["trace[346036091] 'agreement among raft nodes before linearized reading' (duration: 186.727326ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:07.744045Z","caller":"traceutil/trace.go:171","msg":"trace[249920759] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"280.659792ms","start":"2026-03-09T06:32:07.463358Z","end":"2026-03-09T06:32:07.744018Z","steps":["trace[249920759] 'process raft request' (duration: 280.425978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:32:10.723968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.98163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:32:10.724181Z","caller":"traceutil/trace.go:171","msg":"trace[587116282] linearizableReadLoop","detail":"{readStateIndex:8314; appliedIndex:8313; }","duration":"314.289ms","start":"2026-03-09T06:32:10.409849Z","end":"2026-03-09T06:32:10.724138Z","steps":["trace[587116282] 'read index received' (duration: 125.925952ms)","trace[587116282] 'applied index is now lower than readState.Index' (duration: 188.360917ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:32:10.724331Z","caller":"traceutil/trace.go:171","msg":"trace[1107555583] transaction","detail":"{read_only:false; response_revision:8015; number_of_response:1; }","duration":"353.159417ms","start":"2026-03-09T06:32:10.371137Z","end":"2026-03-09T06:32:10.724297Z","steps":["trace[1107555583] 'process raft request' (duration: 164.739729ms)","trace[1107555583] 'compare' (duration: 187.660835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:32:10.724435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.585275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-09T06:32:10.724509Z","caller":"traceutil/trace.go:171","msg":"trace[1148475239] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8015; }","duration":"314.713138ms","start":"2026-03-09T06:32:10.409779Z","end":"2026-03-09T06:32:10.724492Z","steps":["trace[1148475239] 'agreement among raft nodes before linearized reading' (duration: 314.467252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:32:10.724579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:32:10.409753Z","time spent":"314.806109ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-09T06:32:10.72457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:32:10.371102Z","time spent":"353.311291ms","remote":"127.0.0.1:45706","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-03-09T06:32:11.005062Z","caller":"traceutil/trace.go:171","msg":"trace[2095017] transaction","detail":"{read_only:false; response_revision:8016; number_of_response:1; }","duration":"292.842952ms","start":"2026-03-09T06:32:10.712178Z","end":"2026-03-09T06:32:11.005021Z","steps":["trace[2095017] 'process raft request' (duration: 266.018313ms)","trace[2095017] 'compare' (duration: 26.108305ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:32:11.005268Z","caller":"traceutil/trace.go:171","msg":"trace[1796396184] transaction","detail":"{read_only:false; response_revision:8017; number_of_response:1; }","duration":"274.965462ms","start":"2026-03-09T06:32:10.730271Z","end":"2026-03-09T06:32:11.005237Z","steps":["trace[1796396184] 'process raft request' (duration: 274.448433ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:11.005771Z","caller":"traceutil/trace.go:171","msg":"trace[1390303440] linearizableReadLoop","detail":"{readStateIndex:8316; appliedIndex:8314; }","duration":"244.174518ms","start":"2026-03-09T06:32:10.761576Z","end":"2026-03-09T06:32:11.005751Z","steps":["trace[1390303440] 'read index received' (duration: 216.715787ms)","trace[1390303440] 'applied index is now lower than readState.Index' (duration: 27.457611ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:32:11.006031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.441413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-79db57c99f-xmz75.189b188d5fcca8e2\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-03-09T06:32:11.00614Z","caller":"traceutil/trace.go:171","msg":"trace[621649889] range","detail":"{range_begin:/registry/events/openstack/barbican-api-79db57c99f-xmz75.189b188d5fcca8e2; range_end:; response_count:1; response_revision:8018; }","duration":"244.623706ms","start":"2026-03-09T06:32:10.761495Z","end":"2026-03-09T06:32:11.006119Z","steps":["trace[621649889] 'agreement among raft nodes before linearized reading' (duration: 244.378351ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:20.43394Z","caller":"traceutil/trace.go:171","msg":"trace[501218619] linearizableReadLoop","detail":"{readStateIndex:8410; appliedIndex:8409; }","duration":"120.824285ms","start":"2026-03-09T06:32:20.31307Z","end":"2026-03-09T06:32:20.433894Z","steps":["trace[501218619] 'read index received' (duration: 120.5491ms)","trace[501218619] 'applied index is now lower than readState.Index' (duration: 272.845µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:32:20.434034Z","caller":"traceutil/trace.go:171","msg":"trace[1088476936] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"198.753926ms","start":"2026-03-09T06:32:20.235228Z","end":"2026-03-09T06:32:20.433982Z","steps":["trace[1088476936] 'process raft request' (duration: 198.360889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:32:20.434479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.306574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:32:20.434556Z","caller":"traceutil/trace.go:171","msg":"trace[836483818] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8105; }","duration":"121.491078ms","start":"2026-03-09T06:32:20.313045Z","end":"2026-03-09T06:32:20.434537Z","steps":["trace[836483818] 'agreement among raft nodes before linearized reading' (duration: 121.005308ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:20.532109Z","caller":"traceutil/trace.go:171","msg":"trace[1046692326] transaction","detail":"{read_only:false; response_revision:8106; number_of_response:1; }","duration":"214.819289ms","start":"2026-03-09T06:32:20.317235Z","end":"2026-03-09T06:32:20.532055Z","steps":["trace[1046692326] 'process raft request' (duration: 214.502194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:32:20.532327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.953002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s9j9f\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-03-09T06:32:20.532373Z","caller":"traceutil/trace.go:171","msg":"trace[928206064] transaction","detail":"{read_only:false; response_revision:8107; number_of_response:1; }","duration":"204.636407ms","start":"2026-03-09T06:32:20.327707Z","end":"2026-03-09T06:32:20.532343Z","steps":["trace[928206064] 'process raft request' (duration: 204.209309ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:20.532433Z","caller":"traceutil/trace.go:171","msg":"trace[1553273906] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s9j9f; range_end:; response_count:1; response_revision:8108; }","duration":"215.139806ms","start":"2026-03-09T06:32:20.317269Z","end":"2026-03-09T06:32:20.532409Z","steps":["trace[1553273906] 'agreement among raft nodes before linearized reading' (duration: 214.82813ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:20.532557Z","caller":"traceutil/trace.go:171","msg":"trace[1144935985] transaction","detail":"{read_only:false; response_revision:8108; number_of_response:1; }","duration":"146.525053ms","start":"2026-03-09T06:32:20.385967Z","end":"2026-03-09T06:32:20.532492Z","steps":["trace[1144935985] 'process raft request' (duration: 146.016923ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:20.785054Z","caller":"traceutil/trace.go:171","msg":"trace[134986572] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"141.362695ms","start":"2026-03-09T06:32:20.64365Z","end":"2026-03-09T06:32:20.785013Z","steps":["trace[134986572] 'process raft request' (duration: 141.015529ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:21.478649Z","caller":"traceutil/trace.go:171","msg":"trace[491520460] transaction","detail":"{read_only:false; response_revision:8115; number_of_response:1; }","duration":"214.191559ms","start":"2026-03-09T06:32:21.264423Z","end":"2026-03-09T06:32:21.478614Z","steps":["trace[491520460] 'process raft request' (duration: 213.964554ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:21.511556Z","caller":"traceutil/trace.go:171","msg":"trace[284874029] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"202.222371ms","start":"2026-03-09T06:32:21.309309Z","end":"2026-03-09T06:32:21.511532Z","steps":["trace[284874029] 'process raft request' (duration: 202.115019ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:33:26.121543Z","caller":"traceutil/trace.go:171","msg":"trace[875975220] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"142.902408ms","start":"2026-03-09T06:33:25.978614Z","end":"2026-03-09T06:33:26.121516Z","steps":["trace[875975220] 'process raft request' (duration: 142.723225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:33:38.371253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.570082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T06:33:38.371311Z","caller":"traceutil/trace.go:171","msg":"trace[870997358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8826; }","duration":"125.672793ms","start":"2026-03-09T06:33:38.245626Z","end":"2026-03-09T06:33:38.371299Z","steps":["trace[870997358] 'range keys from in-memory index tree' (duration: 125.418828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:33:38.37128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.401478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T06:33:38.371384Z","caller":"traceutil/trace.go:171","msg":"trace[422369084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8826; }","duration":"124.53884ms","start":"2026-03-09T06:33:38.24682Z","end":"2026-03-09T06:33:38.371359Z","steps":["trace[422369084] 'range keys from in-memory index tree' (duration: 124.249413ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:35:41.104133Z","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-03-09T06:35:41.110177Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-09T06:35:41.110335Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-09T06:36:30.043713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7768} {"level":"info","ts":"2026-03-09T06:36:30.11142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7768,"took":"65.135107ms","hash":2123065676,"current-db-size-bytes":42840064,"current-db-size":"43 MB","current-db-size-in-use-bytes":26447872,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-09T06:36:30.111485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2123065676,"revision":7768,"compact-revision":5729} {"level":"info","ts":"2026-03-09T06:41:30.050249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10020} {"level":"info","ts":"2026-03-09T06:41:30.132713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10020,"took":"80.159187ms","hash":3863925532,"current-db-size-bytes":42840064,"current-db-size":"43 MB","current-db-size-in-use-bytes":17666048,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-09T06:41:30.132794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3863925532,"revision":10020,"compact-revision":7768}