{"level":"warn","ts":"2026-07-17T03:33:57.533791Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T03:33:57.534029Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-07-17T03:33:57.534088Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T03:33:57.534099Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-17T03:33:57.534125Z","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-07-17T03:33:57.53471Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-07-17T03:33:57.534806Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-07-17T03:33:57.541338Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.362358ms"} {"level":"info","ts":"2026-07-17T03:33:57.554025Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-07-17T03:33:57.554097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T03:33:57.554127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-07-17T03:33:57.554141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T03:33:57.554152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-07-17T03:33:57.554188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-07-17T03:33:57.563743Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T03:33:57.565884Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T03:33:57.571116Z","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-07-17T03:33:57.574941Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T03:33:57.575376Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T03:33:57.575744Z","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-07-17T03:33:57.575819Z","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-07-17T03:33:57.575828Z","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-07-17T03:33:57.577953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-07-17T03:33:57.578216Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-17T03:33:57.584263Z","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-07-17T03:33:57.584481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-17T03:33:57.584524Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-17T03:33:57.584515Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T03:33:57.584548Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T03:33:57.755387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T03:33:57.755476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T03:33:57.755507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-07-17T03:33:57.755528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-07-17T03:33:57.75554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-17T03:33:57.755562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-07-17T03:33:57.755582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-17T03:33:57.757979Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:33:57.758598Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:33:57.758591Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T03:33:57.758907Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:33:57.759158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:33:57.759312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T03:33:57.759358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T03:33:57.759395Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:33:57.759431Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:33:57.764454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T03:33:57.766493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-07-17T03:34:02.010792Z","caller":"traceutil/trace.go:171","msg":"trace[940472190] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"102.246853ms","start":"2026-07-17T03:34:01.908519Z","end":"2026-07-17T03:34:02.010765Z","steps":["trace[940472190] 'process raft request' (duration: 102.109922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:02.800109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.092383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:34:02.800326Z","caller":"traceutil/trace.go:171","msg":"trace[617357256] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"323.306402ms","start":"2026-07-17T03:34:02.477001Z","end":"2026-07-17T03:34:02.800307Z","steps":["trace[617357256] 'process raft request' (duration: 323.230471ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:02.800307Z","caller":"traceutil/trace.go:171","msg":"trace[317639191] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"343.939977ms","start":"2026-07-17T03:34:02.456331Z","end":"2026-07-17T03:34:02.800271Z","steps":["trace[317639191] 'process raft request' (duration: 34.058896ms)","trace[317639191] 'compare' (duration: 308.972052ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:02.800327Z","caller":"traceutil/trace.go:171","msg":"trace[326196654] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"325.525101ms","start":"2026-07-17T03:34:02.474778Z","end":"2026-07-17T03:34:02.800303Z","steps":["trace[326196654] 'read index received' (duration: 15.62089ms)","trace[326196654] 'applied index is now lower than readState.Index' (duration: 309.900541ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:02.80054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:02.456325Z","time spent":"344.099548ms","remote":"127.0.0.1:35150","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-07-17T03:34:02.800423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:02.476987Z","time spent":"323.399433ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:34:02.800515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.717372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:02.800696Z","caller":"traceutil/trace.go:171","msg":"trace[1861262167] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:271; }","duration":"325.947245ms","start":"2026-07-17T03:34:02.474732Z","end":"2026-07-17T03:34:02.800679Z","steps":["trace[1861262167] 'agreement among raft nodes before linearized reading' (duration: 325.693453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:02.800737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:02.47471Z","time spent":"326.017836ms","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-07-17T03:34:02.821298Z","caller":"traceutil/trace.go:171","msg":"trace[1207641371] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"272.9956ms","start":"2026-07-17T03:34:02.54827Z","end":"2026-07-17T03:34:02.821265Z","steps":["trace[1207641371] 'process raft request' (duration: 272.822379ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:02.821406Z","caller":"traceutil/trace.go:171","msg":"trace[1762608619] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"220.993293ms","start":"2026-07-17T03:34:02.600372Z","end":"2026-07-17T03:34:02.821365Z","steps":["trace[1762608619] 'process raft request' (duration: 220.874232ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:02.821323Z","caller":"traceutil/trace.go:171","msg":"trace[1833136387] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"244.716086ms","start":"2026-07-17T03:34:02.576586Z","end":"2026-07-17T03:34:02.821302Z","steps":["trace[1833136387] 'process raft request' (duration: 244.620706ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:02.821567Z","caller":"traceutil/trace.go:171","msg":"trace[762571893] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"220.57362ms","start":"2026-07-17T03:34:02.600976Z","end":"2026-07-17T03:34:02.821549Z","steps":["trace[762571893] 'process raft request' (duration: 220.300247ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:02.821706Z","caller":"traceutil/trace.go:171","msg":"trace[1353084423] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"218.43183ms","start":"2026-07-17T03:34:02.60326Z","end":"2026-07-17T03:34:02.821692Z","steps":["trace[1353084423] 'process raft request' (duration: 218.208338ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:02.821776Z","caller":"traceutil/trace.go:171","msg":"trace[587024127] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"220.122156ms","start":"2026-07-17T03:34:02.601642Z","end":"2026-07-17T03:34:02.821764Z","steps":["trace[587024127] 'process raft request' (duration: 219.687572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:02.821797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.044894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:02.821847Z","caller":"traceutil/trace.go:171","msg":"trace[1258908704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:276; }","duration":"133.125005ms","start":"2026-07-17T03:34:02.688707Z","end":"2026-07-17T03:34:02.821832Z","steps":["trace[1258908704] 'agreement among raft nodes before linearized reading' (duration: 133.022024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:02.821934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.736513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-17T03:34:02.821973Z","caller":"traceutil/trace.go:171","msg":"trace[1249821042] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:276; }","duration":"345.808933ms","start":"2026-07-17T03:34:02.476154Z","end":"2026-07-17T03:34:02.821962Z","steps":["trace[1249821042] 'agreement among raft nodes before linearized reading' (duration: 345.722913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:02.822005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:02.476138Z","time spent":"345.861004ms","remote":"127.0.0.1:35272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-17T03:34:02.822016Z","caller":"traceutil/trace.go:171","msg":"trace[685484098] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"219.544671ms","start":"2026-07-17T03:34:02.60246Z","end":"2026-07-17T03:34:02.822005Z","steps":["trace[685484098] 'process raft request' (duration: 218.960916ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:03.070183Z","caller":"traceutil/trace.go:171","msg":"trace[87216887] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"244.576071ms","start":"2026-07-17T03:34:02.825565Z","end":"2026-07-17T03:34:03.070141Z","steps":["trace[87216887] 'process raft request' (duration: 150.072616ms)","trace[87216887] 'compare' (duration: 94.285453ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:03.070229Z","caller":"traceutil/trace.go:171","msg":"trace[873213509] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"242.365062ms","start":"2026-07-17T03:34:02.827831Z","end":"2026-07-17T03:34:03.070196Z","steps":["trace[873213509] 'process raft request' (duration: 242.2327ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:03.070214Z","caller":"traceutil/trace.go:171","msg":"trace[1453453408] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"230.657377ms","start":"2026-07-17T03:34:02.839535Z","end":"2026-07-17T03:34:03.070192Z","steps":["trace[1453453408] 'process raft request' (duration: 230.619486ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:03.070406Z","caller":"traceutil/trace.go:171","msg":"trace[996372155] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"230.939819ms","start":"2026-07-17T03:34:02.839446Z","end":"2026-07-17T03:34:03.070386Z","steps":["trace[996372155] 'process raft request' (duration: 230.675857ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:03.070459Z","caller":"traceutil/trace.go:171","msg":"trace[169194164] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:285; }","duration":"233.739824ms","start":"2026-07-17T03:34:02.836637Z","end":"2026-07-17T03:34:03.070377Z","steps":["trace[169194164] 'read index received' (duration: 139.003737ms)","trace[169194164] 'applied index is now lower than readState.Index' (duration: 94.734427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:03.070665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.75742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-07-17T03:34:03.070665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.626513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-17T03:34:03.070706Z","caller":"traceutil/trace.go:171","msg":"trace[976958312] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:281; }","duration":"247.840061ms","start":"2026-07-17T03:34:02.822856Z","end":"2026-07-17T03:34:03.070696Z","steps":["trace[976958312] 'agreement among raft nodes before linearized reading' (duration: 247.66319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:03.070681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.957483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-07-17T03:34:03.070717Z","caller":"traceutil/trace.go:171","msg":"trace[358813453] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:281; }","duration":"243.739304ms","start":"2026-07-17T03:34:02.826965Z","end":"2026-07-17T03:34:03.070704Z","steps":["trace[358813453] 'agreement among raft nodes before linearized reading' (duration: 243.557253ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:03.070756Z","caller":"traceutil/trace.go:171","msg":"trace[876195781] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:281; }","duration":"247.072724ms","start":"2026-07-17T03:34:02.823669Z","end":"2026-07-17T03:34:03.070742Z","steps":["trace[876195781] 'agreement among raft nodes before linearized reading' (duration: 246.898632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:25.246621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.557108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:25.246704Z","caller":"traceutil/trace.go:171","msg":"trace[1518854377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"181.673584ms","start":"2026-07-17T03:34:25.065014Z","end":"2026-07-17T03:34:25.246687Z","steps":["trace[1518854377] 'range keys from in-memory index tree' (duration: 181.513556ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:27.166187Z","caller":"traceutil/trace.go:171","msg":"trace[1818230630] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"101.348862ms","start":"2026-07-17T03:34:27.064809Z","end":"2026-07-17T03:34:27.166158Z","steps":["trace[1818230630] 'read index received' (duration: 101.150763ms)","trace[1818230630] 'applied index is now lower than readState.Index' (duration: 197.149µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:27.166325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.508689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:27.166358Z","caller":"traceutil/trace.go:171","msg":"trace[1800823560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"101.603333ms","start":"2026-07-17T03:34:27.064744Z","end":"2026-07-17T03:34:27.166347Z","steps":["trace[1800823560] 'agreement among raft nodes before linearized reading' (duration: 101.52567ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:27.166479Z","caller":"traceutil/trace.go:171","msg":"trace[2085294177] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"242.821668ms","start":"2026-07-17T03:34:26.923629Z","end":"2026-07-17T03:34:27.166451Z","steps":["trace[2085294177] 'process raft request' (duration: 242.378418ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:34.513563Z","caller":"traceutil/trace.go:171","msg":"trace[1244100080] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"480.513313ms","start":"2026-07-17T03:34:34.032506Z","end":"2026-07-17T03:34:34.513019Z","steps":["trace[1244100080] 'process raft request' (duration: 480.309314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.513979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.032489Z","time spent":"481.234443ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:34.51471Z","caller":"traceutil/trace.go:171","msg":"trace[247418021] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"481.444522ms","start":"2026-07-17T03:34:34.033125Z","end":"2026-07-17T03:34:34.514569Z","steps":["trace[247418021] 'process raft request' (duration: 479.808804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.514909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.033119Z","time spent":"481.692943ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.515199Z","caller":"traceutil/trace.go:171","msg":"trace[1925380009] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"480.349546ms","start":"2026-07-17T03:34:34.034682Z","end":"2026-07-17T03:34:34.515032Z","steps":["trace[1925380009] 'process raft request' (duration: 479.814353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.515339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.034678Z","time spent":"480.593187ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.515581Z","caller":"traceutil/trace.go:171","msg":"trace[425600027] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"481.538356ms","start":"2026-07-17T03:34:34.03402Z","end":"2026-07-17T03:34:34.515558Z","steps":["trace[425600027] 'process raft request' (duration: 478.994919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.51567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.034013Z","time spent":"481.601558ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:34.515771Z","caller":"traceutil/trace.go:171","msg":"trace[237854536] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"481.64755ms","start":"2026-07-17T03:34:34.034112Z","end":"2026-07-17T03:34:34.51576Z","steps":["trace[237854536] 'process raft request' (duration: 479.467209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.515827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.034105Z","time spent":"481.695333ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.516299Z","caller":"traceutil/trace.go:171","msg":"trace[1306335754] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"481.464673ms","start":"2026-07-17T03:34:34.034446Z","end":"2026-07-17T03:34:34.51591Z","steps":["trace[1306335754] 'process raft request' (duration: 479.751681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.516372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.03444Z","time spent":"481.904962ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.517777Z","caller":"traceutil/trace.go:171","msg":"trace[2122495149] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"476.438453ms","start":"2026-07-17T03:34:34.041315Z","end":"2026-07-17T03:34:34.517753Z","steps":["trace[2122495149] 'process raft request' (duration: 473.470689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.517873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.041306Z","time spent":"476.527837ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:34.518776Z","caller":"traceutil/trace.go:171","msg":"trace[2103591108] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:548; }","duration":"456.99085ms","start":"2026-07-17T03:34:34.061732Z","end":"2026-07-17T03:34:34.518723Z","steps":["trace[2103591108] 'read index received' (duration: 3.212064ms)","trace[2103591108] 'applied index is now lower than readState.Index' (duration: 453.777846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:34.518971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.179108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:34.51898Z","caller":"traceutil/trace.go:171","msg":"trace[748010007] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"459.63529ms","start":"2026-07-17T03:34:34.059327Z","end":"2026-07-17T03:34:34.518962Z","steps":["trace[748010007] 'process raft request' (duration: 458.850047ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:34.519023Z","caller":"traceutil/trace.go:171","msg":"trace[571993137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"457.308463ms","start":"2026-07-17T03:34:34.0617Z","end":"2026-07-17T03:34:34.519008Z","steps":["trace[571993137] 'agreement among raft nodes before linearized reading' (duration: 457.128806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.519061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.059317Z","time spent":"459.706994ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:34:34.519064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.061685Z","time spent":"457.369096ms","remote":"127.0.0.1:34902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:34:34.51907Z","caller":"traceutil/trace.go:171","msg":"trace[2146741771] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"467.25277ms","start":"2026-07-17T03:34:34.050952Z","end":"2026-07-17T03:34:34.518204Z","steps":["trace[2146741771] 'process raft request' (duration: 467.128924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.519225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.050948Z","time spent":"468.204899ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.519297Z","caller":"traceutil/trace.go:171","msg":"trace[1702609441] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"459.980485ms","start":"2026-07-17T03:34:34.058214Z","end":"2026-07-17T03:34:34.518195Z","steps":["trace[1702609441] 'process raft request' (duration: 459.909782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.519374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.058205Z","time spent":"461.130673ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.519476Z","caller":"traceutil/trace.go:171","msg":"trace[2042688185] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"467.312342ms","start":"2026-07-17T03:34:34.050895Z","end":"2026-07-17T03:34:34.518207Z","steps":["trace[2042688185] 'process raft request' (duration: 466.360132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.519535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.050878Z","time spent":"468.632267ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.521661Z","caller":"traceutil/trace.go:171","msg":"trace[718517057] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"451.202768ms","start":"2026-07-17T03:34:34.07045Z","end":"2026-07-17T03:34:34.521653Z","steps":["trace[718517057] 'process raft request' (duration: 450.973078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.521698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.070425Z","time spent":"451.2572ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.521757Z","caller":"traceutil/trace.go:171","msg":"trace[772418438] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"442.374439ms","start":"2026-07-17T03:34:34.079377Z","end":"2026-07-17T03:34:34.521751Z","steps":["trace[772418438] 'process raft request' (duration: 442.102628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.521778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.079368Z","time spent":"442.399651ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:34.521829Z","caller":"traceutil/trace.go:171","msg":"trace[802228844] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"344.866874ms","start":"2026-07-17T03:34:34.176957Z","end":"2026-07-17T03:34:34.521824Z","steps":["trace[802228844] 'process raft request' (duration: 344.592453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:34.521848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:34.176929Z","time spent":"344.908996ms","remote":"127.0.0.1:34942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:34:39.564231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115748061362011,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:34:39.679684Z","caller":"traceutil/trace.go:171","msg":"trace[1141903223] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"615.470591ms","start":"2026-07-17T03:34:39.064196Z","end":"2026-07-17T03:34:39.679666Z","steps":["trace[1141903223] 'read index received' (duration: 614.853726ms)","trace[1141903223] 'applied index is now lower than readState.Index' (duration: 616.345µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:39.679896Z","caller":"traceutil/trace.go:171","msg":"trace[234192237] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"700.129963ms","start":"2026-07-17T03:34:38.979736Z","end":"2026-07-17T03:34:39.679866Z","steps":["trace[234192237] 'process raft request' (duration: 699.537009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:39.679986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.883351ms","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-07-17T03:34:39.680014Z","caller":"traceutil/trace.go:171","msg":"trace[2057912437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"391.960184ms","start":"2026-07-17T03:34:39.288046Z","end":"2026-07-17T03:34:39.680006Z","steps":["trace[2057912437] 'agreement among raft nodes before linearized reading' (duration: 391.828329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:39.68003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:39.288021Z","time spent":"392.005576ms","remote":"127.0.0.1:35150","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":"warn","ts":"2026-07-17T03:34:39.680032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:38.979704Z","time spent":"700.258429ms","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:34:39.680144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.754499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T03:34:39.680208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.525809ms","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-07-17T03:34:39.680229Z","caller":"traceutil/trace.go:171","msg":"trace[1593277448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"168.876254ms","start":"2026-07-17T03:34:39.511325Z","end":"2026-07-17T03:34:39.680201Z","steps":["trace[1593277448] 'agreement among raft nodes before linearized reading' (duration: 168.636235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:39.680245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.647608ms","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-07-17T03:34:39.680239Z","caller":"traceutil/trace.go:171","msg":"trace[543695183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"363.577221ms","start":"2026-07-17T03:34:39.316652Z","end":"2026-07-17T03:34:39.680229Z","steps":["trace[543695183] 'agreement among raft nodes before linearized reading' (duration: 363.479647ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:39.680263Z","caller":"traceutil/trace.go:171","msg":"trace[1070589392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"139.665898ms","start":"2026-07-17T03:34:39.540592Z","end":"2026-07-17T03:34:39.680258Z","steps":["trace[1070589392] 'agreement among raft nodes before linearized reading' (duration: 139.609856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:39.680273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:39.316641Z","time spent":"363.626413ms","remote":"127.0.0.1:35150","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-07-17T03:34:39.680395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.198779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:39.680496Z","caller":"traceutil/trace.go:171","msg":"trace[1547362936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"616.326585ms","start":"2026-07-17T03:34:39.064158Z","end":"2026-07-17T03:34:39.680484Z","steps":["trace[1547362936] 'agreement among raft nodes before linearized reading' (duration: 616.213501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:39.680532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:39.064137Z","time spent":"616.389058ms","remote":"127.0.0.1:34902","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-07-17T03:34:40.052507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.042288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:34:40.052591Z","caller":"traceutil/trace.go:171","msg":"trace[1533826182] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"328.796204ms","start":"2026-07-17T03:34:39.723776Z","end":"2026-07-17T03:34:40.052572Z","steps":["trace[1533826182] 'process raft request' (duration: 226.61527ms)","trace[1533826182] 'compare' (duration: 101.948365ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:40.052638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:39.723758Z","time spent":"328.861157ms","remote":"127.0.0.1:35212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:34:40.106965Z","caller":"traceutil/trace.go:171","msg":"trace[2033463847] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"102.988393ms","start":"2026-07-17T03:34:40.003957Z","end":"2026-07-17T03:34:40.106945Z","steps":["trace[2033463847] 'process raft request' (duration: 102.741404ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:41.091345Z","caller":"traceutil/trace.go:171","msg":"trace[1653438579] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"425.071399ms","start":"2026-07-17T03:34:40.666215Z","end":"2026-07-17T03:34:41.091286Z","steps":["trace[1653438579] 'read index received' (duration: 418.36916ms)","trace[1653438579] 'applied index is now lower than readState.Index' (duration: 6.701339ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:41.091432Z","caller":"traceutil/trace.go:171","msg":"trace[1969919454] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"597.895276ms","start":"2026-07-17T03:34:40.493482Z","end":"2026-07-17T03:34:41.091377Z","steps":["trace[1969919454] 'process raft request' (duration: 595.598397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:41.091523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.293338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T03:34:41.091561Z","caller":"traceutil/trace.go:171","msg":"trace[36326393] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:628; }","duration":"425.386082ms","start":"2026-07-17T03:34:40.666164Z","end":"2026-07-17T03:34:41.09155Z","steps":["trace[36326393] 'agreement among raft nodes before linearized reading' (duration: 425.292078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:41.091595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:40.666144Z","time spent":"425.443723ms","remote":"127.0.0.1:34922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"warn","ts":"2026-07-17T03:34:41.091609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:40.493445Z","time spent":"598.068163ms","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:41.307624Z","caller":"traceutil/trace.go:171","msg":"trace[544366961] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"157.725421ms","start":"2026-07-17T03:34:41.149879Z","end":"2026-07-17T03:34:41.307604Z","steps":["trace[544366961] 'process raft request' (duration: 157.628827ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:41.970388Z","caller":"traceutil/trace.go:171","msg":"trace[1990778835] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"189.768543ms","start":"2026-07-17T03:34:41.780554Z","end":"2026-07-17T03:34:41.970322Z","steps":["trace[1990778835] 'process raft request' (duration: 188.234933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:42.440705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.952787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:34:42.440835Z","caller":"traceutil/trace.go:171","msg":"trace[1425160736] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"399.568028ms","start":"2026-07-17T03:34:42.041246Z","end":"2026-07-17T03:34:42.440814Z","steps":["trace[1425160736] 'process raft request' (duration: 44.423553ms)","trace[1425160736] 'compare' (duration: 354.788451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:42.440937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:42.041218Z","time spent":"399.691563ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:34:43.379088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.062738ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:43.379651Z","caller":"traceutil/trace.go:171","msg":"trace[558192639] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:636; }","duration":"435.641261ms","start":"2026-07-17T03:34:42.943985Z","end":"2026-07-17T03:34:43.379626Z","steps":["trace[558192639] 'range keys from in-memory index tree' (duration: 435.043738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:43.379643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.485394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:34:43.379976Z","caller":"traceutil/trace.go:171","msg":"trace[1981075065] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"1.316135923s","start":"2026-07-17T03:34:42.063823Z","end":"2026-07-17T03:34:43.379959Z","steps":["trace[1981075065] 'read index received' (duration: 21.93535ms)","trace[1981075065] 'applied index is now lower than readState.Index' (duration: 1.294198983s)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:43.379994Z","caller":"traceutil/trace.go:171","msg":"trace[1745985013] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.335985614s","start":"2026-07-17T03:34:42.043985Z","end":"2026-07-17T03:34:43.37997Z","steps":["trace[1745985013] 'process raft request' (duration: 701.911638ms)","trace[1745985013] 'compare' (duration: 633.137871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:43.380098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316267139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:43.380141Z","caller":"traceutil/trace.go:171","msg":"trace[1712720111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.316338811s","start":"2026-07-17T03:34:42.06379Z","end":"2026-07-17T03:34:43.380128Z","steps":["trace[1712720111] 'agreement among raft nodes before linearized reading' (duration: 1.316231957s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:43.380188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:42.063777Z","time spent":"1.316399743s","remote":"127.0.0.1:34902","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-07-17T03:34:43.380089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:42.04396Z","time spent":"1.336080958s","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:34:43.909349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.759011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k7v7v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-17T03:34:43.909446Z","caller":"traceutil/trace.go:171","msg":"trace[1170399688] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k7v7v; range_end:; response_count:1; response_revision:639; }","duration":"298.895087ms","start":"2026-07-17T03:34:43.610529Z","end":"2026-07-17T03:34:43.909424Z","steps":["trace[1170399688] 'range keys from in-memory index tree' (duration: 297.065037ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:43.909777Z","caller":"traceutil/trace.go:171","msg":"trace[1517629482] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"147.738773ms","start":"2026-07-17T03:34:43.762012Z","end":"2026-07-17T03:34:43.909751Z","steps":["trace[1517629482] 'read index received' (duration: 64.751456ms)","trace[1517629482] 'applied index is now lower than readState.Index' (duration: 82.986267ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:43.90996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.941551ms","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-07-17T03:34:43.910008Z","caller":"traceutil/trace.go:171","msg":"trace[2084108377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"148.034074ms","start":"2026-07-17T03:34:43.761962Z","end":"2026-07-17T03:34:43.909996Z","steps":["trace[2084108377] 'agreement among raft nodes before linearized reading' (duration: 147.91603ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:43.90993Z","caller":"traceutil/trace.go:171","msg":"trace[358890923] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"225.720801ms","start":"2026-07-17T03:34:43.68417Z","end":"2026-07-17T03:34:43.90989Z","steps":["trace[358890923] 'process raft request' (duration: 142.547847ms)","trace[358890923] 'compare' (duration: 82.915125ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:44.179729Z","caller":"traceutil/trace.go:171","msg":"trace[560645197] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"390.147368ms","start":"2026-07-17T03:34:43.789567Z","end":"2026-07-17T03:34:44.179715Z","steps":["trace[560645197] 'process raft request' (duration: 389.521394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:44.179842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:43.78955Z","time spent":"390.229041ms","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:34:44.179888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.570837ms","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-07-17T03:34:44.179716Z","caller":"traceutil/trace.go:171","msg":"trace[65345837] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"269.854545ms","start":"2026-07-17T03:34:43.909842Z","end":"2026-07-17T03:34:44.179697Z","steps":["trace[65345837] 'read index received' (duration: 269.049345ms)","trace[65345837] 'applied index is now lower than readState.Index' (duration: 804.48µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:44.179934Z","caller":"traceutil/trace.go:171","msg":"trace[2015637274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"398.63891ms","start":"2026-07-17T03:34:43.781283Z","end":"2026-07-17T03:34:44.179922Z","steps":["trace[2015637274] 'agreement among raft nodes before linearized reading' (duration: 398.474113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:44.179963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:43.781272Z","time spent":"398.684032ms","remote":"127.0.0.1:35150","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-07-17T03:34:44.180074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.630017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-17T03:34:44.180161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.653849ms","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-07-17T03:34:44.18026Z","caller":"traceutil/trace.go:171","msg":"trace[943648840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"394.802214ms","start":"2026-07-17T03:34:43.785371Z","end":"2026-07-17T03:34:44.180173Z","steps":["trace[943648840] 'agreement among raft nodes before linearized reading' (duration: 394.43821ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:34:44.180326Z","caller":"traceutil/trace.go:171","msg":"trace[307338284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"394.905898ms","start":"2026-07-17T03:34:43.785398Z","end":"2026-07-17T03:34:44.180304Z","steps":["trace[307338284] 'agreement among raft nodes before linearized reading' (duration: 394.341887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:44.180385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:43.785372Z","time spent":"394.999942ms","remote":"127.0.0.1:35150","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":"warn","ts":"2026-07-17T03:34:44.180426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:43.785362Z","time spent":"394.971301ms","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:34:45.730683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409982507s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:34:45.731114Z","caller":"traceutil/trace.go:171","msg":"trace[488512297] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:659; }","duration":"666.570379ms","start":"2026-07-17T03:34:45.064525Z","end":"2026-07-17T03:34:45.731095Z","steps":["trace[488512297] 'read index received' (duration: 7.353813ms)","trace[488512297] 'applied index is now lower than readState.Index' (duration: 659.215526ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:45.731059Z","caller":"traceutil/trace.go:171","msg":"trace[97376274] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.814527295s","start":"2026-07-17T03:34:43.91646Z","end":"2026-07-17T03:34:45.730987Z","steps":["trace[97376274] 'process raft request' (duration: 404.123812ms)","trace[97376274] 'compare' (duration: 1.40979877s)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:34:45.731145Z","caller":"traceutil/trace.go:171","msg":"trace[135807488] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.794128912s","start":"2026-07-17T03:34:43.936968Z","end":"2026-07-17T03:34:45.731097Z","steps":["trace[135807488] 'process raft request' (duration: 1.793859322s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:45.731217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.693763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:45.731248Z","caller":"traceutil/trace.go:171","msg":"trace[479176696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"666.757645ms","start":"2026-07-17T03:34:45.064481Z","end":"2026-07-17T03:34:45.731238Z","steps":["trace[479176696] 'agreement among raft nodes before linearized reading' (duration: 666.683362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:45.731283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:45.064463Z","time spent":"666.812028ms","remote":"127.0.0.1:34902","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-07-17T03:34:45.731409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:43.936949Z","time spent":"1.794283407s","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:34:45.731372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:43.916436Z","time spent":"1.814750783s","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:45.731675Z","caller":"traceutil/trace.go:171","msg":"trace[1534990988] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.546161178s","start":"2026-07-17T03:34:44.185503Z","end":"2026-07-17T03:34:45.731664Z","steps":["trace[1534990988] 'process raft request' (duration: 1.545422431s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:45.731749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:44.185482Z","time spent":"1.546227561s","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:45.731838Z","caller":"traceutil/trace.go:171","msg":"trace[660163551] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.546255311s","start":"2026-07-17T03:34:44.185574Z","end":"2026-07-17T03:34:45.731829Z","steps":["trace[660163551] 'process raft request' (duration: 1.54541452s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:45.731882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:44.185568Z","time spent":"1.546289192s","remote":"127.0.0.1:35150","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-07-17T03:34:45.73195Z","caller":"traceutil/trace.go:171","msg":"trace[193219815] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.546340674s","start":"2026-07-17T03:34:44.185602Z","end":"2026-07-17T03:34:45.731942Z","steps":["trace[193219815] 'process raft request' (duration: 1.5454221s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:45.731989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:44.185578Z","time spent":"1.546389376s","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:45.73208Z","caller":"traceutil/trace.go:171","msg":"trace[472361878] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.499008658s","start":"2026-07-17T03:34:44.233064Z","end":"2026-07-17T03:34:45.732073Z","steps":["trace[472361878] 'process raft request' (duration: 1.49798878s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:45.732123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:44.233037Z","time spent":"1.49906068s","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:34:47.565251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115748061362067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:34:47.995017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.879117583s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T03:34:48.001361Z","caller":"traceutil/trace.go:171","msg":"trace[1302491387] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.88673707s","start":"2026-07-17T03:34:46.114595Z","end":"2026-07-17T03:34:48.001332Z","steps":["trace[1302491387] 'process raft request' (duration: 1.882947092s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:48.001494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:46.114569Z","time spent":"1.886853974s","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:34:48.012138Z","caller":"traceutil/trace.go:171","msg":"trace[232243602] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"947.720018ms","start":"2026-07-17T03:34:47.064397Z","end":"2026-07-17T03:34:48.012117Z","steps":["trace[232243602] 'read index received' (duration: 930.843666ms)","trace[232243602] 'applied index is now lower than readState.Index' (duration: 16.875372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:34:48.012253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.848392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:34:48.012301Z","caller":"traceutil/trace.go:171","msg":"trace[344514600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"947.938135ms","start":"2026-07-17T03:34:47.064349Z","end":"2026-07-17T03:34:48.012288Z","steps":["trace[344514600] 'agreement among raft nodes before linearized reading' (duration: 947.859262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:48.012338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:34:47.064327Z","time spent":"948.002737ms","remote":"127.0.0.1:34896","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-07-17T03:34:48.01267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.846753ms","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-07-17T03:34:48.012709Z","caller":"traceutil/trace.go:171","msg":"trace[831989770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"275.920236ms","start":"2026-07-17T03:34:47.736778Z","end":"2026-07-17T03:34:48.012698Z","steps":["trace[831989770] 'agreement among raft nodes before linearized reading' (duration: 275.48466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:48.013085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.514888ms","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-07-17T03:34:48.013141Z","caller":"traceutil/trace.go:171","msg":"trace[358288308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"273.575079ms","start":"2026-07-17T03:34:47.739537Z","end":"2026-07-17T03:34:48.013112Z","steps":["trace[358288308] 'agreement among raft nodes before linearized reading' (duration: 272.712268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:48.013393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.61551ms","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-07-17T03:34:48.013474Z","caller":"traceutil/trace.go:171","msg":"trace[389635042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"276.734705ms","start":"2026-07-17T03:34:47.736726Z","end":"2026-07-17T03:34:48.013461Z","steps":["trace[389635042] 'agreement among raft nodes before linearized reading' (duration: 275.500971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:34:48.01361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.84594ms","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-07-17T03:34:48.013644Z","caller":"traceutil/trace.go:171","msg":"trace[1173121256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"273.923762ms","start":"2026-07-17T03:34:47.739712Z","end":"2026-07-17T03:34:48.013636Z","steps":["trace[1173121256] 'agreement among raft nodes before linearized reading' (duration: 272.542283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:04.215936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115748061362392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:35:04.243981Z","caller":"traceutil/trace.go:171","msg":"trace[335381126] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"528.460968ms","start":"2026-07-17T03:35:03.715496Z","end":"2026-07-17T03:35:04.243957Z","steps":["trace[335381126] 'read index received' (duration: 528.222591ms)","trace[335381126] 'applied index is now lower than readState.Index' (duration: 237.237µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:35:04.244031Z","caller":"traceutil/trace.go:171","msg":"trace[871181954] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"696.996385ms","start":"2026-07-17T03:35:03.547006Z","end":"2026-07-17T03:35:04.244002Z","steps":["trace[871181954] 'process raft request' (duration: 696.798768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:04.2442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:03.54699Z","time spent":"697.131128ms","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:35:04.244246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.737386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zt729\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-17T03:35:04.244291Z","caller":"traceutil/trace.go:171","msg":"trace[481766377] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zt729; range_end:; response_count:1; response_revision:812; }","duration":"528.833309ms","start":"2026-07-17T03:35:03.715446Z","end":"2026-07-17T03:35:04.244279Z","steps":["trace[481766377] 'agreement among raft nodes before linearized reading' (duration: 528.690835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:04.244326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:03.715429Z","time spent":"528.888751ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zt729\" "} {"level":"warn","ts":"2026-07-17T03:35:04.244607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.005501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T03:35:04.244642Z","caller":"traceutil/trace.go:171","msg":"trace[951720177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"156.057073ms","start":"2026-07-17T03:35:04.088576Z","end":"2026-07-17T03:35:04.244633Z","steps":["trace[951720177] 'agreement among raft nodes before linearized reading' (duration: 155.96876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:04.244842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.493225ms","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-07-17T03:35:04.244995Z","caller":"traceutil/trace.go:171","msg":"trace[1279812866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"154.66891ms","start":"2026-07-17T03:35:04.090299Z","end":"2026-07-17T03:35:04.244968Z","steps":["trace[1279812866] 'agreement among raft nodes before linearized reading' (duration: 154.241737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:04.245287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.683833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T03:35:04.246306Z","caller":"traceutil/trace.go:171","msg":"trace[1147977305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"157.829877ms","start":"2026-07-17T03:35:04.087537Z","end":"2026-07-17T03:35:04.245367Z","steps":["trace[1147977305] 'agreement among raft nodes before linearized reading' (duration: 156.976611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.853476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.365923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:35:08.853544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.184577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.853557Z","caller":"traceutil/trace.go:171","msg":"trace[481105539] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:864; }","duration":"290.488547ms","start":"2026-07-17T03:35:08.563051Z","end":"2026-07-17T03:35:08.85354Z","steps":["trace[481105539] 'range keys from in-memory index tree' (duration: 290.28526ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:08.853589Z","caller":"traceutil/trace.go:171","msg":"trace[205708056] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:864; }","duration":"171.260649ms","start":"2026-07-17T03:35:08.682311Z","end":"2026-07-17T03:35:08.853571Z","steps":["trace[205708056] 'count revisions from in-memory index tree' (duration: 171.066853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.853748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.868742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:35:08.85382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.772395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.853814Z","caller":"traceutil/trace.go:171","msg":"trace[1146294585] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:864; }","duration":"157.950255ms","start":"2026-07-17T03:35:08.695844Z","end":"2026-07-17T03:35:08.853794Z","steps":["trace[1146294585] 'range keys from in-memory index tree' (duration: 157.815471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.853834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.560029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.853893Z","caller":"traceutil/trace.go:171","msg":"trace[87776259] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:864; }","duration":"170.648601ms","start":"2026-07-17T03:35:08.683226Z","end":"2026-07-17T03:35:08.853874Z","steps":["trace[87776259] 'range keys from in-memory index tree' (duration: 170.406024ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:08.853851Z","caller":"traceutil/trace.go:171","msg":"trace[1129729818] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:864; }","duration":"141.819237ms","start":"2026-07-17T03:35:08.712023Z","end":"2026-07-17T03:35:08.853842Z","steps":["trace[1129729818] 'range keys from in-memory index tree' (duration: 141.713293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.854123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.015292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.854155Z","caller":"traceutil/trace.go:171","msg":"trace[59152773] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:864; }","duration":"291.051773ms","start":"2026-07-17T03:35:08.563095Z","end":"2026-07-17T03:35:08.854147Z","steps":["trace[59152773] 'count revisions from in-memory index tree' (duration: 290.932839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.854206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.625242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050272"} {"level":"info","ts":"2026-07-17T03:35:08.854264Z","caller":"traceutil/trace.go:171","msg":"trace[2104059259] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:864; }","duration":"343.714675ms","start":"2026-07-17T03:35:08.510534Z","end":"2026-07-17T03:35:08.854249Z","steps":["trace[2104059259] 'range keys from in-memory index tree' (duration: 342.54448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.854254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.716103ms","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:5"} {"level":"warn","ts":"2026-07-17T03:35:08.854309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:08.510514Z","time spent":"343.784837ms","remote":"127.0.0.1:34984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050295,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-17T03:35:08.854319Z","caller":"traceutil/trace.go:171","msg":"trace[717175410] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:864; }","duration":"142.798135ms","start":"2026-07-17T03:35:08.711506Z","end":"2026-07-17T03:35:08.854304Z","steps":["trace[717175410] 'count revisions from in-memory index tree' (duration: 142.678242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.853483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.218328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.855447Z","caller":"traceutil/trace.go:171","msg":"trace[1939906964] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:864; }","duration":"173.206047ms","start":"2026-07-17T03:35:08.682217Z","end":"2026-07-17T03:35:08.855423Z","steps":["trace[1939906964] 'range keys from in-memory index tree' (duration: 171.160437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.854067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.412048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.856113Z","caller":"traceutil/trace.go:171","msg":"trace[1925931158] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:864; }","duration":"160.47673ms","start":"2026-07-17T03:35:08.69561Z","end":"2026-07-17T03:35:08.856087Z","steps":["trace[1925931158] 'count revisions from in-memory index tree' (duration: 157.935365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:08.854308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.987241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:35:08.85431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.295505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:35:08.857113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.187077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:08.857188Z","caller":"traceutil/trace.go:171","msg":"trace[146678077] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:864; }","duration":"145.720822ms","start":"2026-07-17T03:35:08.711446Z","end":"2026-07-17T03:35:08.857166Z","steps":["trace[146678077] 'range keys from in-memory index tree' (duration: 143.077954ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:08.857111Z","caller":"traceutil/trace.go:171","msg":"trace[316677438] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:864; }","duration":"293.776414ms","start":"2026-07-17T03:35:08.563282Z","end":"2026-07-17T03:35:08.857058Z","steps":["trace[316677438] 'range keys from in-memory index tree' (duration: 290.934569ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:08.857206Z","caller":"traceutil/trace.go:171","msg":"trace[322818265] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:864; }","duration":"161.544192ms","start":"2026-07-17T03:35:08.695517Z","end":"2026-07-17T03:35:08.857061Z","steps":["trace[322818265] 'range keys from in-memory index tree' (duration: 158.232073ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:17.59678Z","caller":"traceutil/trace.go:171","msg":"trace[1723777693] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"239.054709ms","start":"2026-07-17T03:35:17.357703Z","end":"2026-07-17T03:35:17.596757Z","steps":["trace[1723777693] 'process raft request' (duration: 238.903915ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:19.776876Z","caller":"traceutil/trace.go:171","msg":"trace[400414138] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"262.343509ms","start":"2026-07-17T03:35:19.514521Z","end":"2026-07-17T03:35:19.776865Z","steps":["trace[400414138] 'process raft request' (duration: 262.272417ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:30.939505Z","caller":"traceutil/trace.go:171","msg":"trace[1286802364] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"222.168175ms","start":"2026-07-17T03:35:30.717304Z","end":"2026-07-17T03:35:30.939472Z","steps":["trace[1286802364] 'process raft request' (duration: 200.504921ms)","trace[1286802364] 'compare' (duration: 21.532711ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:35:31.597764Z","caller":"traceutil/trace.go:171","msg":"trace[994461651] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"243.782061ms","start":"2026-07-17T03:35:31.353952Z","end":"2026-07-17T03:35:31.597734Z","steps":["trace[994461651] 'process raft request' (duration: 243.590207ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:31.598516Z","caller":"traceutil/trace.go:171","msg":"trace[1574621598] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1057; }","duration":"198.970176ms","start":"2026-07-17T03:35:31.399526Z","end":"2026-07-17T03:35:31.598496Z","steps":["trace[1574621598] 'read index received' (duration: 197.980992ms)","trace[1574621598] 'applied index is now lower than readState.Index' (duration: 987.764µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:35:31.598737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.187821ms","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-07-17T03:35:31.598795Z","caller":"traceutil/trace.go:171","msg":"trace[609677659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"199.286313ms","start":"2026-07-17T03:35:31.399493Z","end":"2026-07-17T03:35:31.59878Z","steps":["trace[609677659] 'agreement among raft nodes before linearized reading' (duration: 199.093248ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:31.598937Z","caller":"traceutil/trace.go:171","msg":"trace[2101842065] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"244.94373ms","start":"2026-07-17T03:35:31.353967Z","end":"2026-07-17T03:35:31.598911Z","steps":["trace[2101842065] 'process raft request' (duration: 244.375016ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:31.598964Z","caller":"traceutil/trace.go:171","msg":"trace[1013166595] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"243.846953ms","start":"2026-07-17T03:35:31.355098Z","end":"2026-07-17T03:35:31.598945Z","steps":["trace[1013166595] 'process raft request' (duration: 243.341861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:54.945644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.223017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:35:54.945817Z","caller":"traceutil/trace.go:171","msg":"trace[1721291038] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"1.039659088s","start":"2026-07-17T03:35:53.906141Z","end":"2026-07-17T03:35:54.9458Z","steps":["trace[1721291038] 'process raft request' (duration: 1.039603837s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:54.945878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:53.906117Z","time spent":"1.03973129s","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:35:54.945901Z","caller":"traceutil/trace.go:171","msg":"trace[706426681] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1138; }","duration":"1.239893866s","start":"2026-07-17T03:35:53.705976Z","end":"2026-07-17T03:35:54.94587Z","steps":["trace[706426681] 'read index received' (duration: 28.448716ms)","trace[706426681] 'applied index is now lower than readState.Index' (duration: 1.21144326s)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:35:54.94606Z","caller":"traceutil/trace.go:171","msg":"trace[936061519] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"1.240310454s","start":"2026-07-17T03:35:53.705727Z","end":"2026-07-17T03:35:54.946037Z","steps":["trace[936061519] 'process raft request' (duration: 464.612547ms)","trace[936061519] 'compare' (duration: 774.967872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:35:54.946189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:53.705713Z","time spent":"1.240388686s","remote":"127.0.0.1:35150","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-07-17T03:35:54.946566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24057723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-17T03:35:54.946577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.600614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:35:54.946627Z","caller":"traceutil/trace.go:171","msg":"trace[179051092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"882.661635ms","start":"2026-07-17T03:35:54.063953Z","end":"2026-07-17T03:35:54.946614Z","steps":["trace[179051092] 'agreement among raft nodes before linearized reading' (duration: 882.559503ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:35:54.94662Z","caller":"traceutil/trace.go:171","msg":"trace[1238210584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"1.240649181s","start":"2026-07-17T03:35:53.705955Z","end":"2026-07-17T03:35:54.946604Z","steps":["trace[1238210584] 'agreement among raft nodes before linearized reading' (duration: 1.240451087s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:54.94665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:54.063897Z","time spent":"882.749087ms","remote":"127.0.0.1:34902","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-07-17T03:35:54.946666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:53.705947Z","time spent":"1.240706993s","remote":"127.0.0.1:35150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:35:54.946658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238862193s","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-07-17T03:35:54.948428Z","caller":"traceutil/trace.go:171","msg":"trace[201764298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"1.240650431s","start":"2026-07-17T03:35:53.707756Z","end":"2026-07-17T03:35:54.948406Z","steps":["trace[201764298] 'agreement among raft nodes before linearized reading' (duration: 1.238745822s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:35:54.948581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:35:53.707749Z","time spent":"1.240816185s","remote":"127.0.0.1:35150","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-07-17T03:37:01.136134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:37:00.675835Z","time spent":"460.281781ms","remote":"127.0.0.1:34922","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}