{"level":"warn","ts":"2026-03-25T16:58:19.692054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:58:19.692145Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.240:2380","--initial-cluster=instance=https://199.19.213.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-25T16:58:19.692219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:58:19.692232Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.240:2380"]} {"level":"info","ts":"2026-03-25T16:58:19.692255Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-25T16:58:19.69418Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"]} {"level":"info","ts":"2026-03-25T16:58:19.694362Z","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.19.213.240:2380"],"listen-peer-urls":["https://199.19.213.240:2380"],"advertise-client-urls":["https://199.19.213.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-25T16:58:19.699706Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.990694ms"} {"level":"info","ts":"2026-03-25T16:58:19.71545Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"65ae7913d9d30912","cluster-id":"74cb0f8fbd7d5970"} {"level":"info","ts":"2026-03-25T16:58:19.715511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T16:58:19.715529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became follower at term 0"} {"level":"info","ts":"2026-03-25T16:58:19.715536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 65ae7913d9d30912 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T16:58:19.715915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became follower at term 1"} {"level":"info","ts":"2026-03-25T16:58:19.71596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=(7326926769944201490)"} {"level":"warn","ts":"2026-03-25T16:58:19.722866Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T16:58:19.72414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T16:58:19.726842Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-25T16:58:19.72904Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"65ae7913d9d30912","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T16:58:19.731579Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T16:58:19.731851Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T16:58:19.731872Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T16:58:19.731782Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"65ae7913d9d30912","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T16:58:19.732381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=(7326926769944201490)"} {"level":"info","ts":"2026-03-25T16:58:19.733243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"74cb0f8fbd7d5970","local-member-id":"65ae7913d9d30912","added-peer-id":"65ae7913d9d30912","added-peer-peer-urls":["https://199.19.213.240:2380"]} {"level":"info","ts":"2026-03-25T16:58:19.73519Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-25T16:58:19.735462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.240:2380"} {"level":"info","ts":"2026-03-25T16:58:19.735541Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.240:2380"} {"level":"info","ts":"2026-03-25T16:58:19.735683Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"65ae7913d9d30912","initial-advertise-peer-urls":["https://199.19.213.240:2380"],"listen-peer-urls":["https://199.19.213.240:2380"],"advertise-client-urls":["https://199.19.213.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T16:58:19.735735Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T16:58:19.916833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T16:58:19.917021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T16:58:19.917164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 received MsgPreVoteResp from 65ae7913d9d30912 at term 1"} {"level":"info","ts":"2026-03-25T16:58:19.917239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became candidate at term 2"} {"level":"info","ts":"2026-03-25T16:58:19.917336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 received MsgVoteResp from 65ae7913d9d30912 at term 2"} {"level":"info","ts":"2026-03-25T16:58:19.91745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became leader at term 2"} {"level":"info","ts":"2026-03-25T16:58:19.917568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 65ae7913d9d30912 elected leader 65ae7913d9d30912 at term 2"} {"level":"info","ts":"2026-03-25T16:58:19.924998Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"65ae7913d9d30912","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.240:2379]}","request-path":"/0/members/65ae7913d9d30912/attributes","cluster-id":"74cb0f8fbd7d5970","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T16:58:19.925026Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:58:19.925082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:58:19.925008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:58:19.925432Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T16:58:19.925661Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T16:58:19.925935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"74cb0f8fbd7d5970","local-member-id":"65ae7913d9d30912","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:58:19.926037Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:58:19.926066Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:58:19.931352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.240:2379"} {"level":"info","ts":"2026-03-25T16:58:19.931072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-25T16:58:21.442184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.086594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:21.442319Z","caller":"traceutil/trace.go:171","msg":"trace[1919289891] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"125.289683ms","start":"2026-03-25T16:58:21.317012Z","end":"2026-03-25T16:58:21.442302Z","steps":["trace[1919289891] 'agreement among raft nodes before linearized reading' (duration: 125.092254ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:22.226306Z","caller":"traceutil/trace.go:171","msg":"trace[1184259119] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"162.861947ms","start":"2026-03-25T16:58:22.063409Z","end":"2026-03-25T16:58:22.226271Z","steps":["trace[1184259119] 'process raft request' (duration: 130.901671ms)","trace[1184259119] 'compare' (duration: 31.647503ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:58:23.622792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.84801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-25T16:58:23.623072Z","caller":"traceutil/trace.go:171","msg":"trace[841671960] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:68; }","duration":"1.313092375s","start":"2026-03-25T16:58:22.30996Z","end":"2026-03-25T16:58:23.623052Z","steps":["trace[841671960] 'read index received' (duration: 371.467351ms)","trace[841671960] 'applied index is now lower than readState.Index' (duration: 941.623914ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:23.623169Z","caller":"traceutil/trace.go:171","msg":"trace[867766927] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"1.312831134s","start":"2026-03-25T16:58:22.310325Z","end":"2026-03-25T16:58:23.623156Z","steps":["trace[867766927] 'process raft request' (duration: 1.312680817s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:23.62328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313278042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-25T16:58:23.623244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:22.310317Z","time spent":"1.312886156s","remote":"127.0.0.1:53176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T16:58:23.623305Z","caller":"traceutil/trace.go:171","msg":"trace[1235689567] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"1.315558377s","start":"2026-03-25T16:58:22.307703Z","end":"2026-03-25T16:58:23.623262Z","steps":["trace[1235689567] 'process raft request' (duration: 373.627141ms)","trace[1235689567] 'compare' (duration: 940.59267ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:23.623343Z","caller":"traceutil/trace.go:171","msg":"trace[383297825] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:67; }","duration":"1.313383497s","start":"2026-03-25T16:58:22.309941Z","end":"2026-03-25T16:58:23.623324Z","steps":["trace[383297825] 'agreement among raft nodes before linearized reading' (duration: 1.313267372s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:23.623397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:22.309933Z","time spent":"1.313444969s","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-03-25T16:58:23.623441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:22.307678Z","time spent":"1.315697073s","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T16:58:23.623267Z","caller":"traceutil/trace.go:171","msg":"trace[1482917606] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"1.313804344s","start":"2026-03-25T16:58:22.309426Z","end":"2026-03-25T16:58:23.62323Z","steps":["trace[1482917606] 'process raft request' (duration: 1.31349988s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:23.624171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:22.309413Z","time spent":"1.314517433s","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1244,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:58:23.625823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293998811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:23.62588Z","caller":"traceutil/trace.go:171","msg":"trace[1098065398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:67; }","duration":"1.294075896s","start":"2026-03-25T16:58:22.33179Z","end":"2026-03-25T16:58:23.625865Z","steps":["trace[1098065398] 'agreement among raft nodes before linearized reading' (duration: 1.294000652s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:23.625912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:22.331776Z","time spent":"1.294128718s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T16:58:23.626141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:22.829115Z","time spent":"797.023194ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-25T16:58:23.62634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.979343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:23.626387Z","caller":"traceutil/trace.go:171","msg":"trace[446482357] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:67; }","duration":"410.089148ms","start":"2026-03-25T16:58:23.216287Z","end":"2026-03-25T16:58:23.626376Z","steps":["trace[446482357] 'agreement among raft nodes before linearized reading' (duration: 410.017055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:23.626444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.216269Z","time spent":"410.14048ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-25T16:58:23.62655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.590068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:23.626833Z","caller":"traceutil/trace.go:171","msg":"trace[1413017150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:67; }","duration":"144.751275ms","start":"2026-03-25T16:58:23.481892Z","end":"2026-03-25T16:58:23.626643Z","steps":["trace[1413017150] 'agreement among raft nodes before linearized reading' (duration: 144.609359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.126704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.164843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-25T16:58:24.12696Z","caller":"traceutil/trace.go:171","msg":"trace[1971602757] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"496.017306ms","start":"2026-03-25T16:58:23.630927Z","end":"2026-03-25T16:58:24.126944Z","steps":["trace[1971602757] 'process raft request' (duration: 495.960083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.127044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.630911Z","time spent":"496.094129ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1043,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:58:24.127169Z","caller":"traceutil/trace.go:171","msg":"trace[325974309] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:72; }","duration":"499.178867ms","start":"2026-03-25T16:58:23.627945Z","end":"2026-03-25T16:58:24.127124Z","steps":["trace[325974309] 'read index received' (duration: 350.559275ms)","trace[325974309] 'applied index is now lower than readState.Index' (duration: 148.617652ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:24.127199Z","caller":"traceutil/trace.go:171","msg":"trace[876919545] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"497.399353ms","start":"2026-03-25T16:58:23.629738Z","end":"2026-03-25T16:58:24.127137Z","steps":["trace[876919545] 'process raft request' (duration: 497.09987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.127263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.378436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:24.127217Z","caller":"traceutil/trace.go:171","msg":"trace[1677755020] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"499.148276ms","start":"2026-03-25T16:58:23.62783Z","end":"2026-03-25T16:58:24.126978Z","steps":["trace[1677755020] 'process raft request' (duration: 350.648699ms)","trace[1677755020] 'compare' (duration: 147.996376ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:24.127315Z","caller":"traceutil/trace.go:171","msg":"trace[234535523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"499.490131ms","start":"2026-03-25T16:58:23.627811Z","end":"2026-03-25T16:58:24.127301Z","steps":["trace[234535523] 'agreement among raft nodes before linearized reading' (duration: 499.401297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.127357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.627795Z","time spent":"499.554293ms","remote":"127.0.0.1:52822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T16:58:24.127362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.629717Z","time spent":"497.561321ms","remote":"127.0.0.1:53176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:58:24.127445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.627818Z","time spent":"499.537264ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:58:24.128073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.23995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-03-25T16:58:24.12812Z","caller":"traceutil/trace.go:171","msg":"trace[1105026045] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:71; }","duration":"499.346815ms","start":"2026-03-25T16:58:23.62876Z","end":"2026-03-25T16:58:24.128107Z","steps":["trace[1105026045] 'agreement among raft nodes before linearized reading' (duration: 499.202429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.128152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.628742Z","time spent":"499.403576ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-25T16:58:24.128166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.515304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:24.128216Z","caller":"traceutil/trace.go:171","msg":"trace[166073032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:71; }","duration":"282.608738ms","start":"2026-03-25T16:58:23.845593Z","end":"2026-03-25T16:58:24.128202Z","steps":["trace[166073032] 'agreement among raft nodes before linearized reading' (duration: 282.520714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.128217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.794742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:58:24.128266Z","caller":"traceutil/trace.go:171","msg":"trace[1797450020] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:71; }","duration":"498.937848ms","start":"2026-03-25T16:58:23.629316Z","end":"2026-03-25T16:58:24.128254Z","steps":["trace[1797450020] 'agreement among raft nodes before linearized reading' (duration: 498.830984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:24.128299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:23.629302Z","time spent":"498.98981ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-03-25T16:58:51.724017Z","caller":"traceutil/trace.go:171","msg":"trace[456726719] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"188.154867ms","start":"2026-03-25T16:58:51.535836Z","end":"2026-03-25T16:58:51.723991Z","steps":["trace[456726719] 'process raft request' (duration: 188.00109ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731247Z","caller":"traceutil/trace.go:171","msg":"trace[371351125] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"186.023156ms","start":"2026-03-25T16:58:51.545202Z","end":"2026-03-25T16:58:51.731225Z","steps":["trace[371351125] 'process raft request' (duration: 185.518333ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731512Z","caller":"traceutil/trace.go:171","msg":"trace[57467080] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"195.396827ms","start":"2026-03-25T16:58:51.53611Z","end":"2026-03-25T16:58:51.731507Z","steps":["trace[57467080] 'process raft request' (duration: 194.394571ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731566Z","caller":"traceutil/trace.go:171","msg":"trace[1892500199] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"195.396917ms","start":"2026-03-25T16:58:51.536165Z","end":"2026-03-25T16:58:51.731562Z","steps":["trace[1892500199] 'process raft request' (duration: 194.503905ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731637Z","caller":"traceutil/trace.go:171","msg":"trace[562999238] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"138.411025ms","start":"2026-03-25T16:58:51.59321Z","end":"2026-03-25T16:58:51.731621Z","steps":["trace[562999238] 'process raft request' (duration: 138.099371ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731642Z","caller":"traceutil/trace.go:171","msg":"trace[417357038] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"114.16736ms","start":"2026-03-25T16:58:51.617464Z","end":"2026-03-25T16:58:51.731632Z","steps":["trace[417357038] 'process raft request' (duration: 113.913538ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731759Z","caller":"traceutil/trace.go:171","msg":"trace[1454672972] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"182.153346ms","start":"2026-03-25T16:58:51.5496Z","end":"2026-03-25T16:58:51.731754Z","steps":["trace[1454672972] 'process raft request' (duration: 181.283745ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731793Z","caller":"traceutil/trace.go:171","msg":"trace[564836833] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"141.151294ms","start":"2026-03-25T16:58:51.590633Z","end":"2026-03-25T16:58:51.731785Z","steps":["trace[564836833] 'process raft request' (duration: 140.578607ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.73185Z","caller":"traceutil/trace.go:171","msg":"trace[1574463777] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"183.776821ms","start":"2026-03-25T16:58:51.548068Z","end":"2026-03-25T16:58:51.731845Z","steps":["trace[1574463777] 'process raft request' (duration: 182.736403ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.731884Z","caller":"traceutil/trace.go:171","msg":"trace[1922941879] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"181.168558ms","start":"2026-03-25T16:58:51.55071Z","end":"2026-03-25T16:58:51.731879Z","steps":["trace[1922941879] 'process raft request' (duration: 180.247516ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.732079Z","caller":"traceutil/trace.go:171","msg":"trace[1010382572] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"172.01129ms","start":"2026-03-25T16:58:51.560062Z","end":"2026-03-25T16:58:51.732073Z","steps":["trace[1010382572] 'process raft request' (duration: 170.986633ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.97388Z","caller":"traceutil/trace.go:171","msg":"trace[1261422445] transaction","detail":"{read_only:false; number_of_response:1; response_revision:540; }","duration":"184.013223ms","start":"2026-03-25T16:58:51.789816Z","end":"2026-03-25T16:58:51.973829Z","steps":["trace[1261422445] 'process raft request' (duration: 183.850556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:51.973999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.431976ms","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-03-25T16:58:51.974068Z","caller":"traceutil/trace.go:171","msg":"trace[457594705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"183.557692ms","start":"2026-03-25T16:58:51.790497Z","end":"2026-03-25T16:58:51.974054Z","steps":["trace[457594705] 'agreement among raft nodes before linearized reading' (duration: 183.403945ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.973845Z","caller":"traceutil/trace.go:171","msg":"trace[1680606625] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"183.255188ms","start":"2026-03-25T16:58:51.79053Z","end":"2026-03-25T16:58:51.973785Z","steps":["trace[1680606625] 'read index received' (duration: 183.093911ms)","trace[1680606625] 'applied index is now lower than readState.Index' (duration: 159.907µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:51.98647Z","caller":"traceutil/trace.go:171","msg":"trace[480174627] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"154.604345ms","start":"2026-03-25T16:58:51.831854Z","end":"2026-03-25T16:58:51.986459Z","steps":["trace[480174627] 'process raft request' (duration: 154.559943ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.98674Z","caller":"traceutil/trace.go:171","msg":"trace[1956057549] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"182.998445ms","start":"2026-03-25T16:58:51.803735Z","end":"2026-03-25T16:58:51.986733Z","steps":["trace[1956057549] 'process raft request' (duration: 182.45935ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.986829Z","caller":"traceutil/trace.go:171","msg":"trace[1483853536] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"176.68361ms","start":"2026-03-25T16:58:51.810139Z","end":"2026-03-25T16:58:51.986823Z","steps":["trace[1483853536] 'process raft request' (duration: 176.172156ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:51.986905Z","caller":"traceutil/trace.go:171","msg":"trace[698438146] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"172.486812ms","start":"2026-03-25T16:58:51.814413Z","end":"2026-03-25T16:58:51.9869Z","steps":["trace[698438146] 'process raft request' (duration: 171.930176ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:52.936152Z","caller":"traceutil/trace.go:171","msg":"trace[1352720479] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"110.669234ms","start":"2026-03-25T16:58:52.825452Z","end":"2026-03-25T16:58:52.936121Z","steps":["trace[1352720479] 'process raft request' (duration: 110.485395ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:53.135571Z","caller":"traceutil/trace.go:171","msg":"trace[460496402] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"214.677076ms","start":"2026-03-25T16:58:52.920865Z","end":"2026-03-25T16:58:53.135542Z","steps":["trace[460496402] 'process raft request' (duration: 120.056952ms)","trace[460496402] 'compare' (duration: 94.023616ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:53.283744Z","caller":"traceutil/trace.go:171","msg":"trace[1386313897] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"179.621106ms","start":"2026-03-25T16:58:53.104098Z","end":"2026-03-25T16:58:53.283719Z","steps":["trace[1386313897] 'process raft request' (duration: 174.883562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:58.547865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.308853ms","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-03-25T16:58:58.547993Z","caller":"traceutil/trace.go:171","msg":"trace[2036841532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"527.500952ms","start":"2026-03-25T16:58:58.02046Z","end":"2026-03-25T16:58:58.547961Z","steps":["trace[2036841532] 'range keys from in-memory index tree' (duration: 527.011928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:58.548046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.020439Z","time spent":"527.594536ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T16:58:58.658369Z","caller":"traceutil/trace.go:171","msg":"trace[853452904] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"396.692236ms","start":"2026-03-25T16:58:58.261651Z","end":"2026-03-25T16:58:58.658343Z","steps":["trace[853452904] 'read index received' (duration: 395.566141ms)","trace[853452904] 'applied index is now lower than readState.Index' (duration: 1.125085ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:58.658491Z","caller":"traceutil/trace.go:171","msg":"trace[1422304315] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"611.928504ms","start":"2026-03-25T16:58:58.046529Z","end":"2026-03-25T16:58:58.658457Z","steps":["trace[1422304315] 'process raft request' (duration: 611.170477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:58.658676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.046496Z","time spent":"612.090402ms","remote":"127.0.0.1:52904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:58:58.658696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.937418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-25T16:58:58.658752Z","caller":"traceutil/trace.go:171","msg":"trace[420161234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"397.144498ms","start":"2026-03-25T16:58:58.261589Z","end":"2026-03-25T16:58:58.658733Z","steps":["trace[420161234] 'agreement among raft nodes before linearized reading' (duration: 396.878055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:58.658799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.261555Z","time spent":"397.229981ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T16:58:59.629998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.201199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T16:58:59.630262Z","caller":"traceutil/trace.go:171","msg":"trace[1205525239] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.074786042s","start":"2026-03-25T16:58:58.555405Z","end":"2026-03-25T16:58:59.630191Z","steps":["trace[1205525239] 'process raft request' (duration: 525.265426ms)","trace[1205525239] 'compare' (duration: 549.038242ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:58:59.630353Z","caller":"traceutil/trace.go:171","msg":"trace[408830333] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"966.205851ms","start":"2026-03-25T16:58:58.664134Z","end":"2026-03-25T16:58:59.63034Z","steps":["trace[408830333] 'process raft request' (duration: 966.025032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:59.630424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.664116Z","time spent":"966.271354ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:58:59.63049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.55538Z","time spent":"1.074983941s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:58:59.63072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223875355s","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-03-25T16:58:59.630299Z","caller":"traceutil/trace.go:171","msg":"trace[1859411963] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"908.515534ms","start":"2026-03-25T16:58:58.721761Z","end":"2026-03-25T16:58:59.630277Z","steps":["trace[1859411963] 'process raft request' (duration: 908.455331ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:59.630782Z","caller":"traceutil/trace.go:171","msg":"trace[514037148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.224006441s","start":"2026-03-25T16:58:58.406763Z","end":"2026-03-25T16:58:59.630769Z","steps":["trace[514037148] 'agreement among raft nodes before linearized reading' (duration: 1.22377053s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:59.630816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.721694Z","time spent":"909.092651ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:58:59.630825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.406742Z","time spent":"1.224072965s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T16:58:59.630462Z","caller":"traceutil/trace.go:171","msg":"trace[1035952070] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"971.986488ms","start":"2026-03-25T16:58:58.658438Z","end":"2026-03-25T16:58:59.630424Z","steps":["trace[1035952070] 'read index received' (duration: 422.247932ms)","trace[1035952070] 'applied index is now lower than readState.Index' (duration: 549.735006ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:58:59.630937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.666451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q8djs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-25T16:58:59.630992Z","caller":"traceutil/trace.go:171","msg":"trace[1938330158] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q8djs; range_end:; response_count:1; response_revision:624; }","duration":"992.753394ms","start":"2026-03-25T16:58:58.638225Z","end":"2026-03-25T16:58:59.630978Z","steps":["trace[1938330158] 'agreement among raft nodes before linearized reading' (duration: 992.433369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:59.631028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:58.638205Z","time spent":"992.815237ms","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-q8djs\" "} {"level":"warn","ts":"2026-03-25T16:58:59.631233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.774773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:58:59.631341Z","caller":"traceutil/trace.go:171","msg":"trace[1698217740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"320.92946ms","start":"2026-03-25T16:58:59.310383Z","end":"2026-03-25T16:58:59.631313Z","steps":["trace[1698217740] 'agreement among raft nodes before linearized reading' (duration: 320.753232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:59.631422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:59.310357Z","time spent":"321.045236ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T16:59:00.284478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653757682167761166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T16:59:00.785329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653757682167761166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T16:59:01.152685Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.491797246s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T16:59:01.153567Z","caller":"traceutil/trace.go:171","msg":"trace[270225285] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.505318818s","start":"2026-03-25T16:58:59.648204Z","end":"2026-03-25T16:59:01.153523Z","steps":["trace[270225285] 'process raft request' (duration: 1.505032174s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.153911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:59.648193Z","time spent":"1.505449133s","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:59:01.225539Z","caller":"traceutil/trace.go:171","msg":"trace[1548973929] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.199127005s","start":"2026-03-25T16:59:00.026315Z","end":"2026-03-25T16:59:01.225442Z","steps":["trace[1548973929] 'process raft request' (duration: 1.19696494s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.226132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:00.026287Z","time spent":"1.199508264s","remote":"127.0.0.1:52904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:59:01.246157Z","caller":"traceutil/trace.go:171","msg":"trace[1823062134] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:644; }","duration":"1.462597729s","start":"2026-03-25T16:58:59.783516Z","end":"2026-03-25T16:59:01.246114Z","steps":["trace[1823062134] 'read index received' (duration: 1.369696119s)","trace[1823062134] 'applied index is now lower than readState.Index' (duration: 92.90004ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:59:01.246481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462892734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T16:59:01.246562Z","caller":"traceutil/trace.go:171","msg":"trace[1281906603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.463093084s","start":"2026-03-25T16:58:59.783456Z","end":"2026-03-25T16:59:01.246549Z","steps":["trace[1281906603] 'agreement among raft nodes before linearized reading' (duration: 1.462726886s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.246732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:58:59.78343Z","time spent":"1.463289043s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T16:59:01.79497Z","caller":"traceutil/trace.go:171","msg":"trace[2074016246] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"154.041023ms","start":"2026-03-25T16:59:01.640899Z","end":"2026-03-25T16:59:01.79494Z","steps":["trace[2074016246] 'process raft request' (duration: 150.582546ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:01.958317Z","caller":"traceutil/trace.go:171","msg":"trace[1580163775] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"317.126375ms","start":"2026-03-25T16:59:01.64114Z","end":"2026-03-25T16:59:01.958267Z","steps":["trace[1580163775] 'process raft request' (duration: 316.609269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.95856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:01.641133Z","time spent":"317.308054ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:59:01.95879Z","caller":"traceutil/trace.go:171","msg":"trace[341300617] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"310.570826ms","start":"2026-03-25T16:59:01.64815Z","end":"2026-03-25T16:59:01.958721Z","steps":["trace[341300617] 'process raft request' (duration: 309.846182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.959051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:01.648119Z","time spent":"310.805158ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T16:59:01.962552Z","caller":"traceutil/trace.go:171","msg":"trace[567712356] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"317.076531ms","start":"2026-03-25T16:59:01.645444Z","end":"2026-03-25T16:59:01.962521Z","steps":["trace[567712356] 'read index received' (duration: 146.14605ms)","trace[567712356] 'applied index is now lower than readState.Index' (duration: 170.929391ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:59:01.962832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.356374ms","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-03-25T16:59:01.962882Z","caller":"traceutil/trace.go:171","msg":"trace[926143190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"317.442599ms","start":"2026-03-25T16:59:01.645423Z","end":"2026-03-25T16:59:01.962866Z","steps":["trace[926143190] 'agreement among raft nodes before linearized reading' (duration: 317.26824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.962935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:01.645415Z","time spent":"317.505662ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T16:59:01.962936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.4059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q8djs\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-03-25T16:59:01.962999Z","caller":"traceutil/trace.go:171","msg":"trace[171107262] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q8djs; range_end:; response_count:1; response_revision:633; }","duration":"316.496903ms","start":"2026-03-25T16:59:01.646487Z","end":"2026-03-25T16:59:01.962984Z","steps":["trace[171107262] 'agreement among raft nodes before linearized reading' (duration: 316.265702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:01.963049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:01.646481Z","time spent":"316.559506ms","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17171,"request content":"key:\"/registry/pods/kube-system/cilium-q8djs\" "} {"level":"info","ts":"2026-03-25T16:59:01.962833Z","caller":"traceutil/trace.go:171","msg":"trace[357465872] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"242.248556ms","start":"2026-03-25T16:59:01.720512Z","end":"2026-03-25T16:59:01.96276Z","steps":["trace[357465872] 'process raft request' (duration: 240.702911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.652186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.583579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.652279Z","caller":"traceutil/trace.go:171","msg":"trace[924099383] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:643; }","duration":"228.728376ms","start":"2026-03-25T16:59:02.423527Z","end":"2026-03-25T16:59:02.652256Z","steps":["trace[924099383] 'count revisions from in-memory index tree' (duration: 228.525676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.652518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.163552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T16:59:02.6526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.039491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.652628Z","caller":"traceutil/trace.go:171","msg":"trace[1781662573] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:643; }","duration":"140.347021ms","start":"2026-03-25T16:59:02.512256Z","end":"2026-03-25T16:59:02.652603Z","steps":["trace[1781662573] 'range keys from in-memory index tree' (duration: 140.11149ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:02.652661Z","caller":"traceutil/trace.go:171","msg":"trace[1195009023] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:643; }","duration":"229.131926ms","start":"2026-03-25T16:59:02.423515Z","end":"2026-03-25T16:59:02.652647Z","steps":["trace[1195009023] 'range keys from in-memory index tree' (duration: 228.954517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.652943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.592484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.652993Z","caller":"traceutil/trace.go:171","msg":"trace[351144109] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:643; }","duration":"139.926241ms","start":"2026-03-25T16:59:02.513054Z","end":"2026-03-25T16:59:02.65298Z","steps":["trace[351144109] 'count revisions from in-memory index tree' (duration: 139.460808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.652995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.254097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653044Z","caller":"traceutil/trace.go:171","msg":"trace[1086303863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:643; }","duration":"204.32301ms","start":"2026-03-25T16:59:02.448709Z","end":"2026-03-25T16:59:02.653032Z","steps":["trace[1086303863] 'count revisions from in-memory index tree' (duration: 204.138712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.652943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.234291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653227Z","caller":"traceutil/trace.go:171","msg":"trace[1008440678] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:643; }","duration":"229.575718ms","start":"2026-03-25T16:59:02.423637Z","end":"2026-03-25T16:59:02.653213Z","steps":["trace[1008440678] 'range keys from in-memory index tree' (duration: 229.133857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.653262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.537051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653315Z","caller":"traceutil/trace.go:171","msg":"trace[2006700542] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:643; }","duration":"204.613564ms","start":"2026-03-25T16:59:02.44869Z","end":"2026-03-25T16:59:02.653303Z","steps":["trace[2006700542] 'range keys from in-memory index tree' (duration: 204.395204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.65348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.601634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653533Z","caller":"traceutil/trace.go:171","msg":"trace[1950355799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:643; }","duration":"204.755571ms","start":"2026-03-25T16:59:02.448764Z","end":"2026-03-25T16:59:02.65352Z","steps":["trace[1950355799] 'range keys from in-memory index tree' (duration: 204.51645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.65355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.31708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653597Z","caller":"traceutil/trace.go:171","msg":"trace[1951253878] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:643; }","duration":"190.394213ms","start":"2026-03-25T16:59:02.463191Z","end":"2026-03-25T16:59:02.653585Z","steps":["trace[1951253878] 'count revisions from in-memory index tree' (duration: 190.248996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.653776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.363782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653817Z","caller":"traceutil/trace.go:171","msg":"trace[51007379] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:643; }","duration":"190.535411ms","start":"2026-03-25T16:59:02.46327Z","end":"2026-03-25T16:59:02.653805Z","steps":["trace[51007379] 'range keys from in-memory index tree' (duration: 190.273607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:02.653952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.303659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:02.653991Z","caller":"traceutil/trace.go:171","msg":"trace[1835624655] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:643; }","duration":"190.361272ms","start":"2026-03-25T16:59:02.463619Z","end":"2026-03-25T16:59:02.65398Z","steps":["trace[1835624655] 'range keys from in-memory index tree' (duration: 190.238186ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:03.791661Z","caller":"traceutil/trace.go:171","msg":"trace[562215066] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"113.706824ms","start":"2026-03-25T16:59:03.677931Z","end":"2026-03-25T16:59:03.791637Z","steps":["trace[562215066] 'process raft request' (duration: 105.528575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:04.541674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.657092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T16:59:04.541912Z","caller":"traceutil/trace.go:171","msg":"trace[805980928] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:694; }","duration":"348.467181ms","start":"2026-03-25T16:59:04.193412Z","end":"2026-03-25T16:59:04.54188Z","steps":["trace[805980928] 'read index received' (duration: 65.416879ms)","trace[805980928] 'applied index is now lower than readState.Index' (duration: 283.049222ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:59:04.542279Z","caller":"traceutil/trace.go:171","msg":"trace[17804280] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"544.351198ms","start":"2026-03-25T16:59:03.997909Z","end":"2026-03-25T16:59:04.54226Z","steps":["trace[17804280] 'process raft request' (duration: 543.918797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:04.542334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.757484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T16:59:04.542374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:03.997901Z","time spent":"544.426692ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:59:04.542415Z","caller":"traceutil/trace.go:171","msg":"trace[575204714] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"148.905191ms","start":"2026-03-25T16:59:04.393494Z","end":"2026-03-25T16:59:04.5424Z","steps":["trace[575204714] 'agreement among raft nodes before linearized reading' (duration: 148.762044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:04.542369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.921843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:59:04.542485Z","caller":"traceutil/trace.go:171","msg":"trace[2129268415] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"349.101262ms","start":"2026-03-25T16:59:04.19336Z","end":"2026-03-25T16:59:04.542461Z","steps":["trace[2129268415] 'agreement among raft nodes before linearized reading' (duration: 348.874261ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:04.542314Z","caller":"traceutil/trace.go:171","msg":"trace[1099960430] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"547.317942ms","start":"2026-03-25T16:59:03.994945Z","end":"2026-03-25T16:59:04.542263Z","steps":["trace[1099960430] 'process raft request' (duration: 263.985827ms)","trace[1099960430] 'compare' (duration: 282.336787ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:59:04.542486Z","caller":"traceutil/trace.go:171","msg":"trace[421915962] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"546.540625ms","start":"2026-03-25T16:59:03.99575Z","end":"2026-03-25T16:59:04.54229Z","steps":["trace[421915962] 'process raft request' (duration: 546.02564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:04.542564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:04.193337Z","time spent":"349.211377ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-03-25T16:59:04.543844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:03.99574Z","time spent":"548.026788ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:59:04.542708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:03.994932Z","time spent":"547.637878ms","remote":"127.0.0.1:53258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:59:04.719108Z","caller":"traceutil/trace.go:171","msg":"trace[2045969925] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"107.204482ms","start":"2026-03-25T16:59:04.611887Z","end":"2026-03-25T16:59:04.719092Z","steps":["trace[2045969925] 'process raft request' (duration: 106.858975ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:07.075442Z","caller":"traceutil/trace.go:171","msg":"trace[1826587563] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"204.334653ms","start":"2026-03-25T16:59:06.871055Z","end":"2026-03-25T16:59:07.07539Z","steps":["trace[1826587563] 'process raft request' (duration: 200.579838ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:07.553148Z","caller":"traceutil/trace.go:171","msg":"trace[2056968220] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"108.42096ms","start":"2026-03-25T16:59:07.444675Z","end":"2026-03-25T16:59:07.553096Z","steps":["trace[2056968220] 'process raft request' (duration: 91.449893ms)","trace[2056968220] 'compare' (duration: 16.699554ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:59:16.179642Z","caller":"traceutil/trace.go:171","msg":"trace[1424873119] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"101.978ms","start":"2026-03-25T16:59:16.077632Z","end":"2026-03-25T16:59:16.17961Z","steps":["trace[1424873119] 'read index received' (duration: 101.672454ms)","trace[1424873119] 'applied index is now lower than readState.Index' (duration: 304.026µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:59:16.179914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.263334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-25T16:59:16.179957Z","caller":"traceutil/trace.go:171","msg":"trace[1798849916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"102.389911ms","start":"2026-03-25T16:59:16.077556Z","end":"2026-03-25T16:59:16.179946Z","steps":["trace[1798849916] 'agreement among raft nodes before linearized reading' (duration: 102.18499ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:16.18119Z","caller":"traceutil/trace.go:171","msg":"trace[457050912] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"238.416717ms","start":"2026-03-25T16:59:15.941748Z","end":"2026-03-25T16:59:16.180164Z","steps":["trace[457050912] 'process raft request' (duration: 237.626057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:19.792022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180477923s","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-03-25T16:59:19.792133Z","caller":"traceutil/trace.go:171","msg":"trace[517993220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.180659803s","start":"2026-03-25T16:59:18.611447Z","end":"2026-03-25T16:59:19.792107Z","steps":["trace[517993220] 'range keys from in-memory index tree' (duration: 1.180266141s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:19.792197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:18.611389Z","time spent":"1.18079181s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T16:59:19.879128Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049839698s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T16:59:19.879681Z","caller":"traceutil/trace.go:171","msg":"trace[1047278982] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"376.750308ms","start":"2026-03-25T16:59:19.502846Z","end":"2026-03-25T16:59:19.879597Z","steps":["trace[1047278982] 'read index received' (duration: 376.514226ms)","trace[1047278982] 'applied index is now lower than readState.Index' (duration: 235.172µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:59:19.879785Z","caller":"traceutil/trace.go:171","msg":"trace[832056124] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.050570646s","start":"2026-03-25T16:59:18.829193Z","end":"2026-03-25T16:59:19.879764Z","steps":["trace[832056124] 'process raft request' (duration: 1.05024802s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:19.879908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.051974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T16:59:19.879958Z","caller":"traceutil/trace.go:171","msg":"trace[1217143739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"377.150589ms","start":"2026-03-25T16:59:19.502794Z","end":"2026-03-25T16:59:19.879944Z","steps":["trace[1217143739] 'agreement among raft nodes before linearized reading' (duration: 376.984691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:19.879999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:19.502771Z","time spent":"377.216532ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T16:59:19.880039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:18.829171Z","time spent":"1.050779036s","remote":"127.0.0.1:53062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:59:20.156403Z","caller":"traceutil/trace.go:171","msg":"trace[1321974941] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"358.987293ms","start":"2026-03-25T16:59:19.797384Z","end":"2026-03-25T16:59:20.156371Z","steps":["trace[1321974941] 'process raft request' (duration: 357.605662ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:20.156417Z","caller":"traceutil/trace.go:171","msg":"trace[1010008425] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"270.703719ms","start":"2026-03-25T16:59:19.885689Z","end":"2026-03-25T16:59:20.156392Z","steps":["trace[1010008425] 'process raft request' (duration: 270.651887ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:59:20.156447Z","caller":"traceutil/trace.go:171","msg":"trace[32393792] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"276.661533ms","start":"2026-03-25T16:59:19.879751Z","end":"2026-03-25T16:59:20.156412Z","steps":["trace[32393792] 'read index received' (duration: 275.160546ms)","trace[32393792] 'applied index is now lower than readState.Index' (duration: 1.494577ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:59:20.15659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:19.797356Z","time spent":"359.142661ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:59:20.156664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.873832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T16:59:20.156701Z","caller":"traceutil/trace.go:171","msg":"trace[2037701427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"541.954415ms","start":"2026-03-25T16:59:19.614735Z","end":"2026-03-25T16:59:20.15669Z","steps":["trace[2037701427] 'agreement among raft nodes before linearized reading' (duration: 541.780277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:59:20.156746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:59:19.614719Z","time spent":"542.018609ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-25T17:01:26.035557Z","caller":"traceutil/trace.go:171","msg":"trace[1927398492] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"104.455947ms","start":"2026-03-25T17:01:25.931071Z","end":"2026-03-25T17:01:26.035527Z","steps":["trace[1927398492] 'process raft request' (duration: 104.275366ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:01:29.470385Z","caller":"traceutil/trace.go:171","msg":"trace[1569536464] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"103.155636ms","start":"2026-03-25T17:01:29.367175Z","end":"2026-03-25T17:01:29.470331Z","steps":["trace[1569536464] 'process raft request' (duration: 103.007707ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:01:31.352437Z","caller":"traceutil/trace.go:171","msg":"trace[1069020071] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"147.020711ms","start":"2026-03-25T17:01:31.20535Z","end":"2026-03-25T17:01:31.352371Z","steps":["trace[1069020071] 'process raft request' (duration: 136.982229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:01:33.757828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.510633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T17:01:33.757982Z","caller":"traceutil/trace.go:171","msg":"trace[1435453218] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"376.312199ms","start":"2026-03-25T17:01:33.381648Z","end":"2026-03-25T17:01:33.75796Z","steps":["trace[1435453218] 'read index received' (duration: 229.54575ms)","trace[1435453218] 'applied index is now lower than readState.Index' (duration: 146.764869ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:01:33.758241Z","caller":"traceutil/trace.go:171","msg":"trace[1822782696] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"396.691782ms","start":"2026-03-25T17:01:33.361464Z","end":"2026-03-25T17:01:33.758155Z","steps":["trace[1822782696] 'process raft request' (duration: 249.724811ms)","trace[1822782696] 'compare' (duration: 146.30368ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T17:01:33.758727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.951729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T17:01:33.758779Z","caller":"traceutil/trace.go:171","msg":"trace[1482424441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1401; }","duration":"377.013062ms","start":"2026-03-25T17:01:33.381751Z","end":"2026-03-25T17:01:33.758764Z","steps":["trace[1482424441] 'agreement among raft nodes before linearized reading' (duration: 376.775458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:01:33.758828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T17:01:33.361427Z","time spent":"396.931068ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T17:01:33.75891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T17:01:33.381746Z","time spent":"377.149671ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T17:01:33.759482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.334576ms","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-03-25T17:01:33.759699Z","caller":"traceutil/trace.go:171","msg":"trace[479543480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1401; }","duration":"364.644335ms","start":"2026-03-25T17:01:33.39503Z","end":"2026-03-25T17:01:33.759674Z","steps":["trace[479543480] 'agreement among raft nodes before linearized reading' (duration: 363.675896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:01:33.759818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.158763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T17:01:33.759947Z","caller":"traceutil/trace.go:171","msg":"trace[1767411953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1401; }","duration":"378.283411ms","start":"2026-03-25T17:01:33.381632Z","end":"2026-03-25T17:01:33.759915Z","steps":["trace[1767411953] 'agreement among raft nodes before linearized reading' (duration: 376.64049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:01:33.760032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T17:01:33.381624Z","time spent":"378.397498ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T17:01:33.759921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T17:01:33.395003Z","time spent":"364.854579ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T17:01:39.606896Z","caller":"traceutil/trace.go:171","msg":"trace[1935235883] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"293.873688ms","start":"2026-03-25T17:01:39.312991Z","end":"2026-03-25T17:01:39.606865Z","steps":["trace[1935235883] 'read index received' (duration: 293.610021ms)","trace[1935235883] 'applied index is now lower than readState.Index' (duration: 262.227µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:01:39.606992Z","caller":"traceutil/trace.go:171","msg":"trace[1867566998] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"297.914509ms","start":"2026-03-25T17:01:39.309027Z","end":"2026-03-25T17:01:39.606941Z","steps":["trace[1867566998] 'process raft request' (duration: 297.660414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:01:39.607096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.077461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T17:01:39.607144Z","caller":"traceutil/trace.go:171","msg":"trace[1116484091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1426; }","duration":"294.174876ms","start":"2026-03-25T17:01:39.312959Z","end":"2026-03-25T17:01:39.607134Z","steps":["trace[1116484091] 'agreement among raft nodes before linearized reading' (duration: 294.07596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:02:14.385499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.041496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T17:02:14.385802Z","caller":"traceutil/trace.go:171","msg":"trace[772450472] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"150.654005ms","start":"2026-03-25T17:02:14.235119Z","end":"2026-03-25T17:02:14.385773Z","steps":["trace[772450472] 'process raft request' (duration: 47.223325ms)","trace[772450472] 'compare' (duration: 102.900295ms)"],"step_count":2}