{"level":"warn","ts":"2026-04-17T02:28:40.107061Z","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-17T02:28:40.107224Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.170:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.170:2380","--initial-cluster=instance=https://199.19.213.170:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.170:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.170: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-17T02:28:40.10731Z","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-17T02:28:40.107336Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-04-17T02:28:40.107368Z","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-17T02:28:40.108209Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"]} {"level":"info","ts":"2026-04-17T02:28:40.10835Z","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://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.170: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-17T02:28:40.112864Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.215012ms"} {"level":"info","ts":"2026-04-17T02:28:40.120313Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"80be33c6b0d5a492","cluster-id":"abad33c2eec87dc"} {"level":"info","ts":"2026-04-17T02:28:40.120466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T02:28:40.12052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 0"} {"level":"info","ts":"2026-04-17T02:28:40.120556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 80be33c6b0d5a492 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T02:28:40.120598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 1"} {"level":"info","ts":"2026-04-17T02:28:40.120668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"warn","ts":"2026-04-17T02:28:40.135017Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T02:28:40.136747Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T02:28:40.139508Z","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-17T02:28:40.14194Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"80be33c6b0d5a492","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T02:28:40.142452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"80be33c6b0d5a492","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T02:28:40.14258Z","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-17T02:28:40.142898Z","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-17T02:28:40.142927Z","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-17T02:28:40.143333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"info","ts":"2026-04-17T02:28:40.145696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","added-peer-id":"80be33c6b0d5a492","added-peer-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-04-17T02:28:40.148496Z","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-17T02:28:40.148688Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-04-17T02:28:40.148736Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-04-17T02:28:40.149057Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"80be33c6b0d5a492","initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T02:28:40.149138Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T02:28:40.521198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T02:28:40.52131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T02:28:40.521369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgPreVoteResp from 80be33c6b0d5a492 at term 1"} {"level":"info","ts":"2026-04-17T02:28:40.521397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became candidate at term 2"} {"level":"info","ts":"2026-04-17T02:28:40.521433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgVoteResp from 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-04-17T02:28:40.521467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became leader at term 2"} {"level":"info","ts":"2026-04-17T02:28:40.521489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 80be33c6b0d5a492 elected leader 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-04-17T02:28:40.52294Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:28:40.52591Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"80be33c6b0d5a492","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.170:2379]}","request-path":"/0/members/80be33c6b0d5a492/attributes","cluster-id":"abad33c2eec87dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T02:28:40.525921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T02:28:40.526007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T02:28:40.526474Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T02:28:40.526608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T02:28:40.527458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:28:40.5276Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:28:40.527656Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T02:28:40.529515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T02:28:40.529542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.170:2379"} {"level":"warn","ts":"2026-04-17T02:28:59.375896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.842419ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T02:29:13.922015Z","caller":"traceutil/trace.go:171","msg":"trace[1393763396] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"142.332952ms","start":"2026-04-17T02:29:13.779653Z","end":"2026-04-17T02:29:13.921986Z","steps":["trace[1393763396] 'process raft request' (duration: 142.129897ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:29:14.081986Z","caller":"traceutil/trace.go:171","msg":"trace[924720514] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"172.781907ms","start":"2026-04-17T02:29:13.90918Z","end":"2026-04-17T02:29:14.081962Z","steps":["trace[924720514] 'process raft request' (duration: 172.192171ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:29:14.200093Z","caller":"traceutil/trace.go:171","msg":"trace[826012873] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"273.514469ms","start":"2026-04-17T02:29:13.926543Z","end":"2026-04-17T02:29:14.200058Z","steps":["trace[826012873] 'process raft request' (duration: 247.551986ms)","trace[826012873] 'compare' (duration: 25.797987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:29:14.765716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.354206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:29:14.766003Z","caller":"traceutil/trace.go:171","msg":"trace[285931056] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"500.709551ms","start":"2026-04-17T02:29:14.265233Z","end":"2026-04-17T02:29:14.765942Z","steps":["trace[285931056] 'process raft request' (duration: 281.528784ms)","trace[285931056] 'compare' (duration: 218.187669ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:29:14.766112Z","caller":"traceutil/trace.go:171","msg":"trace[770625265] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"297.800585ms","start":"2026-04-17T02:29:14.468242Z","end":"2026-04-17T02:29:14.766042Z","steps":["trace[770625265] 'read index received' (duration: 78.598429ms)","trace[770625265] 'applied index is now lower than readState.Index' (duration: 219.199146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:29:14.766163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:29:14.265222Z","time spent":"500.864057ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:29:14.766401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.126237ms","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-04-17T02:29:14.766494Z","caller":"traceutil/trace.go:171","msg":"trace[140278033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"298.277623ms","start":"2026-04-17T02:29:14.468196Z","end":"2026-04-17T02:29:14.766474Z","steps":["trace[140278033] 'agreement among raft nodes before linearized reading' (duration: 297.946901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:29:40.559917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:29:40.560046Z","caller":"traceutil/trace.go:171","msg":"trace[838945865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"205.344257ms","start":"2026-04-17T02:29:40.354685Z","end":"2026-04-17T02:29:40.56003Z","steps":["trace[838945865] 'range keys from in-memory index tree' (duration: 205.044281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:29:40.560187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.366992ms","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-17T02:29:40.560311Z","caller":"traceutil/trace.go:171","msg":"trace[1775682652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"240.536321ms","start":"2026-04-17T02:29:40.319745Z","end":"2026-04-17T02:29:40.560281Z","steps":["trace[1775682652] 'range keys from in-memory index tree' (duration: 240.155484ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:30:54.731454Z","caller":"traceutil/trace.go:171","msg":"trace[1584048004] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"122.766281ms","start":"2026-04-17T02:30:54.608657Z","end":"2026-04-17T02:30:54.731424Z","steps":["trace[1584048004] 'process raft request' (duration: 122.577431ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:31:05.253864Z","caller":"traceutil/trace.go:171","msg":"trace[1905145393] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"207.723595ms","start":"2026-04-17T02:31:05.046101Z","end":"2026-04-17T02:31:05.253824Z","steps":["trace[1905145393] 'process raft request' (duration: 128.733659ms)","trace[1905145393] 'compare' (duration: 78.852059ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:31:05.528384Z","caller":"traceutil/trace.go:171","msg":"trace[1015069074] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"153.496419ms","start":"2026-04-17T02:31:05.374858Z","end":"2026-04-17T02:31:05.528354Z","steps":["trace[1015069074] 'read index received' (duration: 68.260541ms)","trace[1015069074] 'applied index is now lower than readState.Index' (duration: 85.234728ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:31:05.528424Z","caller":"traceutil/trace.go:171","msg":"trace[208635611] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"202.563398ms","start":"2026-04-17T02:31:05.325807Z","end":"2026-04-17T02:31:05.528371Z","steps":["trace[208635611] 'process raft request' (duration: 117.204303ms)","trace[208635611] 'compare' (duration: 85.211038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:31:05.528612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.739272ms","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-04-17T02:31:05.52869Z","caller":"traceutil/trace.go:171","msg":"trace[160394275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"153.87581ms","start":"2026-04-17T02:31:05.374797Z","end":"2026-04-17T02:31:05.528672Z","steps":["trace[160394275] 'agreement among raft nodes before linearized reading' (duration: 153.648408ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:31:05.533375Z","caller":"traceutil/trace.go:171","msg":"trace[1591095505] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"135.768213ms","start":"2026-04-17T02:31:05.397582Z","end":"2026-04-17T02:31:05.53335Z","steps":["trace[1591095505] 'process raft request' (duration: 135.679378ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:31:05.706548Z","caller":"traceutil/trace.go:171","msg":"trace[1684145741] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"173.438122ms","start":"2026-04-17T02:31:05.533074Z","end":"2026-04-17T02:31:05.706512Z","steps":["trace[1684145741] 'process raft request' (duration: 96.379205ms)","trace[1684145741] 'compare' (duration: 76.869497ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:37:11.952085Z","caller":"traceutil/trace.go:171","msg":"trace[599008171] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"126.770884ms","start":"2026-04-17T02:37:11.825273Z","end":"2026-04-17T02:37:11.952044Z","steps":["trace[599008171] 'process raft request' (duration: 126.413286ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:21.130311Z","caller":"traceutil/trace.go:171","msg":"trace[978544729] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"166.931466ms","start":"2026-04-17T02:37:20.963343Z","end":"2026-04-17T02:37:21.130274Z","steps":["trace[978544729] 'process raft request' (duration: 123.169165ms)","trace[978544729] 'compare' (duration: 43.158567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:37:21.455858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.457747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:37:21.455965Z","caller":"traceutil/trace.go:171","msg":"trace[660663004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2418; }","duration":"100.61021ms","start":"2026-04-17T02:37:21.355331Z","end":"2026-04-17T02:37:21.455941Z","steps":["trace[660663004] 'range keys from in-memory index tree' (duration: 100.365084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:37:23.866646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.941504ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-17T02:37:23.866757Z","caller":"traceutil/trace.go:171","msg":"trace[1461554319] linearizableReadLoop","detail":"{readStateIndex:2552; appliedIndex:2551; }","duration":"178.947829ms","start":"2026-04-17T02:37:23.687792Z","end":"2026-04-17T02:37:23.86674Z","steps":["trace[1461554319] 'read index received' (duration: 54.601µs)","trace[1461554319] 'applied index is now lower than readState.Index' (duration: 178.891718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:37:23.8669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.107973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:37:23.866934Z","caller":"traceutil/trace.go:171","msg":"trace[1652223664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2426; }","duration":"179.166585ms","start":"2026-04-17T02:37:23.687758Z","end":"2026-04-17T02:37:23.866925Z","steps":["trace[1652223664] 'agreement among raft nodes before linearized reading' (duration: 179.026891ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:33.357055Z","caller":"traceutil/trace.go:171","msg":"trace[308219780] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"195.722309ms","start":"2026-04-17T02:37:33.161307Z","end":"2026-04-17T02:37:33.357029Z","steps":["trace[308219780] 'process raft request' (duration: 109.916365ms)","trace[308219780] 'compare' (duration: 85.376354ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:37:36.386461Z","caller":"traceutil/trace.go:171","msg":"trace[142886806] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"106.030989ms","start":"2026-04-17T02:37:36.280394Z","end":"2026-04-17T02:37:36.386425Z","steps":["trace[142886806] 'process raft request' (duration: 78.505224ms)","trace[142886806] 'compare' (duration: 27.30026ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:37:37.619252Z","caller":"traceutil/trace.go:171","msg":"trace[1126515230] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"154.38691ms","start":"2026-04-17T02:37:37.464846Z","end":"2026-04-17T02:37:37.619233Z","steps":["trace[1126515230] 'process raft request' (duration: 154.281308ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:37.693822Z","caller":"traceutil/trace.go:171","msg":"trace[2146603535] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"228.15437ms","start":"2026-04-17T02:37:37.465639Z","end":"2026-04-17T02:37:37.693793Z","steps":["trace[2146603535] 'process raft request' (duration: 221.606506ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:38.568634Z","caller":"traceutil/trace.go:171","msg":"trace[1056825586] linearizableReadLoop","detail":"{readStateIndex:2603; appliedIndex:2602; }","duration":"214.696106ms","start":"2026-04-17T02:37:38.353915Z","end":"2026-04-17T02:37:38.568611Z","steps":["trace[1056825586] 'read index received' (duration: 214.49305ms)","trace[1056825586] 'applied index is now lower than readState.Index' (duration: 202.066µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:37:38.568762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.834208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:37:38.568802Z","caller":"traceutil/trace.go:171","msg":"trace[1093569453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2475; }","duration":"214.928281ms","start":"2026-04-17T02:37:38.353861Z","end":"2026-04-17T02:37:38.56879Z","steps":["trace[1093569453] 'agreement among raft nodes before linearized reading' (duration: 214.836649ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:38.569157Z","caller":"traceutil/trace.go:171","msg":"trace[1686459731] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"264.295185ms","start":"2026-04-17T02:37:38.304848Z","end":"2026-04-17T02:37:38.569143Z","steps":["trace[1686459731] 'process raft request' (duration: 263.624051ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:39.388561Z","caller":"traceutil/trace.go:171","msg":"trace[954682255] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"151.125339ms","start":"2026-04-17T02:37:39.237389Z","end":"2026-04-17T02:37:39.388514Z","steps":["trace[954682255] 'process raft request' (duration: 150.880514ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:39.913582Z","caller":"traceutil/trace.go:171","msg":"trace[425795588] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"111.034009ms","start":"2026-04-17T02:37:39.802529Z","end":"2026-04-17T02:37:39.913563Z","steps":["trace[425795588] 'process raft request' (duration: 110.629831ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:41.537955Z","caller":"traceutil/trace.go:171","msg":"trace[1577626079] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"144.036353ms","start":"2026-04-17T02:37:41.393889Z","end":"2026-04-17T02:37:41.537926Z","steps":["trace[1577626079] 'process raft request' (duration: 143.856148ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:44.010578Z","caller":"traceutil/trace.go:171","msg":"trace[1072441182] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"334.500085ms","start":"2026-04-17T02:37:43.676044Z","end":"2026-04-17T02:37:44.010544Z","steps":["trace[1072441182] 'process raft request' (duration: 328.755259ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:44.010614Z","caller":"traceutil/trace.go:171","msg":"trace[141800318] linearizableReadLoop","detail":"{readStateIndex:2618; appliedIndex:2617; }","duration":"323.009523ms","start":"2026-04-17T02:37:43.687577Z","end":"2026-04-17T02:37:44.010586Z","steps":["trace[141800318] 'read index received' (duration: 317.234106ms)","trace[141800318] 'applied index is now lower than readState.Index' (duration: 5.772057ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:37:44.010655Z","caller":"traceutil/trace.go:171","msg":"trace[177589119] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"127.454648ms","start":"2026-04-17T02:37:43.883177Z","end":"2026-04-17T02:37:44.010631Z","steps":["trace[177589119] 'process raft request' (duration: 127.378526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:37:44.01077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:37:43.675879Z","time spent":"334.786282ms","remote":"127.0.0.1:57290","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-17T02:37:44.010854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.255688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.170\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T02:37:44.010896Z","caller":"traceutil/trace.go:171","msg":"trace[665923678] range","detail":"{range_begin:/registry/masterleases/199.19.213.170; range_end:; response_count:1; response_revision:2490; }","duration":"323.3567ms","start":"2026-04-17T02:37:43.687527Z","end":"2026-04-17T02:37:44.010884Z","steps":["trace[665923678] 'agreement among raft nodes before linearized reading' (duration: 323.182857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:37:44.010926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:37:43.687511Z","time spent":"323.407522ms","remote":"127.0.0.1:57082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.170\" "} {"level":"warn","ts":"2026-04-17T02:37:44.011158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.733858ms","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-17T02:37:44.011234Z","caller":"traceutil/trace.go:171","msg":"trace[1159886969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2490; }","duration":"256.901271ms","start":"2026-04-17T02:37:43.754314Z","end":"2026-04-17T02:37:44.011216Z","steps":["trace[1159886969] 'agreement among raft nodes before linearized reading' (duration: 256.488353ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:44.728127Z","caller":"traceutil/trace.go:171","msg":"trace[1960028923] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"110.152878ms","start":"2026-04-17T02:37:44.617955Z","end":"2026-04-17T02:37:44.728108Z","steps":["trace[1960028923] 'process raft request' (duration: 110.031186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:37:46.378895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.165334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T02:37:46.378909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.407029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:37:46.378966Z","caller":"traceutil/trace.go:171","msg":"trace[2125829067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2499; }","duration":"112.276895ms","start":"2026-04-17T02:37:46.266672Z","end":"2026-04-17T02:37:46.378949Z","steps":["trace[2125829067] 'range keys from in-memory index tree' (duration: 112.020911ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:46.379001Z","caller":"traceutil/trace.go:171","msg":"trace[489488271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2499; }","duration":"158.587723ms","start":"2026-04-17T02:37:46.220392Z","end":"2026-04-17T02:37:46.37898Z","steps":["trace[489488271] 'count revisions from in-memory index tree' (duration: 158.294717ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:46.933532Z","caller":"traceutil/trace.go:171","msg":"trace[497433181] linearizableReadLoop","detail":"{readStateIndex:2632; appliedIndex:2631; }","duration":"201.698539ms","start":"2026-04-17T02:37:46.73181Z","end":"2026-04-17T02:37:46.933509Z","steps":["trace[497433181] 'read index received' (duration: 201.482033ms)","trace[497433181] 'applied index is now lower than readState.Index' (duration: 215.716µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:37:46.93373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.902734ms","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-04-17T02:37:46.933761Z","caller":"traceutil/trace.go:171","msg":"trace[1982667696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2502; }","duration":"201.991005ms","start":"2026-04-17T02:37:46.73176Z","end":"2026-04-17T02:37:46.933751Z","steps":["trace[1982667696] 'agreement among raft nodes before linearized reading' (duration: 201.832452ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:46.933739Z","caller":"traceutil/trace.go:171","msg":"trace[674807182] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"208.595382ms","start":"2026-04-17T02:37:46.725079Z","end":"2026-04-17T02:37:46.933674Z","steps":["trace[674807182] 'process raft request' (duration: 208.291085ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:37:47.14271Z","caller":"traceutil/trace.go:171","msg":"trace[1001734315] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"203.095849ms","start":"2026-04-17T02:37:46.939575Z","end":"2026-04-17T02:37:47.142671Z","steps":["trace[1001734315] 'process raft request' (duration: 173.532151ms)","trace[1001734315] 'compare' (duration: 29.416815ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:00.732665Z","caller":"traceutil/trace.go:171","msg":"trace[2070941278] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"126.914427ms","start":"2026-04-17T02:38:00.605723Z","end":"2026-04-17T02:38:00.732638Z","steps":["trace[2070941278] 'process raft request' (duration: 126.751323ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:08.129362Z","caller":"traceutil/trace.go:171","msg":"trace[1250092510] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"298.641452ms","start":"2026-04-17T02:38:07.830703Z","end":"2026-04-17T02:38:08.129345Z","steps":["trace[1250092510] 'process raft request' (duration: 298.532469ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:10.266048Z","caller":"traceutil/trace.go:171","msg":"trace[1170450032] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"131.585355ms","start":"2026-04-17T02:38:10.134431Z","end":"2026-04-17T02:38:10.266016Z","steps":["trace[1170450032] 'process raft request' (duration: 131.342079ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:13.553825Z","caller":"traceutil/trace.go:171","msg":"trace[61139570] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"161.209979ms","start":"2026-04-17T02:38:13.392595Z","end":"2026-04-17T02:38:13.553805Z","steps":["trace[61139570] 'process raft request' (duration: 161.104667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:13.801195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.975537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.170\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T02:38:13.801326Z","caller":"traceutil/trace.go:171","msg":"trace[2134291326] range","detail":"{range_begin:/registry/masterleases/199.19.213.170; range_end:; response_count:1; response_revision:2587; }","duration":"113.142651ms","start":"2026-04-17T02:38:13.688145Z","end":"2026-04-17T02:38:13.801288Z","steps":["trace[2134291326] 'range keys from in-memory index tree' (duration: 112.781333ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:20.647452Z","caller":"traceutil/trace.go:171","msg":"trace[1511652076] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"199.225853ms","start":"2026-04-17T02:38:20.448194Z","end":"2026-04-17T02:38:20.64742Z","steps":["trace[1511652076] 'process raft request' (duration: 135.009318ms)","trace[1511652076] 'compare' (duration: 64.055061ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:20.81432Z","caller":"traceutil/trace.go:171","msg":"trace[146985102] linearizableReadLoop","detail":"{readStateIndex:2749; appliedIndex:2748; }","duration":"147.121738ms","start":"2026-04-17T02:38:20.667171Z","end":"2026-04-17T02:38:20.814293Z","steps":["trace[146985102] 'read index received' (duration: 140.044537ms)","trace[146985102] 'applied index is now lower than readState.Index' (duration: 7.076201ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:20.814451Z","caller":"traceutil/trace.go:171","msg":"trace[92173012] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"150.712175ms","start":"2026-04-17T02:38:20.663705Z","end":"2026-04-17T02:38:20.814417Z","steps":["trace[92173012] 'process raft request' (duration: 143.574202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:20.814504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.316772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:20.814564Z","caller":"traceutil/trace.go:171","msg":"trace[2132434458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2612; }","duration":"147.415065ms","start":"2026-04-17T02:38:20.667136Z","end":"2026-04-17T02:38:20.814551Z","steps":["trace[2132434458] 'agreement among raft nodes before linearized reading' (duration: 147.294102ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:23.857461Z","caller":"traceutil/trace.go:171","msg":"trace[655890680] linearizableReadLoop","detail":"{readStateIndex:2760; appliedIndex:2759; }","duration":"131.774ms","start":"2026-04-17T02:38:23.725651Z","end":"2026-04-17T02:38:23.857425Z","steps":["trace[655890680] 'read index received' (duration: 78.592912ms)","trace[655890680] 'applied index is now lower than readState.Index' (duration: 53.179238ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:23.857573Z","caller":"traceutil/trace.go:171","msg":"trace[1064404375] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"148.932897ms","start":"2026-04-17T02:38:23.708607Z","end":"2026-04-17T02:38:23.85754Z","steps":["trace[1064404375] 'process raft request' (duration: 95.685558ms)","trace[1064404375] 'compare' (duration: 52.787129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:23.857623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.963433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-17T02:38:23.857678Z","caller":"traceutil/trace.go:171","msg":"trace[825781037] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2622; }","duration":"132.062166ms","start":"2026-04-17T02:38:23.725603Z","end":"2026-04-17T02:38:23.857665Z","steps":["trace[825781037] 'agreement among raft nodes before linearized reading' (duration: 131.956573ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:24.744642Z","caller":"traceutil/trace.go:171","msg":"trace[1234376288] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"105.529138ms","start":"2026-04-17T02:38:24.639071Z","end":"2026-04-17T02:38:24.7446Z","steps":["trace[1234376288] 'process raft request' (duration: 105.200041ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:24.923876Z","caller":"traceutil/trace.go:171","msg":"trace[1747319880] transaction","detail":"{read_only:false; response_revision:2625; number_of_response:1; }","duration":"174.81871ms","start":"2026-04-17T02:38:24.749029Z","end":"2026-04-17T02:38:24.923848Z","steps":["trace[1747319880] 'process raft request' (duration: 99.0869ms)","trace[1747319880] 'compare' (duration: 75.529256ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:25.310563Z","caller":"traceutil/trace.go:171","msg":"trace[419597483] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"132.72952ms","start":"2026-04-17T02:38:25.177816Z","end":"2026-04-17T02:38:25.310545Z","steps":["trace[419597483] 'process raft request' (duration: 132.623127ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:26.787222Z","caller":"traceutil/trace.go:171","msg":"trace[48594246] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"304.916614ms","start":"2026-04-17T02:38:26.482272Z","end":"2026-04-17T02:38:26.787189Z","steps":["trace[48594246] 'process raft request' (duration: 304.733061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:26.787383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:26.482243Z","time spent":"305.059438ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:27.046512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.112589ms","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-17T02:38:27.046826Z","caller":"traceutil/trace.go:171","msg":"trace[1538527637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2630; }","duration":"119.282863ms","start":"2026-04-17T02:38:26.927324Z","end":"2026-04-17T02:38:27.046607Z","steps":["trace[1538527637] 'range keys from in-memory index tree' (duration: 118.865114ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:27.240335Z","caller":"traceutil/trace.go:171","msg":"trace[196365224] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"188.745819ms","start":"2026-04-17T02:38:27.051557Z","end":"2026-04-17T02:38:27.240303Z","steps":["trace[196365224] 'process raft request' (duration: 188.602646ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:27.442239Z","caller":"traceutil/trace.go:171","msg":"trace[18058511] linearizableReadLoop","detail":"{readStateIndex:2771; appliedIndex:2769; }","duration":"310.630117ms","start":"2026-04-17T02:38:27.131578Z","end":"2026-04-17T02:38:27.442208Z","steps":["trace[18058511] 'read index received' (duration: 108.728537ms)","trace[18058511] 'applied index is now lower than readState.Index' (duration: 201.90002ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:27.442302Z","caller":"traceutil/trace.go:171","msg":"trace[1931842234] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"390.584698ms","start":"2026-04-17T02:38:27.051663Z","end":"2026-04-17T02:38:27.442248Z","steps":["trace[1931842234] 'process raft request' (duration: 341.602129ms)","trace[1931842234] 'compare' (duration: 48.687853ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:27.442398Z","caller":"traceutil/trace.go:171","msg":"trace[358820585] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"390.666379ms","start":"2026-04-17T02:38:27.051694Z","end":"2026-04-17T02:38:27.44236Z","steps":["trace[358820585] 'process raft request' (duration: 390.453905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:27.442477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.875622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:27.442556Z","caller":"traceutil/trace.go:171","msg":"trace[1253469574] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2633; }","duration":"311.026745ms","start":"2026-04-17T02:38:27.131502Z","end":"2026-04-17T02:38:27.442528Z","steps":["trace[1253469574] 'agreement among raft nodes before linearized reading' (duration: 310.880742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:27.44258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:27.051671Z","time spent":"390.825723ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:27.442596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.107521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T02:38:27.442583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:27.051636Z","time spent":"390.807933ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:27.442607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:27.131473Z","time spent":"311.121487ms","remote":"127.0.0.1:44656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:38:27.442647Z","caller":"traceutil/trace.go:171","msg":"trace[1563160973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2633; }","duration":"128.221264ms","start":"2026-04-17T02:38:27.314414Z","end":"2026-04-17T02:38:27.442635Z","steps":["trace[1563160973] 'agreement among raft nodes before linearized reading' (duration: 128.000839ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:27.963444Z","caller":"traceutil/trace.go:171","msg":"trace[498941340] linearizableReadLoop","detail":"{readStateIndex:2773; appliedIndex:2772; }","duration":"198.543628ms","start":"2026-04-17T02:38:27.764875Z","end":"2026-04-17T02:38:27.963419Z","steps":["trace[498941340] 'read index received' (duration: 198.423806ms)","trace[498941340] 'applied index is now lower than readState.Index' (duration: 118.222µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:27.963471Z","caller":"traceutil/trace.go:171","msg":"trace[293299326] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"278.525939ms","start":"2026-04-17T02:38:27.684926Z","end":"2026-04-17T02:38:27.963452Z","steps":["trace[293299326] 'process raft request' (duration: 278.322275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:27.963676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.777653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:38:27.963718Z","caller":"traceutil/trace.go:171","msg":"trace[1584794482] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2635; }","duration":"198.868905ms","start":"2026-04-17T02:38:27.764841Z","end":"2026-04-17T02:38:27.96371Z","steps":["trace[1584794482] 'agreement among raft nodes before linearized reading' (duration: 198.722152ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:29.081355Z","caller":"traceutil/trace.go:171","msg":"trace[866224095] linearizableReadLoop","detail":"{readStateIndex:2776; appliedIndex:2775; }","duration":"138.314109ms","start":"2026-04-17T02:38:28.943008Z","end":"2026-04-17T02:38:29.081322Z","steps":["trace[866224095] 'read index received' (duration: 68.961545ms)","trace[866224095] 'applied index is now lower than readState.Index' (duration: 69.349884ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:29.081483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.465942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:38:29.081534Z","caller":"traceutil/trace.go:171","msg":"trace[2101534124] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2637; }","duration":"138.567165ms","start":"2026-04-17T02:38:28.942955Z","end":"2026-04-17T02:38:29.081522Z","steps":["trace[2101534124] 'agreement among raft nodes before linearized reading' (duration: 138.463542ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:29.662196Z","caller":"traceutil/trace.go:171","msg":"trace[658364505] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2777; }","duration":"196.809901ms","start":"2026-04-17T02:38:29.465358Z","end":"2026-04-17T02:38:29.662167Z","steps":["trace[658364505] 'read index received' (duration: 77.861726ms)","trace[658364505] 'applied index is now lower than readState.Index' (duration: 118.947105ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:29.662264Z","caller":"traceutil/trace.go:171","msg":"trace[193373806] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"211.025495ms","start":"2026-04-17T02:38:29.451207Z","end":"2026-04-17T02:38:29.662233Z","steps":["trace[193373806] 'process raft request' (duration: 171.966269ms)","trace[193373806] 'compare' (duration: 38.857612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:29.662436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.068827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:38:29.662481Z","caller":"traceutil/trace.go:171","msg":"trace[116020021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2640; }","duration":"197.167739ms","start":"2026-04-17T02:38:29.465299Z","end":"2026-04-17T02:38:29.662467Z","steps":["trace[116020021] 'agreement among raft nodes before linearized reading' (duration: 196.978675ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:31.401957Z","caller":"traceutil/trace.go:171","msg":"trace[913341952] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"107.601832ms","start":"2026-04-17T02:38:31.294317Z","end":"2026-04-17T02:38:31.401919Z","steps":["trace[913341952] 'process raft request' (duration: 107.384238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:31.991281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.668807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:38:31.991388Z","caller":"traceutil/trace.go:171","msg":"trace[1746066533] linearizableReadLoop","detail":"{readStateIndex:2785; appliedIndex:2784; }","duration":"263.165351ms","start":"2026-04-17T02:38:31.728207Z","end":"2026-04-17T02:38:31.991373Z","steps":["trace[1746066533] 'read index received' (duration: 96.330101ms)","trace[1746066533] 'applied index is now lower than readState.Index' (duration: 166.83413ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:31.991418Z","caller":"traceutil/trace.go:171","msg":"trace[746969264] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"318.465935ms","start":"2026-04-17T02:38:31.672927Z","end":"2026-04-17T02:38:31.991393Z","steps":["trace[746969264] 'process raft request' (duration: 151.601964ms)","trace[746969264] 'compare' (duration: 166.538384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:31.991525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.318214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T02:38:31.991532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:31.672886Z","time spent":"318.580577ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:38:31.991561Z","caller":"traceutil/trace.go:171","msg":"trace[1093420689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2646; }","duration":"263.388136ms","start":"2026-04-17T02:38:31.728163Z","end":"2026-04-17T02:38:31.991551Z","steps":["trace[1093420689] 'agreement among raft nodes before linearized reading' (duration: 263.254513ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:32.322562Z","caller":"traceutil/trace.go:171","msg":"trace[1760291157] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2785; }","duration":"291.1599ms","start":"2026-04-17T02:38:32.031366Z","end":"2026-04-17T02:38:32.322526Z","steps":["trace[1760291157] 'read index received' (duration: 241.709281ms)","trace[1760291157] 'applied index is now lower than readState.Index' (duration: 49.448959ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:32.322655Z","caller":"traceutil/trace.go:171","msg":"trace[1984811702] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"326.66655ms","start":"2026-04-17T02:38:31.995953Z","end":"2026-04-17T02:38:32.322619Z","steps":["trace[1984811702] 'process raft request' (duration: 277.211141ms)","trace[1984811702] 'compare' (duration: 48.962238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:32.322797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:31.995911Z","time spent":"326.808543ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:32.32287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.479906ms","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-17T02:38:32.322909Z","caller":"traceutil/trace.go:171","msg":"trace[1126124153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2647; }","duration":"291.581939ms","start":"2026-04-17T02:38:32.031314Z","end":"2026-04-17T02:38:32.322896Z","steps":["trace[1126124153] 'agreement among raft nodes before linearized reading' (duration: 291.400765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:32.322959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.814738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:38:32.323005Z","caller":"traceutil/trace.go:171","msg":"trace[1482335499] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2647; }","duration":"165.90298ms","start":"2026-04-17T02:38:32.157087Z","end":"2026-04-17T02:38:32.32299Z","steps":["trace[1482335499] 'agreement among raft nodes before linearized reading' (duration: 165.832058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:32.594067Z","caller":"traceutil/trace.go:171","msg":"trace[1332975928] linearizableReadLoop","detail":"{readStateIndex:2787; appliedIndex:2786; }","duration":"239.491804ms","start":"2026-04-17T02:38:32.354547Z","end":"2026-04-17T02:38:32.594039Z","steps":["trace[1332975928] 'read index received' (duration: 149.168052ms)","trace[1332975928] 'applied index is now lower than readState.Index' (duration: 90.322802ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:32.594174Z","caller":"traceutil/trace.go:171","msg":"trace[533871937] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"266.711566ms","start":"2026-04-17T02:38:32.327403Z","end":"2026-04-17T02:38:32.594115Z","steps":["trace[533871937] 'process raft request' (duration: 176.386124ms)","trace[533871937] 'compare' (duration: 90.114068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:32.594328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.74234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:32.594414Z","caller":"traceutil/trace.go:171","msg":"trace[720601501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2648; }","duration":"239.901073ms","start":"2026-04-17T02:38:32.354497Z","end":"2026-04-17T02:38:32.594399Z","steps":["trace[720601501] 'agreement among raft nodes before linearized reading' (duration: 239.719649ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:33.051861Z","caller":"traceutil/trace.go:171","msg":"trace[452266494] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"107.9718ms","start":"2026-04-17T02:38:32.943864Z","end":"2026-04-17T02:38:33.051835Z","steps":["trace[452266494] 'process raft request' (duration: 107.794086ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:34.074569Z","caller":"traceutil/trace.go:171","msg":"trace[991652595] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"347.937695ms","start":"2026-04-17T02:38:33.726614Z","end":"2026-04-17T02:38:34.074552Z","steps":["trace[991652595] 'process raft request' (duration: 333.252691ms)","trace[991652595] 'compare' (duration: 14.4881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:34.074684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:33.72658Z","time spent":"348.043507ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:38:34.074727Z","caller":"traceutil/trace.go:171","msg":"trace[2058192662] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"179.713345ms","start":"2026-04-17T02:38:33.894987Z","end":"2026-04-17T02:38:34.0747Z","steps":["trace[2058192662] 'process raft request' (duration: 179.525151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:34.510549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.349673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:34.510635Z","caller":"traceutil/trace.go:171","msg":"trace[461420651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2653; }","duration":"155.476276ms","start":"2026-04-17T02:38:34.355138Z","end":"2026-04-17T02:38:34.510615Z","steps":["trace[461420651] 'range keys from in-memory index tree' (duration: 155.278202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:34.51082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.243141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:38:34.51093Z","caller":"traceutil/trace.go:171","msg":"trace[203942558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2653; }","duration":"183.411984ms","start":"2026-04-17T02:38:34.327499Z","end":"2026-04-17T02:38:34.510911Z","steps":["trace[203942558] 'range keys from in-memory index tree' (duration: 183.094127ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:34.639851Z","caller":"traceutil/trace.go:171","msg":"trace[50772667] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"123.719567ms","start":"2026-04-17T02:38:34.516095Z","end":"2026-04-17T02:38:34.639814Z","steps":["trace[50772667] 'process raft request' (duration: 123.41324ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:35.021582Z","caller":"traceutil/trace.go:171","msg":"trace[754775818] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"123.979663ms","start":"2026-04-17T02:38:34.897575Z","end":"2026-04-17T02:38:35.021555Z","steps":["trace[754775818] 'process raft request' (duration: 123.817679ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:35.335468Z","caller":"traceutil/trace.go:171","msg":"trace[679306392] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2796; }","duration":"261.556757ms","start":"2026-04-17T02:38:35.073888Z","end":"2026-04-17T02:38:35.335444Z","steps":["trace[679306392] 'read index received' (duration: 240.844663ms)","trace[679306392] 'applied index is now lower than readState.Index' (duration: 20.711464ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:35.335508Z","caller":"traceutil/trace.go:171","msg":"trace[990196085] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"278.419638ms","start":"2026-04-17T02:38:35.057051Z","end":"2026-04-17T02:38:35.335471Z","steps":["trace[990196085] 'process raft request' (duration: 257.620643ms)","trace[990196085] 'compare' (duration: 20.667742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:35.335604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.68999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:38:35.335632Z","caller":"traceutil/trace.go:171","msg":"trace[1971646128] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2657; }","duration":"261.790713ms","start":"2026-04-17T02:38:35.073832Z","end":"2026-04-17T02:38:35.335623Z","steps":["trace[1971646128] 'agreement among raft nodes before linearized reading' (duration: 261.704321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:35.599764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.112425ms","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-17T02:38:35.599844Z","caller":"traceutil/trace.go:171","msg":"trace[169707219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2657; }","duration":"116.250218ms","start":"2026-04-17T02:38:35.483575Z","end":"2026-04-17T02:38:35.599825Z","steps":["trace[169707219] 'range keys from in-memory index tree' (duration: 115.927871ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:35.766008Z","caller":"traceutil/trace.go:171","msg":"trace[1570020925] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"161.756031ms","start":"2026-04-17T02:38:35.604216Z","end":"2026-04-17T02:38:35.765972Z","steps":["trace[1570020925] 'process raft request' (duration: 161.560207ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:36.907029Z","caller":"traceutil/trace.go:171","msg":"trace[1967372370] linearizableReadLoop","detail":"{readStateIndex:2801; appliedIndex:2800; }","duration":"229.383079ms","start":"2026-04-17T02:38:36.677622Z","end":"2026-04-17T02:38:36.907005Z","steps":["trace[1967372370] 'read index received' (duration: 229.233605ms)","trace[1967372370] 'applied index is now lower than readState.Index' (duration: 148.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:36.907083Z","caller":"traceutil/trace.go:171","msg":"trace[1021873688] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"254.068517ms","start":"2026-04-17T02:38:36.652982Z","end":"2026-04-17T02:38:36.907051Z","steps":["trace[1021873688] 'process raft request' (duration: 253.884292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:36.907212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.580413ms","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-04-17T02:38:36.907249Z","caller":"traceutil/trace.go:171","msg":"trace[1051625386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2661; }","duration":"229.673415ms","start":"2026-04-17T02:38:36.677567Z","end":"2026-04-17T02:38:36.90724Z","steps":["trace[1051625386] 'agreement among raft nodes before linearized reading' (duration: 229.529992ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:37.012538Z","caller":"traceutil/trace.go:171","msg":"trace[1777017659] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"100.084731ms","start":"2026-04-17T02:38:36.912417Z","end":"2026-04-17T02:38:37.012502Z","steps":["trace[1777017659] 'process raft request' (duration: 59.82006ms)","trace[1777017659] 'compare' (duration: 40.123678ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:40.568875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1693} {"level":"info","ts":"2026-04-17T02:38:40.615627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1693,"took":"46.176158ms","hash":2851334333,"current-db-size-bytes":23764992,"current-db-size":"24 MB","current-db-size-in-use-bytes":5574656,"current-db-size-in-use":"5.6 MB"} {"level":"info","ts":"2026-04-17T02:38:40.615683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2851334333,"revision":1693,"compact-revision":-1} {"level":"info","ts":"2026-04-17T02:38:45.606638Z","caller":"traceutil/trace.go:171","msg":"trace[870719286] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"225.177708ms","start":"2026-04-17T02:38:45.38143Z","end":"2026-04-17T02:38:45.606608Z","steps":["trace[870719286] 'process raft request' (duration: 224.994704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:45.98523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.69373ms","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-17T02:38:45.985662Z","caller":"traceutil/trace.go:171","msg":"trace[1516475495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2691; }","duration":"139.904413ms","start":"2026-04-17T02:38:45.845475Z","end":"2026-04-17T02:38:45.98538Z","steps":["trace[1516475495] 'range keys from in-memory index tree' (duration: 139.346202ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:46.360888Z","caller":"traceutil/trace.go:171","msg":"trace[497123671] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2834; }","duration":"218.029345ms","start":"2026-04-17T02:38:46.14282Z","end":"2026-04-17T02:38:46.360849Z","steps":["trace[497123671] 'read index received' (duration: 217.734749ms)","trace[497123671] 'applied index is now lower than readState.Index' (duration: 293.346µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:46.360943Z","caller":"traceutil/trace.go:171","msg":"trace[345862723] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"370.492077ms","start":"2026-04-17T02:38:45.990394Z","end":"2026-04-17T02:38:46.360887Z","steps":["trace[345862723] 'process raft request' (duration: 370.249472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:46.36114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.303331ms","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-17T02:38:46.361162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:45.990359Z","time spent":"370.692102ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:38:46.361207Z","caller":"traceutil/trace.go:171","msg":"trace[601159276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2692; }","duration":"218.375933ms","start":"2026-04-17T02:38:46.142816Z","end":"2026-04-17T02:38:46.361191Z","steps":["trace[601159276] 'agreement among raft nodes before linearized reading' (duration: 218.170838ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:47.029135Z","caller":"traceutil/trace.go:171","msg":"trace[435633054] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"661.497713ms","start":"2026-04-17T02:38:46.367604Z","end":"2026-04-17T02:38:47.029102Z","steps":["trace[435633054] 'process raft request' (duration: 661.281789ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:47.029135Z","caller":"traceutil/trace.go:171","msg":"trace[2032020433] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"662.395474ms","start":"2026-04-17T02:38:46.366688Z","end":"2026-04-17T02:38:47.029084Z","steps":["trace[2032020433] 'process raft request' (duration: 645.725117ms)","trace[2032020433] 'compare' (duration: 15.923501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:38:47.029294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:46.367579Z","time spent":"661.638807ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:47.029378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:46.366662Z","time spent":"662.582137ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:47.674541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.526276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:47.674663Z","caller":"traceutil/trace.go:171","msg":"trace[546572001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2696; }","duration":"318.72194ms","start":"2026-04-17T02:38:47.355916Z","end":"2026-04-17T02:38:47.674638Z","steps":["trace[546572001] 'range keys from in-memory index tree' (duration: 318.391682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:47.674859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.355882Z","time spent":"318.879133ms","remote":"127.0.0.1:57038","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-17T02:38:47.674889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.26546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:47.675011Z","caller":"traceutil/trace.go:171","msg":"trace[1252966298] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2696; }","duration":"540.419284ms","start":"2026-04-17T02:38:47.134545Z","end":"2026-04-17T02:38:47.674964Z","steps":["trace[1252966298] 'count revisions from in-memory index tree' (duration: 540.161569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:47.675071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.134526Z","time spent":"540.531457ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:38:48.018954Z","caller":"traceutil/trace.go:171","msg":"trace[1116538009] linearizableReadLoop","detail":"{readStateIndex:2840; appliedIndex:2839; }","duration":"342.196642ms","start":"2026-04-17T02:38:47.676731Z","end":"2026-04-17T02:38:48.018928Z","steps":["trace[1116538009] 'read index received' (duration: 341.949636ms)","trace[1116538009] 'applied index is now lower than readState.Index' (duration: 246.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:48.019015Z","caller":"traceutil/trace.go:171","msg":"trace[1627245717] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"406.155201ms","start":"2026-04-17T02:38:47.61282Z","end":"2026-04-17T02:38:48.018976Z","steps":["trace[1627245717] 'process raft request' (duration: 405.947466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:48.019136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.380835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:48.019181Z","caller":"traceutil/trace.go:171","msg":"trace[778412268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2697; }","duration":"342.486368ms","start":"2026-04-17T02:38:47.676683Z","end":"2026-04-17T02:38:48.019169Z","steps":["trace[778412268] 'agreement among raft nodes before linearized reading' (duration: 342.400126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:48.019213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.612793Z","time spent":"406.296783ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:38:48.019218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:38:47.676661Z","time spent":"342.548999ms","remote":"127.0.0.1:57050","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-17T02:38:48.019504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.016193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:38:48.019637Z","caller":"traceutil/trace.go:171","msg":"trace[1375556505] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2697; }","duration":"153.245648ms","start":"2026-04-17T02:38:47.866371Z","end":"2026-04-17T02:38:48.019617Z","steps":["trace[1375556505] 'agreement among raft nodes before linearized reading' (duration: 152.919261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:48.542304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.269062ms","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-17T02:38:48.542528Z","caller":"traceutil/trace.go:171","msg":"trace[1976828729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2697; }","duration":"176.565708ms","start":"2026-04-17T02:38:48.365927Z","end":"2026-04-17T02:38:48.542493Z","steps":["trace[1976828729] 'range keys from in-memory index tree' (duration: 176.084908ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:48.545654Z","caller":"traceutil/trace.go:171","msg":"trace[678695254] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"148.425466ms","start":"2026-04-17T02:38:48.397202Z","end":"2026-04-17T02:38:48.545628Z","steps":["trace[678695254] 'process raft request' (duration: 148.280072ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:53.229375Z","caller":"traceutil/trace.go:171","msg":"trace[1403331258] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"167.221629ms","start":"2026-04-17T02:38:53.06213Z","end":"2026-04-17T02:38:53.229352Z","steps":["trace[1403331258] 'process raft request' (duration: 167.046545ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:53.231381Z","caller":"traceutil/trace.go:171","msg":"trace[386390084] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"119.732422ms","start":"2026-04-17T02:38:53.111619Z","end":"2026-04-17T02:38:53.231352Z","steps":["trace[386390084] 'read index received' (duration: 117.736459ms)","trace[386390084] 'applied index is now lower than readState.Index' (duration: 1.994483ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:38:53.231453Z","caller":"traceutil/trace.go:171","msg":"trace[517675114] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"166.010992ms","start":"2026-04-17T02:38:53.065423Z","end":"2026-04-17T02:38:53.231434Z","steps":["trace[517675114] 'process raft request' (duration: 165.763166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:38:53.231715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.023329ms","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-04-17T02:38:53.231858Z","caller":"traceutil/trace.go:171","msg":"trace[1331566300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2714; }","duration":"120.250243ms","start":"2026-04-17T02:38:53.111572Z","end":"2026-04-17T02:38:53.231822Z","steps":["trace[1331566300] 'agreement among raft nodes before linearized reading' (duration: 119.902095ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:38:58.837698Z","caller":"traceutil/trace.go:171","msg":"trace[1935584881] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"158.291327ms","start":"2026-04-17T02:38:58.679371Z","end":"2026-04-17T02:38:58.837662Z","steps":["trace[1935584881] 'process raft request' (duration: 158.059912ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:10.302795Z","caller":"traceutil/trace.go:171","msg":"trace[1332984670] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"197.071674ms","start":"2026-04-17T02:39:10.105698Z","end":"2026-04-17T02:39:10.30277Z","steps":["trace[1332984670] 'process raft request' (duration: 196.930821ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:11.02712Z","caller":"traceutil/trace.go:171","msg":"trace[202393959] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"129.10422ms","start":"2026-04-17T02:39:10.897993Z","end":"2026-04-17T02:39:11.027097Z","steps":["trace[202393959] 'process raft request' (duration: 128.959157ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:13.755248Z","caller":"traceutil/trace.go:171","msg":"trace[274999126] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"119.472064ms","start":"2026-04-17T02:39:13.635739Z","end":"2026-04-17T02:39:13.755211Z","steps":["trace[274999126] 'process raft request' (duration: 119.30685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:13.922212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.120762ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-17T02:39:13.922292Z","caller":"traceutil/trace.go:171","msg":"trace[1280159555] linearizableReadLoop","detail":"{readStateIndex:2931; appliedIndex:2930; }","duration":"144.05024ms","start":"2026-04-17T02:39:13.778227Z","end":"2026-04-17T02:39:13.922277Z","steps":["trace[1280159555] 'read index received' (duration: 35.776065ms)","trace[1280159555] 'applied index is now lower than readState.Index' (duration: 108.273335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:13.922383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.117561ms","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-17T02:39:13.922419Z","caller":"traceutil/trace.go:171","msg":"trace[2103671360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2782; }","duration":"144.207914ms","start":"2026-04-17T02:39:13.778201Z","end":"2026-04-17T02:39:13.922409Z","steps":["trace[2103671360] 'agreement among raft nodes before linearized reading' (duration: 144.114332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:20.047007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.71942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:20.047106Z","caller":"traceutil/trace.go:171","msg":"trace[142266336] linearizableReadLoop","detail":"{readStateIndex:2953; appliedIndex:2952; }","duration":"265.599009ms","start":"2026-04-17T02:39:19.781491Z","end":"2026-04-17T02:39:20.04709Z","steps":["trace[142266336] 'read index received' (duration: 34.291µs)","trace[142266336] 'applied index is now lower than readState.Index' (duration: 265.563468ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:20.047231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.744692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:39:20.047222Z","caller":"traceutil/trace.go:171","msg":"trace[1577243753] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"439.965197ms","start":"2026-04-17T02:39:19.607225Z","end":"2026-04-17T02:39:20.04719Z","steps":["trace[1577243753] 'process raft request' (duration: 167.962291ms)","trace[1577243753] 'compare' (duration: 271.604988ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:20.047269Z","caller":"traceutil/trace.go:171","msg":"trace[365904270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2803; }","duration":"265.813814ms","start":"2026-04-17T02:39:19.781446Z","end":"2026-04-17T02:39:20.04726Z","steps":["trace[365904270] 'agreement among raft nodes before linearized reading' (duration: 265.684781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:20.047379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:19.607209Z","time spent":"440.073889ms","remote":"127.0.0.1:57290","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-17T02:39:25.318573Z","caller":"traceutil/trace.go:171","msg":"trace[760664980] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"177.475474ms","start":"2026-04-17T02:39:25.141073Z","end":"2026-04-17T02:39:25.318548Z","steps":["trace[760664980] 'process raft request' (duration: 177.349081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:25.684735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.108851ms","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-17T02:39:25.684815Z","caller":"traceutil/trace.go:171","msg":"trace[1894559188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2819; }","duration":"165.233253ms","start":"2026-04-17T02:39:25.519564Z","end":"2026-04-17T02:39:25.684797Z","steps":["trace[1894559188] 'range keys from in-memory index tree' (duration: 164.869965ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:25.871577Z","caller":"traceutil/trace.go:171","msg":"trace[1147141335] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"182.635425ms","start":"2026-04-17T02:39:25.688901Z","end":"2026-04-17T02:39:25.871537Z","steps":["trace[1147141335] 'process raft request' (duration: 182.40409ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:26.077987Z","caller":"traceutil/trace.go:171","msg":"trace[1863976340] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"388.764143ms","start":"2026-04-17T02:39:25.689193Z","end":"2026-04-17T02:39:26.077957Z","steps":["trace[1863976340] 'process raft request' (duration: 355.095083ms)","trace[1863976340] 'compare' (duration: 33.397704ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:26.078177Z","caller":"traceutil/trace.go:171","msg":"trace[773829588] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"388.364144ms","start":"2026-04-17T02:39:25.689796Z","end":"2026-04-17T02:39:26.078161Z","steps":["trace[773829588] 'process raft request' (duration: 388.108119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:26.078294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:25.689775Z","time spent":"388.447756ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:26.078467Z","caller":"traceutil/trace.go:171","msg":"trace[730244646] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"358.74264ms","start":"2026-04-17T02:39:25.719713Z","end":"2026-04-17T02:39:26.078455Z","steps":["trace[730244646] 'process raft request' (duration: 358.392913ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:26.07847Z","caller":"traceutil/trace.go:171","msg":"trace[1321061356] linearizableReadLoop","detail":"{readStateIndex:2974; appliedIndex:2971; }","duration":"113.981587ms","start":"2026-04-17T02:39:25.964462Z","end":"2026-04-17T02:39:26.078444Z","steps":["trace[1321061356] 'read index received' (duration: 79.713194ms)","trace[1321061356] 'applied index is now lower than readState.Index' (duration: 34.266553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:26.078529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:25.719702Z","time spent":"358.794741ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:26.078606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.129331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:39:26.078652Z","caller":"traceutil/trace.go:171","msg":"trace[1299996653] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2823; }","duration":"114.252333ms","start":"2026-04-17T02:39:25.964389Z","end":"2026-04-17T02:39:26.078641Z","steps":["trace[1299996653] 'agreement among raft nodes before linearized reading' (duration: 114.142821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:26.078493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:25.689181Z","time spent":"388.885955ms","remote":"127.0.0.1:57290","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-17T02:39:26.612065Z","caller":"traceutil/trace.go:171","msg":"trace[1316987825] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"500.009432ms","start":"2026-04-17T02:39:26.11203Z","end":"2026-04-17T02:39:26.612039Z","steps":["trace[1316987825] 'process raft request' (duration: 499.843388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:26.612213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:26.112009Z","time spent":"500.130644ms","remote":"127.0.0.1:57290","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-17T02:39:26.61309Z","caller":"traceutil/trace.go:171","msg":"trace[1371574411] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"239.51767ms","start":"2026-04-17T02:39:26.373549Z","end":"2026-04-17T02:39:26.613067Z","steps":["trace[1371574411] 'process raft request' (duration: 239.393208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:32.005683Z","caller":"traceutil/trace.go:171","msg":"trace[1853978556] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"105.134438ms","start":"2026-04-17T02:39:31.900516Z","end":"2026-04-17T02:39:32.00565Z","steps":["trace[1853978556] 'process raft request' (duration: 104.993305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:32.356517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.624184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T02:39:32.356534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.118288ms","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-17T02:39:32.356596Z","caller":"traceutil/trace.go:171","msg":"trace[2048937970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2840; }","duration":"254.756477ms","start":"2026-04-17T02:39:32.101823Z","end":"2026-04-17T02:39:32.35658Z","steps":["trace[2048937970] 'range keys from in-memory index tree' (duration: 254.41505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:32.356616Z","caller":"traceutil/trace.go:171","msg":"trace[8532047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2840; }","duration":"250.283451ms","start":"2026-04-17T02:39:32.106314Z","end":"2026-04-17T02:39:32.356598Z","steps":["trace[8532047] 'range keys from in-memory index tree' (duration: 249.912514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:32.356732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.95679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:32.356794Z","caller":"traceutil/trace.go:171","msg":"trace[1578302006] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2840; }","duration":"175.085104ms","start":"2026-04-17T02:39:32.181694Z","end":"2026-04-17T02:39:32.356779Z","steps":["trace[1578302006] 'count revisions from in-memory index tree' (duration: 174.867348ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:32.634899Z","caller":"traceutil/trace.go:171","msg":"trace[212032149] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"274.152621ms","start":"2026-04-17T02:39:32.360722Z","end":"2026-04-17T02:39:32.634875Z","steps":["trace[212032149] 'process raft request' (duration: 274.002768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:32.990042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.73274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:32.99021Z","caller":"traceutil/trace.go:171","msg":"trace[1862133518] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"346.673592ms","start":"2026-04-17T02:39:32.643519Z","end":"2026-04-17T02:39:32.990193Z","steps":["trace[1862133518] 'process raft request' (duration: 346.619321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:32.990299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:32.643493Z","time spent":"346.751073ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:32.99037Z","caller":"traceutil/trace.go:171","msg":"trace[2059139246] linearizableReadLoop","detail":"{readStateIndex:2994; appliedIndex:2992; }","duration":"357.538894ms","start":"2026-04-17T02:39:32.632772Z","end":"2026-04-17T02:39:32.99031Z","steps":["trace[2059139246] 'read index received' (duration: 2.022054ms)","trace[2059139246] 'applied index is now lower than readState.Index' (duration: 355.51357ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:32.990401Z","caller":"traceutil/trace.go:171","msg":"trace[1047543161] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"628.333635ms","start":"2026-04-17T02:39:32.362027Z","end":"2026-04-17T02:39:32.99036Z","steps":["trace[1047543161] 'process raft request' (duration: 385.197696ms)","trace[1047543161] 'compare' (duration: 242.619838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:32.990513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.727149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:32.990578Z","caller":"traceutil/trace.go:171","msg":"trace[1146700504] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2843; }","duration":"357.850542ms","start":"2026-04-17T02:39:32.63271Z","end":"2026-04-17T02:39:32.990561Z","steps":["trace[1146700504] 'agreement among raft nodes before linearized reading' (duration: 357.719089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:32.990584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:32.361995Z","time spent":"628.495329ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:32.990607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:32.632687Z","time spent":"357.913533ms","remote":"127.0.0.1:44604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-17T02:39:32.990656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.680165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:39:32.990699Z","caller":"traceutil/trace.go:171","msg":"trace[146583011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2843; }","duration":"340.785647ms","start":"2026-04-17T02:39:32.649902Z","end":"2026-04-17T02:39:32.990688Z","steps":["trace[146583011] 'agreement among raft nodes before linearized reading' (duration: 340.597333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:32.990742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:32.649893Z","time spent":"340.841378ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T02:39:38.140434Z","caller":"traceutil/trace.go:171","msg":"trace[1672477810] transaction","detail":"{read_only:false; response_revision:2860; number_of_response:1; }","duration":"100.170892ms","start":"2026-04-17T02:39:38.040226Z","end":"2026-04-17T02:39:38.140397Z","steps":["trace[1672477810] 'process raft request' (duration: 99.955977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:38.941528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.712527ms","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-17T02:39:38.941626Z","caller":"traceutil/trace.go:171","msg":"trace[788635055] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2861; }","duration":"175.85049ms","start":"2026-04-17T02:39:38.765742Z","end":"2026-04-17T02:39:38.941592Z","steps":["trace[788635055] 'count revisions from in-memory index tree' (duration: 175.635495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:39.418056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.14808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:39.418669Z","caller":"traceutil/trace.go:171","msg":"trace[1194337072] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"392.576044ms","start":"2026-04-17T02:39:39.026059Z","end":"2026-04-17T02:39:39.418635Z","steps":["trace[1194337072] 'process raft request' (duration: 162.76045ms)","trace[1194337072] 'compare' (duration: 229.045578ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:39.418836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:39.026044Z","time spent":"392.703107ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:39.420218Z","caller":"traceutil/trace.go:171","msg":"trace[214389116] linearizableReadLoop","detail":"{readStateIndex:3017; appliedIndex:3015; }","duration":"383.342557ms","start":"2026-04-17T02:39:39.036856Z","end":"2026-04-17T02:39:39.420199Z","steps":["trace[214389116] 'read index received' (duration: 151.984719ms)","trace[214389116] 'applied index is now lower than readState.Index' (duration: 231.356628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:39.420473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.606683ms","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-04-17T02:39:39.420535Z","caller":"traceutil/trace.go:171","msg":"trace[2043205525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2863; }","duration":"383.712574ms","start":"2026-04-17T02:39:39.036809Z","end":"2026-04-17T02:39:39.420521Z","steps":["trace[2043205525] 'agreement among raft nodes before linearized reading' (duration: 383.49949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:39.420561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:39.036787Z","time spent":"383.769346ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T02:39:45.398491Z","caller":"traceutil/trace.go:171","msg":"trace[365975938] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"359.802904ms","start":"2026-04-17T02:39:45.038656Z","end":"2026-04-17T02:39:45.398459Z","steps":["trace[365975938] 'process raft request' (duration: 359.59128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:45.398692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:45.038634Z","time spent":"359.967387ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:45.639592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.891627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T02:39:45.63971Z","caller":"traceutil/trace.go:171","msg":"trace[1163974400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2882; }","duration":"173.053391ms","start":"2026-04-17T02:39:45.46663Z","end":"2026-04-17T02:39:45.639683Z","steps":["trace[1163974400] 'range keys from in-memory index tree' (duration: 172.774974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:45.639754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.017638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T02:39:45.63976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.693891ms","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-17T02:39:45.639848Z","caller":"traceutil/trace.go:171","msg":"trace[1379260741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2882; }","duration":"172.181002ms","start":"2026-04-17T02:39:45.467646Z","end":"2026-04-17T02:39:45.639827Z","steps":["trace[1379260741] 'range keys from in-memory index tree' (duration: 171.867574ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:45.639896Z","caller":"traceutil/trace.go:171","msg":"trace[111612364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2882; }","duration":"178.867955ms","start":"2026-04-17T02:39:45.461001Z","end":"2026-04-17T02:39:45.639869Z","steps":["trace[111612364] 'range keys from in-memory index tree' (duration: 178.565978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:51.275745Z","caller":"traceutil/trace.go:171","msg":"trace[1719573175] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"451.472683ms","start":"2026-04-17T02:39:50.824241Z","end":"2026-04-17T02:39:51.275714Z","steps":["trace[1719573175] 'process raft request' (duration: 451.287909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.275937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:50.824222Z","time spent":"451.616577ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:51.955158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.679763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:39:51.955332Z","caller":"traceutil/trace.go:171","msg":"trace[850983460] linearizableReadLoop","detail":"{readStateIndex:3059; appliedIndex:3057; }","duration":"390.833976ms","start":"2026-04-17T02:39:51.564481Z","end":"2026-04-17T02:39:51.955315Z","steps":["trace[850983460] 'read index received' (duration: 79.703714ms)","trace[850983460] 'applied index is now lower than readState.Index' (duration: 311.129412ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:51.955429Z","caller":"traceutil/trace.go:171","msg":"trace[1339752695] transaction","detail":"{read_only:false; response_revision:2903; number_of_response:1; }","duration":"525.008405ms","start":"2026-04-17T02:39:51.430395Z","end":"2026-04-17T02:39:51.955404Z","steps":["trace[1339752695] 'process raft request' (duration: 524.859472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.955488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.00392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:39:51.955522Z","caller":"traceutil/trace.go:171","msg":"trace[1394276714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2903; }","duration":"391.075642ms","start":"2026-04-17T02:39:51.564437Z","end":"2026-04-17T02:39:51.955513Z","steps":["trace[1394276714] 'agreement among raft nodes before linearized reading' (duration: 391.020651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.955551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.564416Z","time spent":"391.128942ms","remote":"127.0.0.1:57038","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-17T02:39:51.955681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.652633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T02:39:51.955724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.588221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:39:51.955705Z","caller":"traceutil/trace.go:171","msg":"trace[1705885423] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"908.659249ms","start":"2026-04-17T02:39:51.046669Z","end":"2026-04-17T02:39:51.955328Z","steps":["trace[1705885423] 'process raft request' (duration: 503.70412ms)","trace[1705885423] 'compare' (duration: 404.331246ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:51.955751Z","caller":"traceutil/trace.go:171","msg":"trace[735814630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2903; }","duration":"259.768354ms","start":"2026-04-17T02:39:51.695963Z","end":"2026-04-17T02:39:51.955731Z","steps":["trace[735814630] 'agreement among raft nodes before linearized reading' (duration: 259.567979ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:51.955779Z","caller":"traceutil/trace.go:171","msg":"trace[460936097] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2903; }","duration":"244.671232ms","start":"2026-04-17T02:39:51.711092Z","end":"2026-04-17T02:39:51.955764Z","steps":["trace[460936097] 'agreement among raft nodes before linearized reading' (duration: 244.491467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.955764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.122158ms","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-17T02:39:51.955846Z","caller":"traceutil/trace.go:171","msg":"trace[502792544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2903; }","duration":"257.2334ms","start":"2026-04-17T02:39:51.698593Z","end":"2026-04-17T02:39:51.955826Z","steps":["trace[502792544] 'agreement among raft nodes before linearized reading' (duration: 256.999954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.955848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.397383ms","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-17T02:39:51.955915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.046642Z","time spent":"909.15737ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:51.955939Z","caller":"traceutil/trace.go:171","msg":"trace[425371770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2903; }","duration":"256.508675ms","start":"2026-04-17T02:39:51.699403Z","end":"2026-04-17T02:39:51.955912Z","steps":["trace[425371770] 'agreement among raft nodes before linearized reading' (duration: 256.161247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:51.956226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.430367Z","time spent":"525.120958ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:39:52.721507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714050430784516,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T02:39:53.080027Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103625697s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T02:39:53.080495Z","caller":"traceutil/trace.go:171","msg":"trace[1631992794] transaction","detail":"{read_only:false; response_revision:2905; number_of_response:1; }","duration":"1.120220313s","start":"2026-04-17T02:39:51.960249Z","end":"2026-04-17T02:39:53.080469Z","steps":["trace[1631992794] 'process raft request' (duration: 1.119998958s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.080659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.960228Z","time spent":"1.120344625s","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:39:53.080684Z","caller":"traceutil/trace.go:171","msg":"trace[1349750440] transaction","detail":"{read_only:false; response_revision:2906; number_of_response:1; }","duration":"1.119864505s","start":"2026-04-17T02:39:51.960799Z","end":"2026-04-17T02:39:53.080663Z","steps":["trace[1349750440] 'process raft request' (duration: 1.11962956s)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:53.080665Z","caller":"traceutil/trace.go:171","msg":"trace[1339268506] linearizableReadLoop","detail":"{readStateIndex:3062; appliedIndex:3060; }","duration":"859.320235ms","start":"2026-04-17T02:39:52.221279Z","end":"2026-04-17T02:39:53.080599Z","steps":["trace[1339268506] 'read index received' (duration: 858.892246ms)","trace[1339268506] 'applied index is now lower than readState.Index' (duration: 426.129µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:53.080821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:51.960773Z","time spent":"1.119975398s","remote":"127.0.0.1:57290","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-17T02:39:53.080895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.605611ms","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-04-17T02:39:53.080959Z","caller":"traceutil/trace.go:171","msg":"trace[1287389120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2906; }","duration":"859.711983ms","start":"2026-04-17T02:39:52.221233Z","end":"2026-04-17T02:39:53.080945Z","steps":["trace[1287389120] 'agreement among raft nodes before linearized reading' (duration: 859.517289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.080951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.410223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:39:53.080968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.000513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:39:53.081008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.221211Z","time spent":"859.783654ms","remote":"127.0.0.1:57290","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":"info","ts":"2026-04-17T02:39:53.081012Z","caller":"traceutil/trace.go:171","msg":"trace[1441996328] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2906; }","duration":"329.538255ms","start":"2026-04-17T02:39:52.75146Z","end":"2026-04-17T02:39:53.080998Z","steps":["trace[1441996328] 'agreement among raft nodes before linearized reading' (duration: 329.432383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.080987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.122896ms","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":"warn","ts":"2026-04-17T02:39:53.081052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.751428Z","time spent":"329.615397ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:39:53.081043Z","caller":"traceutil/trace.go:171","msg":"trace[233737314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2906; }","duration":"726.149746ms","start":"2026-04-17T02:39:52.354854Z","end":"2026-04-17T02:39:53.081003Z","steps":["trace[233737314] 'agreement among raft nodes before linearized reading' (duration: 726.017773ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:53.081126Z","caller":"traceutil/trace.go:171","msg":"trace[2124426001] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2906; }","duration":"466.30547ms","start":"2026-04-17T02:39:52.614777Z","end":"2026-04-17T02:39:53.081083Z","steps":["trace[2124426001] 'agreement among raft nodes before linearized reading' (duration: 466.107586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.081146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.354823Z","time spent":"726.31264ms","remote":"127.0.0.1:57050","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-17T02:39:53.081189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:52.614756Z","time spent":"466.419603ms","remote":"127.0.0.1:57346","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-17T02:39:53.385Z","caller":"traceutil/trace.go:171","msg":"trace[1504876653] linearizableReadLoop","detail":"{readStateIndex:3063; appliedIndex:3062; }","duration":"152.227776ms","start":"2026-04-17T02:39:53.232734Z","end":"2026-04-17T02:39:53.384961Z","steps":["trace[1504876653] 'read index received' (duration: 98.903476ms)","trace[1504876653] 'applied index is now lower than readState.Index' (duration: 53.32009ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:39:53.385038Z","caller":"traceutil/trace.go:171","msg":"trace[392355598] transaction","detail":"{read_only:false; response_revision:2907; number_of_response:1; }","duration":"299.414072ms","start":"2026-04-17T02:39:53.085578Z","end":"2026-04-17T02:39:53.384992Z","steps":["trace[392355598] 'process raft request' (duration: 246.136683ms)","trace[392355598] 'compare' (duration: 53.058865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:53.385118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.377499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T02:39:53.385196Z","caller":"traceutil/trace.go:171","msg":"trace[711337928] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2907; }","duration":"152.513551ms","start":"2026-04-17T02:39:53.232665Z","end":"2026-04-17T02:39:53.385179Z","steps":["trace[711337928] 'agreement among raft nodes before linearized reading' (duration: 152.387729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:53.385301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.364954ms","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-04-17T02:39:53.385354Z","caller":"traceutil/trace.go:171","msg":"trace[1052586499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2907; }","duration":"105.467136ms","start":"2026-04-17T02:39:53.279875Z","end":"2026-04-17T02:39:53.385342Z","steps":["trace[1052586499] 'agreement among raft nodes before linearized reading' (duration: 105.369354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:59.480848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.000379ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T02:39:59.481046Z","caller":"traceutil/trace.go:171","msg":"trace[1560726010] linearizableReadLoop","detail":"{readStateIndex:3082; appliedIndex:3081; }","duration":"366.381795ms","start":"2026-04-17T02:39:59.11461Z","end":"2026-04-17T02:39:59.480992Z","steps":["trace[1560726010] 'read index received' (duration: 143.165332ms)","trace[1560726010] 'applied index is now lower than readState.Index' (duration: 223.214173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:39:59.481319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.689411ms","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":"warn","ts":"2026-04-17T02:39:59.481328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.817859ms","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-17T02:39:59.481378Z","caller":"traceutil/trace.go:171","msg":"trace[1863176591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2924; }","duration":"366.796924ms","start":"2026-04-17T02:39:59.114568Z","end":"2026-04-17T02:39:59.481365Z","steps":["trace[1863176591] 'agreement among raft nodes before linearized reading' (duration: 366.585369ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:39:59.481385Z","caller":"traceutil/trace.go:171","msg":"trace[1846731118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2924; }","duration":"356.904221ms","start":"2026-04-17T02:39:59.124469Z","end":"2026-04-17T02:39:59.481373Z","steps":["trace[1846731118] 'agreement among raft nodes before linearized reading' (duration: 356.755058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:39:59.481421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:59.124459Z","time spent":"356.954433ms","remote":"127.0.0.1:57290","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":"warn","ts":"2026-04-17T02:39:59.48142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:59.114538Z","time spent":"366.873594ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T02:39:59.704644Z","caller":"traceutil/trace.go:171","msg":"trace[838202848] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"218.790768ms","start":"2026-04-17T02:39:59.485826Z","end":"2026-04-17T02:39:59.704616Z","steps":["trace[838202848] 'process raft request' (duration: 218.634665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:00.160133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.789253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:00.160317Z","caller":"traceutil/trace.go:171","msg":"trace[686839643] transaction","detail":"{read_only:false; response_revision:2928; number_of_response:1; }","duration":"672.54617ms","start":"2026-04-17T02:39:59.487755Z","end":"2026-04-17T02:40:00.160301Z","steps":["trace[686839643] 'process raft request' (duration: 672.515029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:00.16036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:59.48774Z","time spent":"672.598242ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:00.160403Z","caller":"traceutil/trace.go:171","msg":"trace[2146272201] transaction","detail":"{read_only:false; response_revision:2926; number_of_response:1; }","duration":"674.028882ms","start":"2026-04-17T02:39:59.486355Z","end":"2026-04-17T02:40:00.160384Z","steps":["trace[2146272201] 'process raft request' (duration: 371.915982ms)","trace[2146272201] 'compare' (duration: 301.595309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:00.160494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:59.486299Z","time spent":"674.145844ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:00.160432Z","caller":"traceutil/trace.go:171","msg":"trace[1875321344] transaction","detail":"{read_only:false; response_revision:2927; number_of_response:1; }","duration":"674.023131ms","start":"2026-04-17T02:39:59.486382Z","end":"2026-04-17T02:40:00.160405Z","steps":["trace[1875321344] 'process raft request' (duration: 673.823357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:00.160716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:59.486299Z","time spent":"674.342838ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:00.363274Z","caller":"traceutil/trace.go:171","msg":"trace[1320903660] linearizableReadLoop","detail":"{readStateIndex:3087; appliedIndex:3086; }","duration":"305.178905ms","start":"2026-04-17T02:40:00.058075Z","end":"2026-04-17T02:40:00.363254Z","steps":["trace[1320903660] 'read index received' (duration: 305.012002ms)","trace[1320903660] 'applied index is now lower than readState.Index' (duration: 166.273µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:00.363365Z","caller":"traceutil/trace.go:171","msg":"trace[369889396] transaction","detail":"{read_only:false; response_revision:2929; number_of_response:1; }","duration":"366.621259ms","start":"2026-04-17T02:39:59.996733Z","end":"2026-04-17T02:40:00.363354Z","steps":["trace[369889396] 'process raft request' (duration: 366.413305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:00.363457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:39:59.99671Z","time spent":"366.676781ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:00.363636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.549534ms","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-04-17T02:40:00.363699Z","caller":"traceutil/trace.go:171","msg":"trace[237072110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2929; }","duration":"305.641995ms","start":"2026-04-17T02:40:00.058043Z","end":"2026-04-17T02:40:00.363685Z","steps":["trace[237072110] 'agreement among raft nodes before linearized reading' (duration: 305.505442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:00.363723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:00.05803Z","time spent":"305.687336ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T02:40:04.736643Z","caller":"traceutil/trace.go:171","msg":"trace[631448411] linearizableReadLoop","detail":"{readStateIndex:3168; appliedIndex:3166; }","duration":"162.526696ms","start":"2026-04-17T02:40:04.574084Z","end":"2026-04-17T02:40:04.736611Z","steps":["trace[631448411] 'read index received' (duration: 5.384976ms)","trace[631448411] 'applied index is now lower than readState.Index' (duration: 157.14058ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:04.736761Z","caller":"traceutil/trace.go:171","msg":"trace[1749916940] transaction","detail":"{read_only:false; response_revision:3008; number_of_response:1; }","duration":"167.389979ms","start":"2026-04-17T02:40:04.569326Z","end":"2026-04-17T02:40:04.736717Z","steps":["trace[1749916940] 'process raft request' (duration: 167.108223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:04.736846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.74787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:04.736902Z","caller":"traceutil/trace.go:171","msg":"trace[1151169187] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:3008; }","duration":"162.833791ms","start":"2026-04-17T02:40:04.574051Z","end":"2026-04-17T02:40:04.736884Z","steps":["trace[1151169187] 'agreement among raft nodes before linearized reading' (duration: 162.721869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:04.736918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.97893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-17T02:40:04.736953Z","caller":"traceutil/trace.go:171","msg":"trace[1293893165] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:3008; }","duration":"145.060252ms","start":"2026-04-17T02:40:04.591884Z","end":"2026-04-17T02:40:04.736944Z","steps":["trace[1293893165] 'agreement among raft nodes before linearized reading' (duration: 144.97222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:04.737029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.88134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-04-17T02:40:04.737097Z","caller":"traceutil/trace.go:171","msg":"trace[1580579809] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:3008; }","duration":"153.057703ms","start":"2026-04-17T02:40:04.584024Z","end":"2026-04-17T02:40:04.737081Z","steps":["trace[1580579809] 'agreement among raft nodes before linearized reading' (duration: 152.773857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.256435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.696967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:05.256789Z","caller":"traceutil/trace.go:171","msg":"trace[16376580] transaction","detail":"{read_only:false; response_revision:3009; number_of_response:1; }","duration":"516.190267ms","start":"2026-04-17T02:40:04.74055Z","end":"2026-04-17T02:40:05.25674Z","steps":["trace[16376580] 'process raft request' (duration: 209.079121ms)","trace[16376580] 'compare' (duration: 306.463682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:05.256898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.740525Z","time spent":"516.329331ms","remote":"127.0.0.1:57114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T02:40:05.257037Z","caller":"traceutil/trace.go:171","msg":"trace[2093629736] linearizableReadLoop","detail":"{readStateIndex:3172; appliedIndex:3168; }","duration":"513.47088ms","start":"2026-04-17T02:40:04.743545Z","end":"2026-04-17T02:40:05.257015Z","steps":["trace[2093629736] 'read index received' (duration: 206.100478ms)","trace[2093629736] 'applied index is now lower than readState.Index' (duration: 307.369332ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:05.257035Z","caller":"traceutil/trace.go:171","msg":"trace[541053158] transaction","detail":"{read_only:false; response_revision:3011; number_of_response:1; }","duration":"514.856589ms","start":"2026-04-17T02:40:04.742137Z","end":"2026-04-17T02:40:05.256994Z","steps":["trace[541053158] 'process raft request' (duration: 514.577033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.257161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.604203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:40:05.257207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.742108Z","time spent":"515.004682ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:05.25724Z","caller":"traceutil/trace.go:171","msg":"trace[315133722] range","detail":"{range_begin:/registry/services/specs/openstack/memcached; range_end:; response_count:0; response_revision:3012; }","duration":"513.719235ms","start":"2026-04-17T02:40:04.743502Z","end":"2026-04-17T02:40:05.257221Z","steps":["trace[315133722] 'agreement among raft nodes before linearized reading' (duration: 513.583123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.257296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.743494Z","time spent":"513.789397ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/memcached\" "} {"level":"info","ts":"2026-04-17T02:40:05.25714Z","caller":"traceutil/trace.go:171","msg":"trace[1533679818] transaction","detail":"{read_only:false; response_revision:3012; number_of_response:1; }","duration":"514.308337ms","start":"2026-04-17T02:40:04.742762Z","end":"2026-04-17T02:40:05.257071Z","steps":["trace[1533679818] 'process raft request' (duration: 514.162644ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:05.256799Z","caller":"traceutil/trace.go:171","msg":"trace[226171569] transaction","detail":"{read_only:false; response_revision:3010; number_of_response:1; }","duration":"516.054595ms","start":"2026-04-17T02:40:04.740711Z","end":"2026-04-17T02:40:05.256765Z","steps":["trace[226171569] 'process raft request' (duration: 515.873051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.257921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.740703Z","time spent":"517.163168ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:05.25794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.742744Z","time spent":"515.042672ms","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:05.513217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.723617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:05.513315Z","caller":"traceutil/trace.go:171","msg":"trace[1380471494] linearizableReadLoop","detail":"{readStateIndex:3173; appliedIndex:3172; }","duration":"256.209387ms","start":"2026-04-17T02:40:05.257089Z","end":"2026-04-17T02:40:05.513298Z","steps":["trace[1380471494] 'read index received' (duration: 138.346897ms)","trace[1380471494] 'applied index is now lower than readState.Index' (duration: 117.86103ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:05.513329Z","caller":"traceutil/trace.go:171","msg":"trace[1096212974] transaction","detail":"{read_only:false; response_revision:3013; number_of_response:1; }","duration":"500.077681ms","start":"2026-04-17T02:40:05.013222Z","end":"2026-04-17T02:40:05.5133Z","steps":["trace[1096212974] 'process raft request' (duration: 382.199021ms)","trace[1096212974] 'compare' (duration: 117.544933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:05.513432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.359635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:40:05.513453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.013193Z","time spent":"500.194754ms","remote":"127.0.0.1:57114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T02:40:05.513472Z","caller":"traceutil/trace.go:171","msg":"trace[698664734] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:3013; }","duration":"563.464998ms","start":"2026-04-17T02:40:04.949995Z","end":"2026-04-17T02:40:05.51346Z","steps":["trace[698664734] 'agreement among raft nodes before linearized reading' (duration: 563.368906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.513488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.287415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"warn","ts":"2026-04-17T02:40:05.513506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.949967Z","time spent":"563.529979ms","remote":"127.0.0.1:57188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-17T02:40:05.513525Z","caller":"traceutil/trace.go:171","msg":"trace[1613491803] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:3013; }","duration":"622.348207ms","start":"2026-04-17T02:40:04.891168Z","end":"2026-04-17T02:40:05.513516Z","steps":["trace[1613491803] 'agreement among raft nodes before linearized reading' (duration: 622.267325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.513521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.596816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"warn","ts":"2026-04-17T02:40:05.513547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.891158Z","time spent":"622.384178ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10266,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-17T02:40:05.513578Z","caller":"traceutil/trace.go:171","msg":"trace[299636566] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:3013; }","duration":"690.686987ms","start":"2026-04-17T02:40:04.822874Z","end":"2026-04-17T02:40:05.513561Z","steps":["trace[299636566] 'agreement among raft nodes before linearized reading' (duration: 690.504013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.513619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.822856Z","time spent":"690.753879ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-04-17T02:40:05.513711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.218596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:05.513844Z","caller":"traceutil/trace.go:171","msg":"trace[1999635021] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:3013; }","duration":"696.475421ms","start":"2026-04-17T02:40:04.817343Z","end":"2026-04-17T02:40:05.513818Z","steps":["trace[1999635021] 'agreement among raft nodes before linearized reading' (duration: 696.073923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.513934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.817329Z","time spent":"696.589893ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-04-17T02:40:05.513748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.755497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-2flmh\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-04-17T02:40:05.514107Z","caller":"traceutil/trace.go:171","msg":"trace[916454449] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-2flmh; range_end:; response_count:1; response_revision:3013; }","duration":"646.136405ms","start":"2026-04-17T02:40:04.867959Z","end":"2026-04-17T02:40:05.514095Z","steps":["trace[916454449] 'agreement among raft nodes before linearized reading' (duration: 645.627344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.513723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.511525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:40:05.514155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.867947Z","time spent":"646.196336ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11651,"request content":"key:\"/registry/pods/openstack/openvswitch-2flmh\" "} {"level":"info","ts":"2026-04-17T02:40:05.514189Z","caller":"traceutil/trace.go:171","msg":"trace[1243483970] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:3013; }","duration":"764.017635ms","start":"2026-04-17T02:40:04.750135Z","end":"2026-04-17T02:40:05.514152Z","steps":["trace[1243483970] 'agreement among raft nodes before linearized reading' (duration: 763.437393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.514267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:04.750091Z","time spent":"764.153749ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"info","ts":"2026-04-17T02:40:05.679204Z","caller":"traceutil/trace.go:171","msg":"trace[1704523005] linearizableReadLoop","detail":"{readStateIndex:3175; appliedIndex:3173; }","duration":"165.842296ms","start":"2026-04-17T02:40:05.513341Z","end":"2026-04-17T02:40:05.679184Z","steps":["trace[1704523005] 'read index received' (duration: 124.533674ms)","trace[1704523005] 'applied index is now lower than readState.Index' (duration: 41.307902ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:05.679226Z","caller":"traceutil/trace.go:171","msg":"trace[316730071] transaction","detail":"{read_only:false; response_revision:3014; number_of_response:1; }","duration":"416.615488ms","start":"2026-04-17T02:40:05.262576Z","end":"2026-04-17T02:40:05.679192Z","steps":["trace[316730071] 'process raft request' (duration: 375.284754ms)","trace[316730071] 'compare' (duration: 41.126269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:05.679349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.694962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/memcached-memcached\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:40:05.679362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.242096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:05.679376Z","caller":"traceutil/trace.go:171","msg":"trace[610930457] range","detail":"{range_begin:/registry/deployments/openstack/memcached-memcached; range_end:; response_count:0; response_revision:3015; }","duration":"418.775944ms","start":"2026-04-17T02:40:05.26059Z","end":"2026-04-17T02:40:05.679365Z","steps":["trace[610930457] 'agreement among raft nodes before linearized reading' (duration: 418.691032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.679372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.262556Z","time spent":"416.74592ms","remote":"127.0.0.1:57114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":804,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T02:40:05.679399Z","caller":"traceutil/trace.go:171","msg":"trace[543002832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3015; }","duration":"326.332548ms","start":"2026-04-17T02:40:05.353055Z","end":"2026-04-17T02:40:05.679388Z","steps":["trace[543002832] 'agreement among raft nodes before linearized reading' (duration: 326.220645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.679408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.260569Z","time spent":"418.832885ms","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/memcached-memcached\" "} {"level":"warn","ts":"2026-04-17T02:40:05.679416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.353031Z","time spent":"326.381988ms","remote":"127.0.0.1:57038","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-17T02:40:05.67941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.291144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-17T02:40:05.679491Z","caller":"traceutil/trace.go:171","msg":"trace[1005968482] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:3015; }","duration":"173.417957ms","start":"2026-04-17T02:40:05.506053Z","end":"2026-04-17T02:40:05.679471Z","steps":["trace[1005968482] 'agreement among raft nodes before linearized reading' (duration: 173.202483ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:05.6795Z","caller":"traceutil/trace.go:171","msg":"trace[422901413] transaction","detail":"{read_only:false; response_revision:3015; number_of_response:1; }","duration":"414.745388ms","start":"2026-04-17T02:40:05.264502Z","end":"2026-04-17T02:40:05.679248Z","steps":["trace[422901413] 'process raft request' (duration: 414.626255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.679728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.264493Z","time spent":"415.121347ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:05.958971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.906592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:40:05.95909Z","caller":"traceutil/trace.go:171","msg":"trace[1599077887] linearizableReadLoop","detail":"{readStateIndex:3176; appliedIndex:3175; }","duration":"279.814073ms","start":"2026-04-17T02:40:05.679258Z","end":"2026-04-17T02:40:05.959072Z","steps":["trace[1599077887] 'read index received' (duration: 146.740758ms)","trace[1599077887] 'applied index is now lower than readState.Index' (duration: 133.071915ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:05.959146Z","caller":"traceutil/trace.go:171","msg":"trace[822217946] transaction","detail":"{read_only:false; response_revision:3016; number_of_response:1; }","duration":"431.951586ms","start":"2026-04-17T02:40:05.527152Z","end":"2026-04-17T02:40:05.959104Z","steps":["trace[822217946] 'process raft request' (duration: 298.821639ms)","trace[822217946] 'compare' (duration: 132.777129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:05.959196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.468207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:05.95924Z","caller":"traceutil/trace.go:171","msg":"trace[1847966860] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:3016; }","duration":"432.536628ms","start":"2026-04-17T02:40:05.526688Z","end":"2026-04-17T02:40:05.959225Z","steps":["trace[1847966860] 'agreement among raft nodes before linearized reading' (duration: 432.457817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.959252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.515563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-17T02:40:05.959307Z","caller":"traceutil/trace.go:171","msg":"trace[265462245] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:3016; }","duration":"436.603685ms","start":"2026-04-17T02:40:05.52269Z","end":"2026-04-17T02:40:05.959293Z","steps":["trace[265462245] 'agreement among raft nodes before linearized reading' (duration: 436.469422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.9593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.526673Z","time spent":"432.613359ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"warn","ts":"2026-04-17T02:40:05.959324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.527125Z","time spent":"432.106649ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:05.959345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.522673Z","time spent":"436.666186ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10266,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-17T02:40:05.980172Z","caller":"traceutil/trace.go:171","msg":"trace[1883063689] transaction","detail":"{read_only:false; response_revision:3017; number_of_response:1; }","duration":"294.266802ms","start":"2026-04-17T02:40:05.68588Z","end":"2026-04-17T02:40:05.980147Z","steps":["trace[1883063689] 'process raft request' (duration: 294.060548ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:05.980392Z","caller":"traceutil/trace.go:171","msg":"trace[1700394241] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3017; }","duration":"228.762802ms","start":"2026-04-17T02:40:05.751602Z","end":"2026-04-17T02:40:05.980365Z","steps":["trace[1700394241] 'process raft request' (duration: 228.498936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.980555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.625457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-8lgxw\" ","response":"range_response_count:1 size:2650"} {"level":"info","ts":"2026-04-17T02:40:05.98062Z","caller":"traceutil/trace.go:171","msg":"trace[2114313107] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-8lgxw; range_end:; response_count:1; response_revision:3017; }","duration":"299.733079ms","start":"2026-04-17T02:40:05.680869Z","end":"2026-04-17T02:40:05.980602Z","steps":["trace[2114313107] 'agreement among raft nodes before linearized reading' (duration: 299.489094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.980646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.894492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:05.980708Z","caller":"traceutil/trace.go:171","msg":"trace[1450867083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3017; }","duration":"299.993284ms","start":"2026-04-17T02:40:05.680697Z","end":"2026-04-17T02:40:05.98069Z","steps":["trace[1450867083] 'agreement among raft nodes before linearized reading' (duration: 299.891222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.980712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.95133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:05.980757Z","caller":"traceutil/trace.go:171","msg":"trace[695808162] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-osd; range_end:; response_count:0; response_revision:3017; }","duration":"109.038822ms","start":"2026-04-17T02:40:05.871707Z","end":"2026-04-17T02:40:05.980746Z","steps":["trace[695808162] 'agreement among raft nodes before linearized reading' (duration: 108.96128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:05.980756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.68068Z","time spent":"300.065916ms","remote":"127.0.0.1:57050","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-17T02:40:05.980891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.712554ms","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-04-17T02:40:05.980956Z","caller":"traceutil/trace.go:171","msg":"trace[777132136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3017; }","duration":"252.872587ms","start":"2026-04-17T02:40:05.728067Z","end":"2026-04-17T02:40:05.980939Z","steps":["trace[777132136] 'agreement among raft nodes before linearized reading' (duration: 252.690163ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:06.280709Z","caller":"traceutil/trace.go:171","msg":"trace[313170606] linearizableReadLoop","detail":"{readStateIndex:3179; appliedIndex:3178; }","duration":"298.621706ms","start":"2026-04-17T02:40:05.982063Z","end":"2026-04-17T02:40:06.280684Z","steps":["trace[313170606] 'read index received' (duration: 298.530504ms)","trace[313170606] 'applied index is now lower than readState.Index' (duration: 90.102µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:06.280753Z","caller":"traceutil/trace.go:171","msg":"trace[2130969918] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3017; }","duration":"299.34452ms","start":"2026-04-17T02:40:05.981369Z","end":"2026-04-17T02:40:06.280714Z","steps":["trace[2130969918] 'process raft request' (duration: 299.194597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.280957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.850541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/openstack\" ","response":"range_response_count:1 size:368"} {"level":"info","ts":"2026-04-17T02:40:06.280997Z","caller":"traceutil/trace.go:171","msg":"trace[1489162844] range","detail":"{range_begin:/registry/namespaces/openstack; range_end:; response_count:1; response_revision:3017; }","duration":"298.949142ms","start":"2026-04-17T02:40:05.982037Z","end":"2026-04-17T02:40:06.280987Z","steps":["trace[1489162844] 'agreement among raft nodes before linearized reading' (duration: 298.768848ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:06.522702Z","caller":"traceutil/trace.go:171","msg":"trace[816594497] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3017; }","duration":"539.401743ms","start":"2026-04-17T02:40:05.983282Z","end":"2026-04-17T02:40:06.522684Z","steps":["trace[816594497] 'process raft request' (duration: 539.25427ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:06.522782Z","caller":"traceutil/trace.go:171","msg":"trace[831160614] linearizableReadLoop","detail":"{readStateIndex:3182; appliedIndex:3179; }","duration":"241.986804ms","start":"2026-04-17T02:40:06.280778Z","end":"2026-04-17T02:40:06.522765Z","steps":["trace[831160614] 'read index received' (duration: 241.81714ms)","trace[831160614] 'applied index is now lower than readState.Index' (duration: 168.584µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:40:06.522808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.98327Z","time spent":"539.485165ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T02:40:06.522825Z","caller":"traceutil/trace.go:171","msg":"trace[2098103099] transaction","detail":"{read_only:false; response_revision:3019; number_of_response:1; }","duration":"535.603973ms","start":"2026-04-17T02:40:05.987192Z","end":"2026-04-17T02:40:06.522796Z","steps":["trace[2098103099] 'process raft request' (duration: 535.52896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.522962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.987168Z","time spent":"535.724375ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3596,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:06.522975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.944214ms","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-17T02:40:06.522998Z","caller":"traceutil/trace.go:171","msg":"trace[1346364238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3019; }","duration":"344.031386ms","start":"2026-04-17T02:40:06.178959Z","end":"2026-04-17T02:40:06.522991Z","steps":["trace[1346364238] 'agreement among raft nodes before linearized reading' (duration: 343.892003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.523022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.178941Z","time spent":"344.074077ms","remote":"127.0.0.1:57290","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-17T02:40:06.523052Z","caller":"traceutil/trace.go:171","msg":"trace[591307660] transaction","detail":"{read_only:false; response_revision:3018; number_of_response:1; }","duration":"537.568254ms","start":"2026-04-17T02:40:05.985475Z","end":"2026-04-17T02:40:06.523043Z","steps":["trace[591307660] 'process raft request' (duration: 537.140724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.523068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.001442ms","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":"warn","ts":"2026-04-17T02:40:06.523121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.985441Z","time spent":"537.633706ms","remote":"127.0.0.1:57290","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-17T02:40:06.523147Z","caller":"traceutil/trace.go:171","msg":"trace[1595154944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3019; }","duration":"327.116454ms","start":"2026-04-17T02:40:06.19601Z","end":"2026-04-17T02:40:06.523126Z","steps":["trace[1595154944] 'agreement among raft nodes before linearized reading' (duration: 326.822378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.523124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.421133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:40:06.523193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.195998Z","time spent":"327.186606ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T02:40:06.523238Z","caller":"traceutil/trace.go:171","msg":"trace[2002931534] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-mgr; range_end:; response_count:0; response_revision:3019; }","duration":"538.581347ms","start":"2026-04-17T02:40:05.984631Z","end":"2026-04-17T02:40:06.523212Z","steps":["trace[2002931534] 'agreement among raft nodes before linearized reading' (duration: 538.348302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.523294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:05.984622Z","time spent":"538.663038ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" "} {"level":"warn","ts":"2026-04-17T02:40:06.523388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.524982ms","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-04-17T02:40:06.523482Z","caller":"traceutil/trace.go:171","msg":"trace[1226405919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3019; }","duration":"326.633814ms","start":"2026-04-17T02:40:06.196827Z","end":"2026-04-17T02:40:06.523461Z","steps":["trace[1226405919] 'agreement among raft nodes before linearized reading' (duration: 326.203815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:06.523522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.19678Z","time spent":"326.734806ms","remote":"127.0.0.1:57290","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-17T02:40:07.02372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714050430784772,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T02:40:07.08718Z","caller":"traceutil/trace.go:171","msg":"trace[1233327465] transaction","detail":"{read_only:false; response_revision:3020; number_of_response:1; }","duration":"798.82035ms","start":"2026-04-17T02:40:06.288333Z","end":"2026-04-17T02:40:07.087153Z","steps":["trace[1233327465] 'process raft request' (duration: 798.589695ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:07.087181Z","caller":"traceutil/trace.go:171","msg":"trace[1936393722] transaction","detail":"{read_only:false; response_revision:3021; number_of_response:1; }","duration":"708.925248ms","start":"2026-04-17T02:40:06.378233Z","end":"2026-04-17T02:40:07.087158Z","steps":["trace[1936393722] 'process raft request' (duration: 708.825526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.087289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.288301Z","time spent":"798.934502ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T02:40:07.087325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.378209Z","time spent":"709.038831ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:07.111281Z","caller":"traceutil/trace.go:171","msg":"trace[1984315656] transaction","detail":"{read_only:false; response_revision:3022; number_of_response:1; }","duration":"584.55371ms","start":"2026-04-17T02:40:06.526708Z","end":"2026-04-17T02:40:07.111262Z","steps":["trace[1984315656] 'process raft request' (duration: 584.405106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.111392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.526681Z","time spent":"584.652591ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:07.111549Z","caller":"traceutil/trace.go:171","msg":"trace[2122306136] transaction","detail":"{read_only:false; response_revision:3023; number_of_response:1; }","duration":"584.632601ms","start":"2026-04-17T02:40:06.526878Z","end":"2026-04-17T02:40:07.111511Z","steps":["trace[2122306136] 'process raft request' (duration: 584.347395ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:07.111605Z","caller":"traceutil/trace.go:171","msg":"trace[2077736135] linearizableReadLoop","detail":"{readStateIndex:3188; appliedIndex:3182; }","duration":"588.757358ms","start":"2026-04-17T02:40:06.522827Z","end":"2026-04-17T02:40:07.111585Z","steps":["trace[2077736135] 'read index received' (duration: 564.121901ms)","trace[2077736135] 'applied index is now lower than readState.Index' (duration: 24.634467ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:40:07.111651Z","caller":"traceutil/trace.go:171","msg":"trace[1696649114] transaction","detail":"{read_only:false; response_revision:3025; number_of_response:1; }","duration":"350.454813ms","start":"2026-04-17T02:40:06.761178Z","end":"2026-04-17T02:40:07.111633Z","steps":["trace[1696649114] 'process raft request' (duration: 350.365861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.111735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.817472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T02:40:07.111735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.761152Z","time spent":"350.544385ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:07.111746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.52687Z","time spent":"584.775364ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T02:40:07.111773Z","caller":"traceutil/trace.go:171","msg":"trace[1355213152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3025; }","duration":"756.899434ms","start":"2026-04-17T02:40:06.354863Z","end":"2026-04-17T02:40:07.111763Z","steps":["trace[1355213152] 'agreement among raft nodes before linearized reading' (duration: 756.826383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.111777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.246557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T02:40:07.11181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.354849Z","time spent":"756.953485ms","remote":"127.0.0.1:57038","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-17T02:40:07.111836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.841813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:4678"} {"level":"info","ts":"2026-04-17T02:40:07.111854Z","caller":"traceutil/trace.go:171","msg":"trace[1164665676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3025; }","duration":"609.36572ms","start":"2026-04-17T02:40:06.502471Z","end":"2026-04-17T02:40:07.111837Z","steps":["trace[1164665676] 'agreement among raft nodes before linearized reading' (duration: 609.190476ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:07.111882Z","caller":"traceutil/trace.go:171","msg":"trace[1430321502] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:3025; }","duration":"829.908494ms","start":"2026-04-17T02:40:06.281962Z","end":"2026-04-17T02:40:07.11187Z","steps":["trace[1430321502] 'agreement among raft nodes before linearized reading' (duration: 829.831343ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:40:07.111758Z","caller":"traceutil/trace.go:171","msg":"trace[1295880201] transaction","detail":"{read_only:false; response_revision:3024; number_of_response:1; }","duration":"584.792074ms","start":"2026-04-17T02:40:06.526954Z","end":"2026-04-17T02:40:07.111746Z","steps":["trace[1295880201] 'process raft request' (duration: 584.471917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.111917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.281948Z","time spent":"829.962486ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":4701,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" "} {"level":"warn","ts":"2026-04-17T02:40:07.111896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.502452Z","time spent":"609.435381ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T02:40:07.112077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.526946Z","time spent":"585.017789ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T02:40:07.144179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.35471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-2flmh\" ","response":"range_response_count:1 size:11830"} {"level":"warn","ts":"2026-04-17T02:40:07.144176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.020434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:40:07.144249Z","caller":"traceutil/trace.go:171","msg":"trace[1307760794] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-2flmh; range_end:; response_count:1; response_revision:3026; }","duration":"618.437503ms","start":"2026-04-17T02:40:06.525794Z","end":"2026-04-17T02:40:07.144232Z","steps":["trace[1307760794] 'agreement among raft nodes before linearized reading' (duration: 618.275129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.144289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.525789Z","time spent":"618.492164ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11853,"request content":"key:\"/registry/pods/openstack/openvswitch-2flmh\" "} {"level":"info","ts":"2026-04-17T02:40:07.144304Z","caller":"traceutil/trace.go:171","msg":"trace[803355620] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter; range_end:; response_count:0; response_revision:3026; }","duration":"618.171346ms","start":"2026-04-17T02:40:06.526093Z","end":"2026-04-17T02:40:07.144265Z","steps":["trace[803355620] 'agreement among raft nodes before linearized reading' (duration: 617.957222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.144371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.526089Z","time spent":"618.27125ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" "} {"level":"warn","ts":"2026-04-17T02:40:07.144375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.754649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:10381"} {"level":"info","ts":"2026-04-17T02:40:07.144472Z","caller":"traceutil/trace.go:171","msg":"trace[393211204] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:3026; }","duration":"618.889122ms","start":"2026-04-17T02:40:06.525555Z","end":"2026-04-17T02:40:07.144444Z","steps":["trace[393211204] 'agreement among raft nodes before linearized reading' (duration: 618.578946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:40:07.144523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T02:40:06.52554Z","time spent":"618.974204ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":10404,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" "} {"level":"info","ts":"2026-04-17T02:43:40.574631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2674} {"level":"info","ts":"2026-04-17T02:43:40.595485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2674,"took":"19.761278ms","hash":3599257449,"current-db-size-bytes":24600576,"current-db-size":"25 MB","current-db-size-in-use-bytes":23564288,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-17T02:43:40.595558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3599257449,"revision":2674,"compact-revision":1693} {"level":"info","ts":"2026-04-17T02:48:36.004518Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b5.wal"} {"level":"info","ts":"2026-04-17T02:48:40.581139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5803} {"level":"info","ts":"2026-04-17T02:48:40.663151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5803,"took":"80.470633ms","hash":766963815,"current-db-size-bytes":50040832,"current-db-size":"50 MB","current-db-size-in-use-bytes":37560320,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-04-17T02:48:40.663289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":766963815,"revision":5803,"compact-revision":2674} {"level":"info","ts":"2026-04-17T02:48:59.922632Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"80be33c6b0d5a492","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T02:48:59.928572Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T02:48:59.92865Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T02:53:40.588209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9151} {"level":"info","ts":"2026-04-17T02:53:40.698156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9151,"took":"107.259677ms","hash":1479041767,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":37539840,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-04-17T02:53:40.69823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1479041767,"revision":9151,"compact-revision":5803} {"level":"info","ts":"2026-04-17T02:54:32.681887Z","caller":"traceutil/trace.go:171","msg":"trace[1613206099] transaction","detail":"{read_only:false; response_revision:13441; number_of_response:1; }","duration":"118.743593ms","start":"2026-04-17T02:54:32.563102Z","end":"2026-04-17T02:54:32.681845Z","steps":["trace[1613206099] 'process raft request' (duration: 118.480578ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:32.751214Z","caller":"traceutil/trace.go:171","msg":"trace[2003220004] transaction","detail":"{read_only:false; response_revision:13442; number_of_response:1; }","duration":"126.415248ms","start":"2026-04-17T02:54:32.624774Z","end":"2026-04-17T02:54:32.751189Z","steps":["trace[2003220004] 'process raft request' (duration: 125.937457ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:34.437882Z","caller":"traceutil/trace.go:171","msg":"trace[1331303263] transaction","detail":"{read_only:false; response_revision:13515; number_of_response:1; }","duration":"116.510576ms","start":"2026-04-17T02:54:34.321326Z","end":"2026-04-17T02:54:34.437837Z","steps":["trace[1331303263] 'process raft request' (duration: 115.896942ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:34.437956Z","caller":"traceutil/trace.go:171","msg":"trace[404965702] linearizableReadLoop","detail":"{readStateIndex:13974; appliedIndex:13973; }","duration":"104.134031ms","start":"2026-04-17T02:54:34.333791Z","end":"2026-04-17T02:54:34.437925Z","steps":["trace[404965702] 'read index received' (duration: 59.506415ms)","trace[404965702] 'applied index is now lower than readState.Index' (duration: 44.626346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:54:34.438573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.760334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-82c7t\" ","response":"range_response_count:1 size:20668"} {"level":"info","ts":"2026-04-17T02:54:34.438636Z","caller":"traceutil/trace.go:171","msg":"trace[1909562466] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-82c7t; range_end:; response_count:1; response_revision:13517; }","duration":"104.878667ms","start":"2026-04-17T02:54:34.333743Z","end":"2026-04-17T02:54:34.438622Z","steps":["trace[1909562466] 'agreement among raft nodes before linearized reading' (duration: 104.698983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:54:36.569597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.129746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T02:54:36.569747Z","caller":"traceutil/trace.go:171","msg":"trace[43187940] transaction","detail":"{read_only:false; response_revision:13575; number_of_response:1; }","duration":"126.642013ms","start":"2026-04-17T02:54:36.443091Z","end":"2026-04-17T02:54:36.569733Z","steps":["trace[43187940] 'process raft request' (duration: 126.592772ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:36.569914Z","caller":"traceutil/trace.go:171","msg":"trace[627085982] transaction","detail":"{read_only:false; response_revision:13574; number_of_response:1; }","duration":"198.206846ms","start":"2026-04-17T02:54:36.371698Z","end":"2026-04-17T02:54:36.569905Z","steps":["trace[627085982] 'process raft request' (duration: 61.712232ms)","trace[627085982] 'compare' (duration: 136.023984ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T02:54:36.570018Z","caller":"traceutil/trace.go:171","msg":"trace[127377836] linearizableReadLoop","detail":"{readStateIndex:14033; appliedIndex:14032; }","duration":"188.041807ms","start":"2026-04-17T02:54:36.381969Z","end":"2026-04-17T02:54:36.570011Z","steps":["trace[127377836] 'read index received' (duration: 51.36236ms)","trace[127377836] 'applied index is now lower than readState.Index' (duration: 136.678707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:54:36.570186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.422492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/nova-openstack-nova-api-osapi\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:54:36.570212Z","caller":"traceutil/trace.go:171","msg":"trace[1747120390] range","detail":"{range_begin:/registry/roles/openstack/nova-openstack-nova-api-osapi; range_end:; response_count:0; response_revision:13575; }","duration":"185.483942ms","start":"2026-04-17T02:54:36.384722Z","end":"2026-04-17T02:54:36.570206Z","steps":["trace[1747120390] 'agreement among raft nodes before linearized reading' (duration: 185.431881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:54:36.570289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.463326ms","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":"info","ts":"2026-04-17T02:54:36.570402Z","caller":"traceutil/trace.go:171","msg":"trace[1002295829] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:13575; }","duration":"194.623099ms","start":"2026-04-17T02:54:36.37576Z","end":"2026-04-17T02:54:36.570384Z","steps":["trace[1002295829] 'agreement among raft nodes before linearized reading' (duration: 194.344613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:54:36.57057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.435367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T02:54:36.570602Z","caller":"traceutil/trace.go:171","msg":"trace[1895343147] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:13575; }","duration":"111.508899ms","start":"2026-04-17T02:54:36.459085Z","end":"2026-04-17T02:54:36.570594Z","steps":["trace[1895343147] 'agreement among raft nodes before linearized reading' (duration: 111.463818ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:38.926479Z","caller":"traceutil/trace.go:171","msg":"trace[986518403] linearizableReadLoop","detail":"{readStateIndex:14271; appliedIndex:14270; }","duration":"180.980746ms","start":"2026-04-17T02:54:38.745475Z","end":"2026-04-17T02:54:38.926456Z","steps":["trace[986518403] 'read index received' (duration: 180.923695ms)","trace[986518403] 'applied index is now lower than readState.Index' (duration: 55.771µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T02:54:38.926654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.15828ms","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-17T02:54:38.926617Z","caller":"traceutil/trace.go:171","msg":"trace[391351025] transaction","detail":"{read_only:false; response_revision:13810; number_of_response:1; }","duration":"196.909217ms","start":"2026-04-17T02:54:38.729641Z","end":"2026-04-17T02:54:38.92655Z","steps":["trace[391351025] 'process raft request' (duration: 196.659662ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:38.926693Z","caller":"traceutil/trace.go:171","msg":"trace[56297458] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:13810; }","duration":"181.253452ms","start":"2026-04-17T02:54:38.745427Z","end":"2026-04-17T02:54:38.92668Z","steps":["trace[56297458] 'agreement among raft nodes before linearized reading' (duration: 181.13558ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:54:38.939794Z","caller":"traceutil/trace.go:171","msg":"trace[246749073] transaction","detail":"{read_only:false; response_revision:13811; number_of_response:1; }","duration":"143.576545ms","start":"2026-04-17T02:54:38.796181Z","end":"2026-04-17T02:54:38.939757Z","steps":["trace[246749073] 'process raft request' (duration: 143.32922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T02:54:38.940002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.191763ms","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-04-17T02:54:38.940094Z","caller":"traceutil/trace.go:171","msg":"trace[1100273590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13812; }","duration":"140.317385ms","start":"2026-04-17T02:54:38.799753Z","end":"2026-04-17T02:54:38.94007Z","steps":["trace[1100273590] 'agreement among raft nodes before linearized reading' (duration: 140.09498ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T02:58:40.595438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12723} {"level":"info","ts":"2026-04-17T02:58:40.709108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12723,"took":"110.187709ms","hash":1955601459,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":46673920,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-04-17T02:58:40.709177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1955601459,"revision":12723,"compact-revision":9151} {"level":"info","ts":"2026-04-17T03:01:39.549659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"80be33c6b0d5a492","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T03:01:39.554873Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-17T03:01:39.554965Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-17T03:01:57.582958Z","caller":"traceutil/trace.go:171","msg":"trace[1060739153] transaction","detail":"{read_only:false; response_revision:19566; number_of_response:1; }","duration":"134.949776ms","start":"2026-04-17T03:01:57.447988Z","end":"2026-04-17T03:01:57.582938Z","steps":["trace[1060739153] 'process raft request' (duration: 134.813714ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:02:06.917899Z","caller":"traceutil/trace.go:171","msg":"trace[421396597] transaction","detail":"{read_only:false; response_revision:19726; number_of_response:1; }","duration":"280.336367ms","start":"2026-04-17T03:02:06.637527Z","end":"2026-04-17T03:02:06.917863Z","steps":["trace[421396597] 'process raft request' (duration: 188.617475ms)","trace[421396597] 'compare' (duration: 91.16166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:02:06.918022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.634237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-04-17T03:02:06.918098Z","caller":"traceutil/trace.go:171","msg":"trace[835888078] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:19727; }","duration":"218.75831ms","start":"2026-04-17T03:02:06.69932Z","end":"2026-04-17T03:02:06.918078Z","steps":["trace[835888078] 'agreement among raft nodes before linearized reading' (duration: 218.547655ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:02:06.918159Z","caller":"traceutil/trace.go:171","msg":"trace[1134011425] linearizableReadLoop","detail":"{readStateIndex:20336; appliedIndex:20334; }","duration":"218.483313ms","start":"2026-04-17T03:02:06.69938Z","end":"2026-04-17T03:02:06.917863Z","steps":["trace[1134011425] 'read index received' (duration: 126.790222ms)","trace[1134011425] 'applied index is now lower than readState.Index' (duration: 91.692361ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:02:06.91843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.685613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-24hhn\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-04-17T03:02:06.918508Z","caller":"traceutil/trace.go:171","msg":"trace[784995294] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-24hhn; range_end:; response_count:1; response_revision:19727; }","duration":"157.773365ms","start":"2026-04-17T03:02:06.760691Z","end":"2026-04-17T03:02:06.918465Z","steps":["trace[784995294] 'agreement among raft nodes before linearized reading' (duration: 157.633032ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:02:06.918596Z","caller":"traceutil/trace.go:171","msg":"trace[1494724448] transaction","detail":"{read_only:false; response_revision:19727; number_of_response:1; }","duration":"275.043304ms","start":"2026-04-17T03:02:06.643522Z","end":"2026-04-17T03:02:06.918566Z","steps":["trace[1494724448] 'process raft request' (duration: 274.230657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:02:07.152743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.908817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:02:07.15292Z","caller":"traceutil/trace.go:171","msg":"trace[627814393] linearizableReadLoop","detail":"{readStateIndex:20338; appliedIndex:20336; }","duration":"183.339683ms","start":"2026-04-17T03:02:06.969565Z","end":"2026-04-17T03:02:07.152905Z","steps":["trace[627814393] 'read index received' (duration: 61.136348ms)","trace[627814393] 'applied index is now lower than readState.Index' (duration: 122.202645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:02:07.153008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.429725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:02:07.152999Z","caller":"traceutil/trace.go:171","msg":"trace[1155760421] transaction","detail":"{read_only:false; response_revision:19728; number_of_response:1; }","duration":"228.166162ms","start":"2026-04-17T03:02:06.924773Z","end":"2026-04-17T03:02:07.152939Z","steps":["trace[1155760421] 'process raft request' (duration: 105.915676ms)","trace[1155760421] 'compare' (duration: 121.807165ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:02:07.153059Z","caller":"traceutil/trace.go:171","msg":"trace[364968714] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:19729; }","duration":"183.492096ms","start":"2026-04-17T03:02:06.969537Z","end":"2026-04-17T03:02:07.153029Z","steps":["trace[364968714] 'agreement among raft nodes before linearized reading' (duration: 183.426424ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:02:07.152977Z","caller":"traceutil/trace.go:171","msg":"trace[114702691] transaction","detail":"{read_only:false; response_revision:19729; number_of_response:1; }","duration":"217.150256ms","start":"2026-04-17T03:02:06.935795Z","end":"2026-04-17T03:02:07.152945Z","steps":["trace[114702691] 'process raft request' (duration: 217.049894ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:03:15.980953Z","caller":"traceutil/trace.go:171","msg":"trace[904605558] transaction","detail":"{read_only:false; response_revision:20473; number_of_response:1; }","duration":"145.443802ms","start":"2026-04-17T03:03:15.835468Z","end":"2026-04-17T03:03:15.980912Z","steps":["trace[904605558] 'process raft request' (duration: 144.86491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:03:16.194446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.508847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:03:16.194602Z","caller":"traceutil/trace.go:171","msg":"trace[1063813577] transaction","detail":"{read_only:false; response_revision:20474; number_of_response:1; }","duration":"208.561552ms","start":"2026-04-17T03:03:15.986019Z","end":"2026-04-17T03:03:16.19458Z","steps":["trace[1063813577] 'process raft request' (duration: 95.777359ms)","trace[1063813577] 'compare' (duration: 112.313473ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:03:16.270247Z","caller":"traceutil/trace.go:171","msg":"trace[422176120] linearizableReadLoop","detail":"{readStateIndex:21100; appliedIndex:21099; }","duration":"176.073557ms","start":"2026-04-17T03:03:16.094146Z","end":"2026-04-17T03:03:16.27022Z","steps":["trace[422176120] 'read index received' (duration: 175.972295ms)","trace[422176120] 'applied index is now lower than readState.Index' (duration: 100.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:03:16.270381Z","caller":"traceutil/trace.go:171","msg":"trace[1617552675] transaction","detail":"{read_only:false; response_revision:20475; number_of_response:1; }","duration":"191.064488ms","start":"2026-04-17T03:03:16.079292Z","end":"2026-04-17T03:03:16.270357Z","steps":["trace[1617552675] 'process raft request' (duration: 190.788262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:03:16.270774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.592098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-17T03:03:16.27084Z","caller":"traceutil/trace.go:171","msg":"trace[2012288057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20475; }","duration":"176.679519ms","start":"2026-04-17T03:03:16.094139Z","end":"2026-04-17T03:03:16.270819Z","steps":["trace[2012288057] 'agreement among raft nodes before linearized reading' (duration: 176.24477ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:03:16.332568Z","caller":"traceutil/trace.go:171","msg":"trace[2026457672] transaction","detail":"{read_only:false; response_revision:20476; number_of_response:1; }","duration":"162.913035ms","start":"2026-04-17T03:03:16.169611Z","end":"2026-04-17T03:03:16.332524Z","steps":["trace[2026457672] 'process raft request' (duration: 160.426543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:03:16.646709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.494854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:03:16.646815Z","caller":"traceutil/trace.go:171","msg":"trace[178277980] transaction","detail":"{read_only:false; response_revision:20480; number_of_response:1; }","duration":"234.541568ms","start":"2026-04-17T03:03:16.412255Z","end":"2026-04-17T03:03:16.646796Z","steps":["trace[178277980] 'process raft request' (duration: 131.866061ms)","trace[178277980] 'compare' (duration: 101.95616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:03:16.938514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.104334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T03:03:16.938722Z","caller":"traceutil/trace.go:171","msg":"trace[290165691] transaction","detail":"{read_only:false; response_revision:20481; number_of_response:1; }","duration":"357.966778ms","start":"2026-04-17T03:03:16.580733Z","end":"2026-04-17T03:03:16.9387Z","steps":["trace[290165691] 'process raft request' (duration: 241.572598ms)","trace[290165691] 'compare' (duration: 115.642445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T03:03:16.938792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:03:16.580704Z","time spent":"358.058151ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T03:03:16.938793Z","caller":"traceutil/trace.go:171","msg":"trace[1405469449] linearizableReadLoop","detail":"{readStateIndex:21107; appliedIndex:21105; }","duration":"269.076037ms","start":"2026-04-17T03:03:16.66969Z","end":"2026-04-17T03:03:16.938766Z","steps":["trace[1405469449] 'read index received' (duration: 152.530573ms)","trace[1405469449] 'applied index is now lower than readState.Index' (duration: 116.543254ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:03:16.938834Z","caller":"traceutil/trace.go:171","msg":"trace[1740786859] transaction","detail":"{read_only:false; response_revision:20482; number_of_response:1; }","duration":"352.364519ms","start":"2026-04-17T03:03:16.586438Z","end":"2026-04-17T03:03:16.938802Z","steps":["trace[1740786859] 'process raft request' (duration: 352.182835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:03:16.938963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:03:16.586425Z","time spent":"352.472261ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T03:03:16.938963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.2494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:03:16.939029Z","caller":"traceutil/trace.go:171","msg":"trace[669088546] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:20482; }","duration":"269.408584ms","start":"2026-04-17T03:03:16.669604Z","end":"2026-04-17T03:03:16.939012Z","steps":["trace[669088546] 'agreement among raft nodes before linearized reading' (duration: 269.273201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:03:16.939971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.076026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:1 size:4874"} {"level":"warn","ts":"2026-04-17T03:03:16.940013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.724754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-04-17T03:03:16.940034Z","caller":"traceutil/trace.go:171","msg":"trace[1572184411] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:1; response_revision:20483; }","duration":"232.189947ms","start":"2026-04-17T03:03:16.707831Z","end":"2026-04-17T03:03:16.940021Z","steps":["trace[1572184411] 'agreement among raft nodes before linearized reading' (duration: 232.013723ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:03:16.940058Z","caller":"traceutil/trace.go:171","msg":"trace[1031005835] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:20483; }","duration":"186.816536ms","start":"2026-04-17T03:03:16.753231Z","end":"2026-04-17T03:03:16.940048Z","steps":["trace[1031005835] 'agreement among raft nodes before linearized reading' (duration: 186.686963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:03:16.940149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.90187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-17T03:03:16.94025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.070594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:03:16.94029Z","caller":"traceutil/trace.go:171","msg":"trace[1389880296] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20483; }","duration":"232.147456ms","start":"2026-04-17T03:03:16.708134Z","end":"2026-04-17T03:03:16.940281Z","steps":["trace[1389880296] 'agreement among raft nodes before linearized reading' (duration: 232.086124ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:03:16.940233Z","caller":"traceutil/trace.go:171","msg":"trace[1763169890] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20483; }","duration":"232.008253ms","start":"2026-04-17T03:03:16.7082Z","end":"2026-04-17T03:03:16.940208Z","steps":["trace[1763169890] 'agreement among raft nodes before linearized reading' (duration: 231.87089ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:03:40.605807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17387} {"level":"info","ts":"2026-04-17T03:03:40.785756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17387,"took":"176.594229ms","hash":781909506,"current-db-size-bytes":63799296,"current-db-size":"64 MB","current-db-size-in-use-bytes":37175296,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-17T03:03:40.78582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":781909506,"revision":17387,"compact-revision":12723} {"level":"info","ts":"2026-04-17T03:07:25.662146Z","caller":"traceutil/trace.go:171","msg":"trace[1967660201] transaction","detail":"{read_only:false; response_revision:23049; number_of_response:1; }","duration":"330.448599ms","start":"2026-04-17T03:07:25.331666Z","end":"2026-04-17T03:07:25.662114Z","steps":["trace[1967660201] 'process raft request' (duration: 330.270555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.662368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.331646Z","time spent":"330.594421ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T03:07:25.724022Z","caller":"traceutil/trace.go:171","msg":"trace[2137271040] linearizableReadLoop","detail":"{readStateIndex:23738; appliedIndex:23735; }","duration":"367.856679ms","start":"2026-04-17T03:07:25.356114Z","end":"2026-04-17T03:07:25.72397Z","steps":["trace[2137271040] 'read index received' (duration: 305.798241ms)","trace[2137271040] 'applied index is now lower than readState.Index' (duration: 62.057508ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:07:25.724033Z","caller":"traceutil/trace.go:171","msg":"trace[119047661] transaction","detail":"{read_only:false; response_revision:23050; number_of_response:1; }","duration":"389.012501ms","start":"2026-04-17T03:07:25.334978Z","end":"2026-04-17T03:07:25.723991Z","steps":["trace[119047661] 'process raft request' (duration: 355.205318ms)","trace[119047661] 'compare' (duration: 33.580018ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T03:07:25.724101Z","caller":"traceutil/trace.go:171","msg":"trace[99416117] transaction","detail":"{read_only:false; response_revision:23051; number_of_response:1; }","duration":"388.169662ms","start":"2026-04-17T03:07:25.335875Z","end":"2026-04-17T03:07:25.724045Z","steps":["trace[99416117] 'process raft request' (duration: 388.040759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.724228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.334955Z","time spent":"389.172475ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T03:07:25.724308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.177215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T03:07:25.724394Z","caller":"traceutil/trace.go:171","msg":"trace[930123684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23051; }","duration":"368.302817ms","start":"2026-04-17T03:07:25.356073Z","end":"2026-04-17T03:07:25.724376Z","steps":["trace[930123684] 'agreement among raft nodes before linearized reading' (duration: 368.170864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.724385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.335849Z","time spent":"388.395877ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T03:07:25.72445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.356057Z","time spent":"368.3863ms","remote":"127.0.0.1:57038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T03:07:25.724654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.966906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-17T03:07:25.724718Z","caller":"traceutil/trace.go:171","msg":"trace[1766266354] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:23051; }","duration":"357.118069ms","start":"2026-04-17T03:07:25.367585Z","end":"2026-04-17T03:07:25.724703Z","steps":["trace[1766266354] 'agreement among raft nodes before linearized reading' (duration: 356.813342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.724771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.367566Z","time spent":"357.192281ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":198,"response size":32,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-17T03:07:25.72481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.491117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T03:07:25.724843Z","caller":"traceutil/trace.go:171","msg":"trace[510590937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23051; }","duration":"127.60984ms","start":"2026-04-17T03:07:25.597225Z","end":"2026-04-17T03:07:25.724835Z","steps":["trace[510590937] 'agreement among raft nodes before linearized reading' (duration: 127.494567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.724923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.505656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-04-17T03:07:25.724957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.349354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-17T03:07:25.724987Z","caller":"traceutil/trace.go:171","msg":"trace[665986637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23051; }","duration":"271.409556ms","start":"2026-04-17T03:07:25.453565Z","end":"2026-04-17T03:07:25.724975Z","steps":["trace[665986637] 'agreement among raft nodes before linearized reading' (duration: 271.342964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.725057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.103767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-17T03:07:25.725109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.323278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-17T03:07:25.725114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.868762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-17T03:07:25.725139Z","caller":"traceutil/trace.go:171","msg":"trace[1749279731] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23051; }","duration":"210.352339ms","start":"2026-04-17T03:07:25.514779Z","end":"2026-04-17T03:07:25.725131Z","steps":["trace[1749279731] 'agreement among raft nodes before linearized reading' (duration: 210.273767ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:07:25.725165Z","caller":"traceutil/trace.go:171","msg":"trace[1090098878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23051; }","duration":"132.959865ms","start":"2026-04-17T03:07:25.592194Z","end":"2026-04-17T03:07:25.725154Z","steps":["trace[1090098878] 'agreement among raft nodes before linearized reading' (duration: 132.815891ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:07:25.724985Z","caller":"traceutil/trace.go:171","msg":"trace[1144689799] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23051; }","duration":"198.590778ms","start":"2026-04-17T03:07:25.526381Z","end":"2026-04-17T03:07:25.724972Z","steps":["trace[1144689799] 'agreement among raft nodes before linearized reading' (duration: 198.336712ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T03:07:25.725116Z","caller":"traceutil/trace.go:171","msg":"trace[1116054508] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:23051; }","duration":"348.194638ms","start":"2026-04-17T03:07:25.376907Z","end":"2026-04-17T03:07:25.725101Z","steps":["trace[1116054508] 'agreement among raft nodes before linearized reading' (duration: 348.087356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T03:07:25.725316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T03:07:25.376883Z","time spent":"348.413454ms","remote":"127.0.0.1:44830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-17T03:08:40.615083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20686} {"level":"info","ts":"2026-04-17T03:08:40.770697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20686,"took":"152.38159ms","hash":4174113538,"current-db-size-bytes":63799296,"current-db-size":"64 MB","current-db-size-in-use-bytes":35823616,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-17T03:08:40.770784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4174113538,"revision":20686,"compact-revision":17387} {"level":"info","ts":"2026-04-17T03:13:40.621842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23612} {"level":"info","ts":"2026-04-17T03:13:40.776362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23612,"took":"149.783153ms","hash":949465206,"current-db-size-bytes":63799296,"current-db-size":"64 MB","current-db-size-in-use-bytes":32747520,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-17T03:13:40.77646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":949465206,"revision":23612,"compact-revision":20686} {"level":"info","ts":"2026-04-17T03:15:33.173282Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b68.wal"} {"level":"info","ts":"2026-04-17T03:18:40.628093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25837} {"level":"info","ts":"2026-04-17T03:18:40.742986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25837,"took":"111.061985ms","hash":2687596082,"current-db-size-bytes":63799296,"current-db-size":"64 MB","current-db-size-in-use-bytes":33452032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T03:18:40.743038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2687596082,"revision":25837,"compact-revision":23612} {"level":"info","ts":"2026-04-17T03:21:00.715145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"80be33c6b0d5a492","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T03:21:00.718736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-17T03:21:00.718898Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}