{"level":"warn","ts":"2026-05-05T16:54:46.723077Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:54:46.723173Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181: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.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-05-05T16:54:46.72325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:54:46.723263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-05-05T16:54:46.723281Z","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-05-05T16:54:46.72436Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-05-05T16:54:46.724448Z","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.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-05-05T16:54:46.735088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.814827ms"} {"level":"info","ts":"2026-05-05T16:54:46.743528Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-05-05T16:54:46.743604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T16:54:46.743626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-05-05T16:54:46.743637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T16:54:46.743647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-05-05T16:54:46.743682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-05-05T16:54:46.75053Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T16:54:46.751824Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T16:54:46.752903Z","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-05-05T16:54:46.755054Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T16:54:46.755147Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T16:54:46.755599Z","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-05-05T16:54:46.755671Z","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-05-05T16:54:46.755679Z","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-05-05T16:54:46.755964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-05-05T16:54:46.757477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-05-05T16:54:46.761358Z","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-05-05T16:54:46.761437Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-05-05T16:54:46.761475Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-05-05T16:54:46.761564Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T16:54:46.761587Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T16:54:47.743904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T16:54:47.743982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T16:54:47.744211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-05-05T16:54:47.744325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-05-05T16:54:47.744417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-05-05T16:54:47.744545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-05-05T16:54:47.74458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-05-05T16:54:47.782897Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:54:48.02336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T16:54:48.023413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:54:48.023496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:54:48.023805Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T16:54:48.023839Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T16:54:48.028165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T16:54:48.0291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"info","ts":"2026-05-05T16:54:48.256904Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:54:48.257223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:54:48.257351Z","caller":"traceutil/trace.go:171","msg":"trace[1918222070] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"187.260037ms","start":"2026-05-05T16:54:48.070072Z","end":"2026-05-05T16:54:48.257332Z","steps":["trace[1918222070] 'read index received' (duration: 186.767623ms)","trace[1918222070] 'applied index is now lower than readState.Index' (duration: 490.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:48.257466Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T16:54:48.257678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.541775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T16:54:48.257713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.527024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.257804Z","caller":"traceutil/trace.go:171","msg":"trace[1684702776] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"187.668158ms","start":"2026-05-05T16:54:48.070117Z","end":"2026-05-05T16:54:48.257785Z","steps":["trace[1684702776] 'agreement among raft nodes before linearized reading' (duration: 187.505374ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:48.257775Z","caller":"traceutil/trace.go:171","msg":"trace[792053307] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"187.692809ms","start":"2026-05-05T16:54:48.070068Z","end":"2026-05-05T16:54:48.25776Z","steps":["trace[792053307] 'agreement among raft nodes before linearized reading' (duration: 187.449842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.259771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.359815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.259995Z","caller":"traceutil/trace.go:171","msg":"trace[563651192] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"107.597552ms","start":"2026-05-05T16:54:48.152371Z","end":"2026-05-05T16:54:48.259968Z","steps":["trace[563651192] 'agreement among raft nodes before linearized reading' (duration: 107.352005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.260336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.806421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.260376Z","caller":"traceutil/trace.go:171","msg":"trace[382331084] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"118.858433ms","start":"2026-05-05T16:54:48.141501Z","end":"2026-05-05T16:54:48.260359Z","steps":["trace[382331084] 'agreement among raft nodes before linearized reading' (duration: 118.805782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.260646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.011153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.260796Z","caller":"traceutil/trace.go:171","msg":"trace[42963487] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"129.195629ms","start":"2026-05-05T16:54:48.131583Z","end":"2026-05-05T16:54:48.260778Z","steps":["trace[42963487] 'agreement among raft nodes before linearized reading' (duration: 128.963862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.260828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.227242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.260982Z","caller":"traceutil/trace.go:171","msg":"trace[1003492831] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"141.401717ms","start":"2026-05-05T16:54:48.119529Z","end":"2026-05-05T16:54:48.260931Z","steps":["trace[1003492831] 'agreement among raft nodes before linearized reading' (duration: 141.187051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.261031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.509593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.261071Z","caller":"traceutil/trace.go:171","msg":"trace[1449401754] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"119.577795ms","start":"2026-05-05T16:54:48.141474Z","end":"2026-05-05T16:54:48.261051Z","steps":["trace[1449401754] 'agreement among raft nodes before linearized reading' (duration: 119.526013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.261214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.448657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.261251Z","caller":"traceutil/trace.go:171","msg":"trace[1466501173] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"129.518599ms","start":"2026-05-05T16:54:48.131723Z","end":"2026-05-05T16:54:48.261242Z","steps":["trace[1466501173] 'agreement among raft nodes before linearized reading' (duration: 129.471018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:48.265105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.660766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:54:48.265164Z","caller":"traceutil/trace.go:171","msg":"trace[2066594823] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"112.741028ms","start":"2026-05-05T16:54:48.152411Z","end":"2026-05-05T16:54:48.265152Z","steps":["trace[2066594823] 'agreement among raft nodes before linearized reading' (duration: 106.069578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:49.750573Z","caller":"traceutil/trace.go:171","msg":"trace[2093167588] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"131.278871ms","start":"2026-05-05T16:54:49.619264Z","end":"2026-05-05T16:54:49.750543Z","steps":["trace[2093167588] 'process raft request' (duration: 59.647847ms)","trace[2093167588] 'compare' (duration: 71.366557ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:49.750583Z","caller":"traceutil/trace.go:171","msg":"trace[1413167193] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"110.790112ms","start":"2026-05-05T16:54:49.639767Z","end":"2026-05-05T16:54:49.750557Z","steps":["trace[1413167193] 'process raft request' (duration: 110.74363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:49.750805Z","caller":"traceutil/trace.go:171","msg":"trace[669785734] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"114.722503ms","start":"2026-05-05T16:54:49.636029Z","end":"2026-05-05T16:54:49.750751Z","steps":["trace[669785734] 'process raft request' (duration: 114.432795ms)"],"step_count":1} 2026/05/05 16:54:52 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-05T16:55:19.228014Z","caller":"traceutil/trace.go:171","msg":"trace[1295771881] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"140.722695ms","start":"2026-05-05T16:55:19.087272Z","end":"2026-05-05T16:55:19.227995Z","steps":["trace[1295771881] 'process raft request' (duration: 140.626043ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.233233Z","caller":"traceutil/trace.go:171","msg":"trace[952198418] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"134.382108ms","start":"2026-05-05T16:55:19.098835Z","end":"2026-05-05T16:55:19.233217Z","steps":["trace[952198418] 'read index received' (duration: 129.151555ms)","trace[952198418] 'applied index is now lower than readState.Index' (duration: 5.230033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:19.233345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.50404ms","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-05-05T16:55:19.233371Z","caller":"traceutil/trace.go:171","msg":"trace[1483382110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"134.572792ms","start":"2026-05-05T16:55:19.098792Z","end":"2026-05-05T16:55:19.233365Z","steps":["trace[1483382110] 'agreement among raft nodes before linearized reading' (duration: 134.48909ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.233522Z","caller":"traceutil/trace.go:171","msg":"trace[1312150285] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"108.059191ms","start":"2026-05-05T16:55:19.125456Z","end":"2026-05-05T16:55:19.233515Z","steps":["trace[1312150285] 'process raft request' (duration: 107.624232ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.233795Z","caller":"traceutil/trace.go:171","msg":"trace[1749436792] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"135.028812ms","start":"2026-05-05T16:55:19.098757Z","end":"2026-05-05T16:55:19.233786Z","steps":["trace[1749436792] 'process raft request' (duration: 134.04683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.23351Z","caller":"traceutil/trace.go:171","msg":"trace[1550300264] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"114.899408ms","start":"2026-05-05T16:55:19.118453Z","end":"2026-05-05T16:55:19.233352Z","steps":["trace[1550300264] 'process raft request' (duration: 114.505148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.233942Z","caller":"traceutil/trace.go:171","msg":"trace[1551126615] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"109.196076ms","start":"2026-05-05T16:55:19.124712Z","end":"2026-05-05T16:55:19.233908Z","steps":["trace[1551126615] 'process raft request' (duration: 108.326477ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.234065Z","caller":"traceutil/trace.go:171","msg":"trace[1600631621] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"115.568562ms","start":"2026-05-05T16:55:19.118488Z","end":"2026-05-05T16:55:19.234056Z","steps":["trace[1600631621] 'process raft request' (duration: 114.494008ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.234061Z","caller":"traceutil/trace.go:171","msg":"trace[406225622] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"115.674304ms","start":"2026-05-05T16:55:19.11836Z","end":"2026-05-05T16:55:19.234034Z","steps":["trace[406225622] 'process raft request' (duration: 114.540729ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.234098Z","caller":"traceutil/trace.go:171","msg":"trace[1386346977] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"111.832912ms","start":"2026-05-05T16:55:19.122259Z","end":"2026-05-05T16:55:19.234091Z","steps":["trace[1386346977] 'process raft request' (duration: 110.748399ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.234103Z","caller":"traceutil/trace.go:171","msg":"trace[1177758541] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"115.807976ms","start":"2026-05-05T16:55:19.118286Z","end":"2026-05-05T16:55:19.234094Z","steps":["trace[1177758541] 'process raft request' (duration: 114.58498ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:19.234119Z","caller":"traceutil/trace.go:171","msg":"trace[1659538335] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"115.690214ms","start":"2026-05-05T16:55:19.118396Z","end":"2026-05-05T16:55:19.234086Z","steps":["trace[1659538335] 'process raft request' (duration: 114.532159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:19.827311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.852634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:55:19.827437Z","caller":"traceutil/trace.go:171","msg":"trace[316069334] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"479.521992ms","start":"2026-05-05T16:55:19.347904Z","end":"2026-05-05T16:55:19.827426Z","steps":["trace[316069334] 'process raft request' (duration: 479.480521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:19.827474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:19.34788Z","time spent":"479.579482ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T16:55:19.827539Z","caller":"traceutil/trace.go:171","msg":"trace[1878668287] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"482.623819ms","start":"2026-05-05T16:55:19.34489Z","end":"2026-05-05T16:55:19.827513Z","steps":["trace[1878668287] 'process raft request' (duration: 318.162722ms)","trace[1878668287] 'compare' (duration: 163.745491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:19.827658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:19.34487Z","time spent":"482.727911ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:19.846039Z","caller":"traceutil/trace.go:171","msg":"trace[1083041679] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:564; }","duration":"255.533391ms","start":"2026-05-05T16:55:19.59049Z","end":"2026-05-05T16:55:19.846023Z","steps":["trace[1083041679] 'read index received' (duration: 72.669937ms)","trace[1083041679] 'applied index is now lower than readState.Index' (duration: 182.862844ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:19.846348Z","caller":"traceutil/trace.go:171","msg":"trace[1165257802] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"478.893107ms","start":"2026-05-05T16:55:19.367448Z","end":"2026-05-05T16:55:19.846341Z","steps":["trace[1165257802] 'process raft request' (duration: 478.342686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:19.846448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:19.367422Z","time spent":"478.942229ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:19.846548Z","caller":"traceutil/trace.go:171","msg":"trace[1574654507] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"468.346181ms","start":"2026-05-05T16:55:19.378198Z","end":"2026-05-05T16:55:19.846544Z","steps":["trace[1574654507] 'process raft request' (duration: 467.797538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:19.846574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:19.378179Z","time spent":"468.379192ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:55:19.84664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.160465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:55:19.846656Z","caller":"traceutil/trace.go:171","msg":"trace[974277155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"256.210226ms","start":"2026-05-05T16:55:19.59044Z","end":"2026-05-05T16:55:19.846651Z","steps":["trace[974277155] 'agreement among raft nodes before linearized reading' (duration: 256.182895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:19.850141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.310775ms","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-05-05T16:55:19.850196Z","caller":"traceutil/trace.go:171","msg":"trace[2016385488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"210.405537ms","start":"2026-05-05T16:55:19.639778Z","end":"2026-05-05T16:55:19.850184Z","steps":["trace[2016385488] 'agreement among raft nodes before linearized reading' (duration: 210.282084ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:21.44639Z","caller":"traceutil/trace.go:171","msg":"trace[634993066] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"260.61482ms","start":"2026-05-05T16:55:21.185751Z","end":"2026-05-05T16:55:21.446365Z","steps":["trace[634993066] 'read index received' (duration: 258.186749ms)","trace[634993066] 'applied index is now lower than readState.Index' (duration: 2.426971ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:21.446523Z","caller":"traceutil/trace.go:171","msg":"trace[1205361839] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"271.583623ms","start":"2026-05-05T16:55:21.174929Z","end":"2026-05-05T16:55:21.446513Z","steps":["trace[1205361839] 'process raft request' (duration: 269.173042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:21.446844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.686952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T16:55:21.446995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.236653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-05-05T16:55:21.447044Z","caller":"traceutil/trace.go:171","msg":"trace[1388702046] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:619; }","duration":"261.316925ms","start":"2026-05-05T16:55:21.185711Z","end":"2026-05-05T16:55:21.447028Z","steps":["trace[1388702046] 'agreement among raft nodes before linearized reading' (duration: 260.933067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:21.447127Z","caller":"traceutil/trace.go:171","msg":"trace[1101012435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"194.916727ms","start":"2026-05-05T16:55:21.252096Z","end":"2026-05-05T16:55:21.447012Z","steps":["trace[1101012435] 'agreement among raft nodes before linearized reading' (duration: 194.59055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:28.724625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378075492114422972,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:55:28.915049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.923269ms","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-05-05T16:55:28.915226Z","caller":"traceutil/trace.go:171","msg":"trace[808485074] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:682; }","duration":"410.119073ms","start":"2026-05-05T16:55:28.505093Z","end":"2026-05-05T16:55:28.915212Z","steps":["trace[808485074] 'range keys from in-memory index tree' (duration: 409.905639ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:28.929226Z","caller":"traceutil/trace.go:171","msg":"trace[643451808] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"705.626862ms","start":"2026-05-05T16:55:28.223583Z","end":"2026-05-05T16:55:28.92921Z","steps":["trace[643451808] 'read index received' (duration: 705.467529ms)","trace[643451808] 'applied index is now lower than readState.Index' (duration: 158.863µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:28.929531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.594926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:55:28.929532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.399408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-05T16:55:28.929555Z","caller":"traceutil/trace.go:171","msg":"trace[818035332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"338.657087ms","start":"2026-05-05T16:55:28.590891Z","end":"2026-05-05T16:55:28.929548Z","steps":["trace[818035332] 'agreement among raft nodes before linearized reading' (duration: 338.621496ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:28.929579Z","caller":"traceutil/trace.go:171","msg":"trace[1101480073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"705.489229ms","start":"2026-05-05T16:55:28.224083Z","end":"2026-05-05T16:55:28.929572Z","steps":["trace[1101480073] 'agreement among raft nodes before linearized reading' (duration: 705.342887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:28.929591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:28.590864Z","time spent":"338.721678ms","remote":"127.0.0.1:55048","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-05-05T16:55:28.929602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:28.224065Z","time spent":"705.529501ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T16:55:28.929597Z","caller":"traceutil/trace.go:171","msg":"trace[1848787623] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"870.877644ms","start":"2026-05-05T16:55:28.058702Z","end":"2026-05-05T16:55:28.929579Z","steps":["trace[1848787623] 'process raft request' (duration: 870.398444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:28.929529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.934348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T16:55:28.929724Z","caller":"traceutil/trace.go:171","msg":"trace[1249182417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"706.183243ms","start":"2026-05-05T16:55:28.223534Z","end":"2026-05-05T16:55:28.929717Z","steps":["trace[1249182417] 'agreement among raft nodes before linearized reading' (duration: 705.826976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:28.929748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:28.223512Z","time spent":"706.228924ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T16:55:28.929833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.190184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T16:55:28.929862Z","caller":"traceutil/trace.go:171","msg":"trace[1557683881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"705.270595ms","start":"2026-05-05T16:55:28.224586Z","end":"2026-05-05T16:55:28.929856Z","steps":["trace[1557683881] 'agreement among raft nodes before linearized reading' (duration: 705.167833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:28.929881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:28.22458Z","time spent":"705.295975ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:55:28.930552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:28.058681Z","time spent":"870.965905ms","remote":"127.0.0.1:55236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:34.333036Z","caller":"traceutil/trace.go:171","msg":"trace[1952487504] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"168.574193ms","start":"2026-05-05T16:55:34.163932Z","end":"2026-05-05T16:55:34.332506Z","steps":["trace[1952487504] 'process raft request' (duration: 164.781459ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:35.233017Z","caller":"traceutil/trace.go:171","msg":"trace[362914411] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"242.334179ms","start":"2026-05-05T16:55:34.990665Z","end":"2026-05-05T16:55:35.232999Z","steps":["trace[362914411] 'read index received' (duration: 242.197516ms)","trace[362914411] 'applied index is now lower than readState.Index' (duration: 135.783µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:35.233062Z","caller":"traceutil/trace.go:171","msg":"trace[1309275027] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"242.831359ms","start":"2026-05-05T16:55:34.990217Z","end":"2026-05-05T16:55:35.233048Z","steps":["trace[1309275027] 'process raft request' (duration: 242.641495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:35.233298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.622875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T16:55:35.233349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.521032ms","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-05-05T16:55:35.233368Z","caller":"traceutil/trace.go:171","msg":"trace[203350005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"242.724537ms","start":"2026-05-05T16:55:34.99063Z","end":"2026-05-05T16:55:35.233355Z","steps":["trace[203350005] 'agreement among raft nodes before linearized reading' (duration: 242.536153ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:35.233402Z","caller":"traceutil/trace.go:171","msg":"trace[823392963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"242.601893ms","start":"2026-05-05T16:55:34.990791Z","end":"2026-05-05T16:55:35.233393Z","steps":["trace[823392963] 'agreement among raft nodes before linearized reading' (duration: 242.41611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:35.233449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.738624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p4gb6\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-05-05T16:55:35.233497Z","caller":"traceutil/trace.go:171","msg":"trace[1252134365] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p4gb6; range_end:; response_count:1; response_revision:726; }","duration":"122.814485ms","start":"2026-05-05T16:55:35.11067Z","end":"2026-05-05T16:55:35.233485Z","steps":["trace[1252134365] 'agreement among raft nodes before linearized reading' (duration: 122.682223ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:41.321632Z","caller":"traceutil/trace.go:171","msg":"trace[669735376] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"111.648634ms","start":"2026-05-05T16:55:41.209957Z","end":"2026-05-05T16:55:41.321606Z","steps":["trace[669735376] 'process raft request' (duration: 111.387529ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.500357Z","caller":"traceutil/trace.go:171","msg":"trace[1437537090] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"147.32365ms","start":"2026-05-05T16:55:42.352989Z","end":"2026-05-05T16:55:42.500312Z","steps":["trace[1437537090] 'process raft request' (duration: 147.212648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.500524Z","caller":"traceutil/trace.go:171","msg":"trace[602291982] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"176.667664ms","start":"2026-05-05T16:55:42.323771Z","end":"2026-05-05T16:55:42.500439Z","steps":["trace[602291982] 'process raft request' (duration: 128.504268ms)","trace[602291982] 'compare' (duration: 47.643567ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:47.530225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.37284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ppfbh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T16:55:47.530348Z","caller":"traceutil/trace.go:171","msg":"trace[1107261359] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ppfbh; range_end:; response_count:1; response_revision:804; }","duration":"375.553543ms","start":"2026-05-05T16:55:47.154766Z","end":"2026-05-05T16:55:47.53032Z","steps":["trace[1107261359] 'range keys from in-memory index tree' (duration: 375.168517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:47.530404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:47.154749Z","time spent":"375.641574ms","remote":"127.0.0.1:55236","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-ppfbh\" "} {"level":"warn","ts":"2026-05-05T16:55:47.530535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.617159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T16:55:47.53063Z","caller":"traceutil/trace.go:171","msg":"trace[211520600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"122.724061ms","start":"2026-05-05T16:55:47.407886Z","end":"2026-05-05T16:55:47.53061Z","steps":["trace[211520600] 'range keys from in-memory index tree' (duration: 122.417426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:47.53065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.691326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T16:55:47.530741Z","caller":"traceutil/trace.go:171","msg":"trace[213002572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"103.826268ms","start":"2026-05-05T16:55:47.426893Z","end":"2026-05-05T16:55:47.530719Z","steps":["trace[213002572] 'range keys from in-memory index tree' (duration: 103.478022ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:55.781302Z","caller":"traceutil/trace.go:171","msg":"trace[1342557419] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"198.164652ms","start":"2026-05-05T16:55:55.583099Z","end":"2026-05-05T16:55:55.781264Z","steps":["trace[1342557419] 'process raft request' (duration: 115.714ms)","trace[1342557419] 'compare' (duration: 82.237438ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:42.710145Z","caller":"traceutil/trace.go:171","msg":"trace[2128873575] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"310.689775ms","start":"2026-05-05T16:57:42.399434Z","end":"2026-05-05T16:57:42.710124Z","steps":["trace[2128873575] 'read index received' (duration: 310.545713ms)","trace[2128873575] 'applied index is now lower than readState.Index' (duration: 143.012µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:42.710208Z","caller":"traceutil/trace.go:171","msg":"trace[10719247] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"322.444101ms","start":"2026-05-05T16:57:42.387712Z","end":"2026-05-05T16:57:42.710156Z","steps":["trace[10719247] 'process raft request' (duration: 322.247209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:42.710446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.992928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T16:57:42.710478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:42.387694Z","time spent":"322.640423ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:57:42.710529Z","caller":"traceutil/trace.go:171","msg":"trace[1477314469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1353; }","duration":"311.14072ms","start":"2026-05-05T16:57:42.39937Z","end":"2026-05-05T16:57:42.710511Z","steps":["trace[1477314469] 'agreement among raft nodes before linearized reading' (duration: 310.856497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:42.710567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:42.399334Z","time spent":"311.226641ms","remote":"127.0.0.1:55308","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-05-05T16:57:42.710583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.681223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:42.710774Z","caller":"traceutil/trace.go:171","msg":"trace[1508267405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"119.962356ms","start":"2026-05-05T16:57:42.590755Z","end":"2026-05-05T16:57:42.710717Z","steps":["trace[1508267405] 'agreement among raft nodes before linearized reading' (duration: 119.644912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:42.710712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.559362ms","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-05-05T16:57:42.710913Z","caller":"traceutil/trace.go:171","msg":"trace[1203193331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"211.868106ms","start":"2026-05-05T16:57:42.499035Z","end":"2026-05-05T16:57:42.710903Z","steps":["trace[1203193331] 'agreement among raft nodes before linearized reading' (duration: 211.236369ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:55.009246Z","caller":"traceutil/trace.go:171","msg":"trace[519126342] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"105.128271ms","start":"2026-05-05T16:57:54.904083Z","end":"2026-05-05T16:57:55.009211Z","steps":["trace[519126342] 'process raft request' (duration: 103.730476ms)"],"step_count":1}