{"level":"warn","ts":"2026-05-11T05:31:36.561758Z","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-11T05:31:36.561848Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-11T05:31:36.561917Z","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-11T05:31:36.561928Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-11T05:31:36.561952Z","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-11T05:31:36.562738Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-05-11T05:31:36.562845Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-11T05:31:36.632614Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"50.318879ms"} {"level":"info","ts":"2026-05-11T05:31:36.804488Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-05-11T05:31:36.804703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:31:36.804777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-05-11T05:31:36.804828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:31:36.804872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-05-11T05:31:36.804956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-05-11T05:31:36.843739Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:31:36.8505Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:31:36.874554Z","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-11T05:31:36.878967Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:31:36.879126Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:31:36.879308Z","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-11T05:31:36.879388Z","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-11T05:31:36.879404Z","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-11T05:31:36.883298Z","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-11T05:31:36.883427Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-11T05:31:36.883517Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-11T05:31:36.883564Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:31:36.883621Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:31:36.89877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-05-11T05:31:36.898901Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-11T05:31:37.605668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:31:37.605769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:31:37.605799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-05-11T05:31:37.605814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:31:37.605821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-11T05:31:37.605842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-05-11T05:31:37.605852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-11T05:31:37.607778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:31:37.607934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:31:37.608007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:31:37.608108Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:31:37.608453Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:31:37.608485Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:31:37.610257Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-05-11T05:31:37.610746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:31:37.610805Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T05:31:37.610871Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:31:37.610906Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:31:39.194786Z","caller":"traceutil/trace.go:171","msg":"trace[1230399283] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"285.699971ms","start":"2026-05-11T05:31:38.909065Z","end":"2026-05-11T05:31:39.194765Z","steps":["trace[1230399283] 'process raft request' (duration: 277.30206ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.195015Z","caller":"traceutil/trace.go:171","msg":"trace[851040498] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"238.679327ms","start":"2026-05-11T05:31:38.956303Z","end":"2026-05-11T05:31:39.194982Z","steps":["trace[851040498] 'read index received' (duration: 43.493469ms)","trace[851040498] 'applied index is now lower than readState.Index' (duration: 195.180767ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:31:39.195106Z","caller":"traceutil/trace.go:171","msg":"trace[1471061809] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"199.776737ms","start":"2026-05-11T05:31:38.995314Z","end":"2026-05-11T05:31:39.195091Z","steps":["trace[1471061809] 'process raft request' (duration: 199.644386ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.195042Z","caller":"traceutil/trace.go:171","msg":"trace[645925102] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"199.853044ms","start":"2026-05-11T05:31:38.995137Z","end":"2026-05-11T05:31:39.19499Z","steps":["trace[645925102] 'process raft request' (duration: 199.736293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:39.195184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.830601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:31:39.195228Z","caller":"traceutil/trace.go:171","msg":"trace[442686207] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"238.968362ms","start":"2026-05-11T05:31:38.956253Z","end":"2026-05-11T05:31:39.195221Z","steps":["trace[442686207] 'agreement among raft nodes before linearized reading' (duration: 238.82917ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.195189Z","caller":"traceutil/trace.go:171","msg":"trace[1244988001] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"199.785428ms","start":"2026-05-11T05:31:38.995275Z","end":"2026-05-11T05:31:39.19506Z","steps":["trace[1244988001] 'process raft request' (duration: 199.666117ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.195212Z","caller":"traceutil/trace.go:171","msg":"trace[1511702602] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"200.221365ms","start":"2026-05-11T05:31:38.994941Z","end":"2026-05-11T05:31:39.195162Z","steps":["trace[1511702602] 'process raft request' (duration: 199.913029ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.195363Z","caller":"traceutil/trace.go:171","msg":"trace[1647991195] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"202.618714ms","start":"2026-05-11T05:31:38.992734Z","end":"2026-05-11T05:31:39.195353Z","steps":["trace[1647991195] 'process raft request' (duration: 202.044344ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.195118Z","caller":"traceutil/trace.go:171","msg":"trace[991280312] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"199.880456ms","start":"2026-05-11T05:31:38.995188Z","end":"2026-05-11T05:31:39.195069Z","steps":["trace[991280312] 'process raft request' (duration: 199.707301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:39.208614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.976557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-11T05:31:39.208619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.972077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-11T05:31:39.208652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.351301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:31:39.208684Z","caller":"traceutil/trace.go:171","msg":"trace[1921685746] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"154.016903ms","start":"2026-05-11T05:31:39.054661Z","end":"2026-05-11T05:31:39.208678Z","steps":["trace[1921685746] 'process raft request' (duration: 153.680774ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.20871Z","caller":"traceutil/trace.go:171","msg":"trace[1867051562] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"151.420228ms","start":"2026-05-11T05:31:39.057278Z","end":"2026-05-11T05:31:39.208699Z","steps":["trace[1867051562] 'agreement among raft nodes before linearized reading' (duration: 151.359752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:39.208674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.177297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:31:39.208657Z","caller":"traceutil/trace.go:171","msg":"trace[561640511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"251.022521ms","start":"2026-05-11T05:31:38.957624Z","end":"2026-05-11T05:31:39.208647Z","steps":["trace[561640511] 'agreement among raft nodes before linearized reading' (duration: 250.912051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:39.208611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.529717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:31:39.208783Z","caller":"traceutil/trace.go:171","msg":"trace[1523568837] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"161.310739ms","start":"2026-05-11T05:31:39.047458Z","end":"2026-05-11T05:31:39.208769Z","steps":["trace[1523568837] 'agreement among raft nodes before linearized reading' (duration: 161.187497ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.208829Z","caller":"traceutil/trace.go:171","msg":"trace[51968643] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"215.794071ms","start":"2026-05-11T05:31:38.993027Z","end":"2026-05-11T05:31:39.208821Z","steps":["trace[51968643] 'agreement among raft nodes before linearized reading' (duration: 215.468492ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.208652Z","caller":"traceutil/trace.go:171","msg":"trace[2086702639] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"219.023012ms","start":"2026-05-11T05:31:38.989619Z","end":"2026-05-11T05:31:39.208642Z","steps":["trace[2086702639] 'agreement among raft nodes before linearized reading' (duration: 218.960236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:39.20861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.525045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-11T05:31:39.20862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.168031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T05:31:39.20909Z","caller":"traceutil/trace.go:171","msg":"trace[36409574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"252.668536ms","start":"2026-05-11T05:31:38.956413Z","end":"2026-05-11T05:31:39.209081Z","steps":["trace[36409574] 'agreement among raft nodes before linearized reading' (duration: 252.088624ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:39.209119Z","caller":"traceutil/trace.go:171","msg":"trace[961829506] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:0; response_revision:10; }","duration":"219.917499ms","start":"2026-05-11T05:31:38.989058Z","end":"2026-05-11T05:31:39.208975Z","steps":["trace[961829506] 'agreement among raft nodes before linearized reading' (duration: 219.47767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:45.291737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.949711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-11T05:31:45.291816Z","caller":"traceutil/trace.go:171","msg":"trace[1179091591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:294; }","duration":"241.086333ms","start":"2026-05-11T05:31:45.050715Z","end":"2026-05-11T05:31:45.291802Z","steps":["trace[1179091591] 'range keys from in-memory index tree' (duration: 240.795177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:46.700576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393274949691364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:31:47.201768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393274949691364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:31:47.430441Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.320265374s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T05:31:47.432483Z","caller":"traceutil/trace.go:171","msg":"trace[990516053] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.322401233s","start":"2026-05-11T05:31:46.110061Z","end":"2026-05-11T05:31:47.432462Z","steps":["trace[990516053] 'process raft request' (duration: 1.320725774s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.432949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.110032Z","time spent":"1.322514344s","remote":"127.0.0.1:38032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:31:47.499419Z","caller":"traceutil/trace.go:171","msg":"trace[1551979466] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"1.387961412s","start":"2026-05-11T05:31:46.11143Z","end":"2026-05-11T05:31:47.499392Z","steps":["trace[1551979466] 'process raft request' (duration: 1.387782376s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.499529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.111411Z","time spent":"1.38805586s","remote":"127.0.0.1:38032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:31:47.499652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299793057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-11T05:31:47.49981Z","caller":"traceutil/trace.go:171","msg":"trace[2024349647] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.388137207s","start":"2026-05-11T05:31:46.111644Z","end":"2026-05-11T05:31:47.499781Z","steps":["trace[2024349647] 'process raft request' (duration: 1.387694358s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.499401Z","caller":"traceutil/trace.go:171","msg":"trace[1778576510] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:324; }","duration":"1.299577348s","start":"2026-05-11T05:31:46.199803Z","end":"2026-05-11T05:31:47.49938Z","steps":["trace[1778576510] 'read index received' (duration: 1.230904124s)","trace[1778576510] 'applied index is now lower than readState.Index' (duration: 68.672244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:31:47.499961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.111634Z","time spent":"1.388241446s","remote":"127.0.0.1:38032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:31:47.500082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249601709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-11T05:31:47.500105Z","caller":"traceutil/trace.go:171","msg":"trace[254088136] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:314; }","duration":"1.249644703s","start":"2026-05-11T05:31:46.250453Z","end":"2026-05-11T05:31:47.500097Z","steps":["trace[254088136] 'agreement among raft nodes before linearized reading' (duration: 1.249598569s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.250442Z","time spent":"1.249675666s","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-05-11T05:31:47.500233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.370283ms","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-11T05:31:47.500256Z","caller":"traceutil/trace.go:171","msg":"trace[1262094058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:314; }","duration":"140.438479ms","start":"2026-05-11T05:31:47.35981Z","end":"2026-05-11T05:31:47.500249Z","steps":["trace[1262094058] 'agreement among raft nodes before linearized reading' (duration: 140.379214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.240542ms","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-11T05:31:47.500258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19984516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-11T05:31:47.500314Z","caller":"traceutil/trace.go:171","msg":"trace[1829783307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:314; }","duration":"161.310047ms","start":"2026-05-11T05:31:47.338994Z","end":"2026-05-11T05:31:47.500304Z","steps":["trace[1829783307] 'agreement among raft nodes before linearized reading' (duration: 161.242771ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.500349Z","caller":"traceutil/trace.go:171","msg":"trace[1109294910] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"1.200025696s","start":"2026-05-11T05:31:46.300305Z","end":"2026-05-11T05:31:47.500331Z","steps":["trace[1109294910] 'agreement among raft nodes before linearized reading' (duration: 1.199860811s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.061956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-11T05:31:47.500397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.300294Z","time spent":"1.200093512s","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-11T05:31:47.500422Z","caller":"traceutil/trace.go:171","msg":"trace[1235088804] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:314; }","duration":"477.090938ms","start":"2026-05-11T05:31:47.023324Z","end":"2026-05-11T05:31:47.500415Z","steps":["trace[1235088804] 'agreement among raft nodes before linearized reading' (duration: 477.057645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.023318Z","time spent":"477.1179ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-11T05:31:47.500459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234912101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-05-11T05:31:47.500512Z","caller":"traceutil/trace.go:171","msg":"trace[1437610398] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:314; }","duration":"1.234982927s","start":"2026-05-11T05:31:46.265511Z","end":"2026-05-11T05:31:47.500494Z","steps":["trace[1437610398] 'agreement among raft nodes before linearized reading' (duration: 1.234896489s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.368898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-11T05:31:47.500545Z","caller":"traceutil/trace.go:171","msg":"trace[405155736] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"480.433534ms","start":"2026-05-11T05:31:47.020105Z","end":"2026-05-11T05:31:47.500538Z","steps":["trace[405155736] 'agreement among raft nodes before linearized reading' (duration: 480.396071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.265501Z","time spent":"1.235033872s","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-11T05:31:47.500559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235329167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-11T05:31:47.500564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.020072Z","time spent":"480.488649ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-05-11T05:31:47.500577Z","caller":"traceutil/trace.go:171","msg":"trace[716827295] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:314; }","duration":"1.235347909s","start":"2026-05-11T05:31:46.265222Z","end":"2026-05-11T05:31:47.50057Z","steps":["trace[716827295] 'agreement among raft nodes before linearized reading' (duration: 1.235315536s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.500591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.265091Z","time spent":"1.235496862s","remote":"127.0.0.1:38084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-11T05:31:47.500563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.028851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-11T05:31:47.500745Z","caller":"traceutil/trace.go:171","msg":"trace[257495551] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:314; }","duration":"478.286484ms","start":"2026-05-11T05:31:47.022408Z","end":"2026-05-11T05:31:47.500694Z","steps":["trace[257495551] 'agreement among raft nodes before linearized reading' (duration: 477.873277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.501077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.613362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-11T05:31:47.500958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.0224Z","time spent":"478.532795ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-05-11T05:31:47.501123Z","caller":"traceutil/trace.go:171","msg":"trace[850658480] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"478.687079ms","start":"2026-05-11T05:31:47.022427Z","end":"2026-05-11T05:31:47.501114Z","steps":["trace[850658480] 'agreement among raft nodes before linearized reading' (duration: 477.745016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.501142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.022415Z","time spent":"478.722642ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-11T05:31:47.499746Z","caller":"traceutil/trace.go:171","msg":"trace[1746601693] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"1.299962611s","start":"2026-05-11T05:31:46.199738Z","end":"2026-05-11T05:31:47.499701Z","steps":["trace[1746601693] 'agreement among raft nodes before linearized reading' (duration: 1.299713089s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.502473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:46.199708Z","time spent":"1.302748818s","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-05-11T05:31:47.636716Z","caller":"traceutil/trace.go:171","msg":"trace[1118394818] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"127.734474ms","start":"2026-05-11T05:31:47.508925Z","end":"2026-05-11T05:31:47.63666Z","steps":["trace[1118394818] 'process raft request' (duration: 127.398665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.637707Z","caller":"traceutil/trace.go:171","msg":"trace[1907434527] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"128.58622ms","start":"2026-05-11T05:31:47.509092Z","end":"2026-05-11T05:31:47.637679Z","steps":["trace[1907434527] 'process raft request' (duration: 127.480993ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.637788Z","caller":"traceutil/trace.go:171","msg":"trace[1597030115] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"125.788792ms","start":"2026-05-11T05:31:47.511972Z","end":"2026-05-11T05:31:47.637761Z","steps":["trace[1597030115] 'process raft request' (duration: 125.634239ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.638094Z","caller":"traceutil/trace.go:171","msg":"trace[1841259838] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:327; }","duration":"114.557457ms","start":"2026-05-11T05:31:47.523514Z","end":"2026-05-11T05:31:47.638071Z","steps":["trace[1841259838] 'read index received' (duration: 69.610556ms)","trace[1841259838] 'applied index is now lower than readState.Index' (duration: 44.945701ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:31:47.638245Z","caller":"traceutil/trace.go:171","msg":"trace[653804795] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"126.149514ms","start":"2026-05-11T05:31:47.512086Z","end":"2026-05-11T05:31:47.638235Z","steps":["trace[653804795] 'process raft request' (duration: 125.611147ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.638398Z","caller":"traceutil/trace.go:171","msg":"trace[58792252] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"122.247137ms","start":"2026-05-11T05:31:47.516139Z","end":"2026-05-11T05:31:47.638386Z","steps":["trace[58792252] 'process raft request' (duration: 121.7141ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.638743Z","caller":"traceutil/trace.go:171","msg":"trace[1571052810] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"122.506882ms","start":"2026-05-11T05:31:47.516223Z","end":"2026-05-11T05:31:47.63873Z","steps":["trace[1571052810] 'process raft request' (duration: 121.673487ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.639058Z","caller":"traceutil/trace.go:171","msg":"trace[1430483384] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"121.987686ms","start":"2026-05-11T05:31:47.517057Z","end":"2026-05-11T05:31:47.639045Z","steps":["trace[1430483384] 'process raft request' (duration: 120.891218ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.639341Z","caller":"traceutil/trace.go:171","msg":"trace[536409740] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"127.146593ms","start":"2026-05-11T05:31:47.512175Z","end":"2026-05-11T05:31:47.639321Z","steps":["trace[536409740] 'process raft request' (duration: 125.566583ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.639539Z","caller":"traceutil/trace.go:171","msg":"trace[1265329433] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"123.393189ms","start":"2026-05-11T05:31:47.516123Z","end":"2026-05-11T05:31:47.639516Z","steps":["trace[1265329433] 'process raft request' (duration: 121.689118ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.639694Z","caller":"traceutil/trace.go:171","msg":"trace[715589171] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"123.750641ms","start":"2026-05-11T05:31:47.51593Z","end":"2026-05-11T05:31:47.639681Z","steps":["trace[715589171] 'process raft request' (duration: 121.848802ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:47.639815Z","caller":"traceutil/trace.go:171","msg":"trace[2080377779] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"113.35102ms","start":"2026-05-11T05:31:47.526439Z","end":"2026-05-11T05:31:47.639791Z","steps":["trace[2080377779] 'process raft request' (duration: 111.576593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:47.640132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.589777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-05-11T05:31:47.640178Z","caller":"traceutil/trace.go:171","msg":"trace[130920090] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:326; }","duration":"116.676595ms","start":"2026-05-11T05:31:47.523493Z","end":"2026-05-11T05:31:47.64017Z","steps":["trace[130920090] 'agreement among raft nodes before linearized reading' (duration: 116.568905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:48.029132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.648007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:31:48.029989Z","caller":"traceutil/trace.go:171","msg":"trace[951823478] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"383.442417ms","start":"2026-05-11T05:31:47.646523Z","end":"2026-05-11T05:31:48.029965Z","steps":["trace[951823478] 'process raft request' (duration: 217.684301ms)","trace[951823478] 'compare' (duration: 163.915613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:31:48.030085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.64651Z","time spent":"383.533016ms","remote":"127.0.0.1:37902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:31:48.030236Z","caller":"traceutil/trace.go:171","msg":"trace[408107627] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"383.517464ms","start":"2026-05-11T05:31:47.646682Z","end":"2026-05-11T05:31:48.0302Z","steps":["trace[408107627] 'process raft request' (duration: 383.237679ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:48.030297Z","caller":"traceutil/trace.go:171","msg":"trace[99808168] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:340; }","duration":"338.447051ms","start":"2026-05-11T05:31:47.691833Z","end":"2026-05-11T05:31:48.03028Z","steps":["trace[99808168] 'read index received' (duration: 172.253527ms)","trace[99808168] 'applied index is now lower than readState.Index' (duration: 166.192314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:31:48.030388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.548642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-11T05:31:48.030423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.646662Z","time spent":"383.666867ms","remote":"127.0.0.1:37798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:31:48.030468Z","caller":"traceutil/trace.go:171","msg":"trace[723265903] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"382.389874ms","start":"2026-05-11T05:31:47.648071Z","end":"2026-05-11T05:31:48.030461Z","steps":["trace[723265903] 'process raft request' (duration: 382.037193ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:48.030476Z","caller":"traceutil/trace.go:171","msg":"trace[102171947] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"381.278886ms","start":"2026-05-11T05:31:47.649189Z","end":"2026-05-11T05:31:48.030468Z","steps":["trace[102171947] 'process raft request' (duration: 381.002021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:48.030525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.648064Z","time spent":"382.427957ms","remote":"127.0.0.1:38032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:31:48.030544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.649183Z","time spent":"381.31986ms","remote":"127.0.0.1:38158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:31:48.030437Z","caller":"traceutil/trace.go:171","msg":"trace[1045992266] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:332; }","duration":"338.652691ms","start":"2026-05-11T05:31:47.69177Z","end":"2026-05-11T05:31:48.030423Z","steps":["trace[1045992266] 'agreement among raft nodes before linearized reading' (duration: 338.568353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:48.030695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.691749Z","time spent":"338.926845ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-11T05:31:48.030763Z","caller":"traceutil/trace.go:171","msg":"trace[1404142481] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"379.809105ms","start":"2026-05-11T05:31:47.650942Z","end":"2026-05-11T05:31:48.030751Z","steps":["trace[1404142481] 'process raft request' (duration: 379.294239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:48.030849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:47.650929Z","time spent":"379.869481ms","remote":"127.0.0.1:37940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:31:48.445792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.639608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-11T05:31:48.445859Z","caller":"traceutil/trace.go:171","msg":"trace[1028776509] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:349; }","duration":"117.758299ms","start":"2026-05-11T05:31:48.328087Z","end":"2026-05-11T05:31:48.445845Z","steps":["trace[1028776509] 'range keys from in-memory index tree' (duration: 116.935795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:50.145745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393274949691418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:31:50.459476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.510884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:31:50.459568Z","caller":"traceutil/trace.go:171","msg":"trace[575668309] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"858.504882ms","start":"2026-05-11T05:31:49.601049Z","end":"2026-05-11T05:31:50.459554Z","steps":["trace[575668309] 'process raft request' (duration: 668.854757ms)","trace[575668309] 'compare' (duration: 189.416765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:31:50.459647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:49.601006Z","time spent":"858.5873ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:31:50.544461Z","caller":"traceutil/trace.go:171","msg":"trace[1075902589] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"899.680516ms","start":"2026-05-11T05:31:49.644761Z","end":"2026-05-11T05:31:50.544442Z","steps":["trace[1075902589] 'read index received' (duration: 625.153851ms)","trace[1075902589] 'applied index is now lower than readState.Index' (duration: 274.525965ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:31:50.54488Z","caller":"traceutil/trace.go:171","msg":"trace[713629242] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"706.032593ms","start":"2026-05-11T05:31:49.838837Z","end":"2026-05-11T05:31:50.544869Z","steps":["trace[713629242] 'process raft request' (duration: 705.456382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:50.545045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:49.838799Z","time spent":"706.11392ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:31:50.54521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.484008ms","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-05-11T05:31:50.545234Z","caller":"traceutil/trace.go:171","msg":"trace[466126681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:352; }","duration":"900.548933ms","start":"2026-05-11T05:31:49.644678Z","end":"2026-05-11T05:31:50.545227Z","steps":["trace[466126681] 'agreement among raft nodes before linearized reading' (duration: 900.498468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:50.545257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:49.644657Z","time spent":"900.596427ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:31:50.545351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.324514ms","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-11T05:31:50.545368Z","caller":"traceutil/trace.go:171","msg":"trace[1754293933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:352; }","duration":"899.38053ms","start":"2026-05-11T05:31:49.645982Z","end":"2026-05-11T05:31:50.545363Z","steps":["trace[1754293933] 'agreement among raft nodes before linearized reading' (duration: 899.350127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:50.545384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:49.64596Z","time spent":"899.420633ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T05:31:50.545522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.182122ms","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-11T05:31:50.54554Z","caller":"traceutil/trace.go:171","msg":"trace[438311850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:352; }","duration":"853.243188ms","start":"2026-05-11T05:31:49.692291Z","end":"2026-05-11T05:31:50.545535Z","steps":["trace[438311850] 'agreement among raft nodes before linearized reading' (duration: 853.207175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:50.545558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:49.692232Z","time spent":"853.321825ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T05:31:50.66096Z","caller":"traceutil/trace.go:171","msg":"trace[154677328] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"111.704096ms","start":"2026-05-11T05:31:50.549232Z","end":"2026-05-11T05:31:50.660936Z","steps":["trace[154677328] 'process raft request' (duration: 81.20246ms)","trace[154677328] 'compare' (duration: 30.31436ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:31:50.660963Z","caller":"traceutil/trace.go:171","msg":"trace[889911806] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"111.048737ms","start":"2026-05-11T05:31:50.549901Z","end":"2026-05-11T05:31:50.66095Z","steps":["trace[889911806] 'process raft request' (duration: 110.973991ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:50.661102Z","caller":"traceutil/trace.go:171","msg":"trace[959800402] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"111.052968ms","start":"2026-05-11T05:31:50.550025Z","end":"2026-05-11T05:31:50.661078Z","steps":["trace[959800402] 'process raft request' (duration: 110.882583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:51.188313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.871824ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-11T05:31:51.189263Z","caller":"traceutil/trace.go:171","msg":"trace[780415110] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"138.665628ms","start":"2026-05-11T05:31:51.050572Z","end":"2026-05-11T05:31:51.189238Z","steps":["trace[780415110] 'process raft request' (duration: 138.471961ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:31:56.358298Z","caller":"traceutil/trace.go:171","msg":"trace[358035606] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"652.178214ms","start":"2026-05-11T05:31:55.706099Z","end":"2026-05-11T05:31:56.358277Z","steps":["trace[358035606] 'process raft request' (duration: 652.044852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:31:56.35845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:31:55.706088Z","time spent":"652.293134ms","remote":"127.0.0.1:37902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:31:56.670619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.186511ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T05:31:56.67073Z","caller":"traceutil/trace.go:171","msg":"trace[2108049491] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"189.605475ms","start":"2026-05-11T05:31:56.481106Z","end":"2026-05-11T05:31:56.670711Z","steps":["trace[2108049491] 'read index received' (duration: 86.375971ms)","trace[2108049491] 'applied index is now lower than readState.Index' (duration: 103.228084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:31:56.670814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.705805ms","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-11T05:31:56.670848Z","caller":"traceutil/trace.go:171","msg":"trace[385826707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"189.771092ms","start":"2026-05-11T05:31:56.481067Z","end":"2026-05-11T05:31:56.670838Z","steps":["trace[385826707] 'agreement among raft nodes before linearized reading' (duration: 189.692064ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:06.928125Z","caller":"traceutil/trace.go:171","msg":"trace[525135270] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"180.945567ms","start":"2026-05-11T05:32:06.747148Z","end":"2026-05-11T05:32:06.928093Z","steps":["trace[525135270] 'read index received' (duration: 180.744089ms)","trace[525135270] 'applied index is now lower than readState.Index' (duration: 200.598µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:32:06.92819Z","caller":"traceutil/trace.go:171","msg":"trace[255617929] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"203.287199ms","start":"2026-05-11T05:32:06.724877Z","end":"2026-05-11T05:32:06.928165Z","steps":["trace[255617929] 'process raft request' (duration: 202.987666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:06.928391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.867306ms","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-11T05:32:06.928464Z","caller":"traceutil/trace.go:171","msg":"trace[873867629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:432; }","duration":"172.999699ms","start":"2026-05-11T05:32:06.75545Z","end":"2026-05-11T05:32:06.92845Z","steps":["trace[873867629] 'agreement among raft nodes before linearized reading' (duration: 172.851668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:06.928382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.204982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T05:32:06.92852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.179866ms","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-11T05:32:06.928541Z","caller":"traceutil/trace.go:171","msg":"trace[1057392326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:432; }","duration":"181.429049ms","start":"2026-05-11T05:32:06.747063Z","end":"2026-05-11T05:32:06.928492Z","steps":["trace[1057392326] 'agreement among raft nodes before linearized reading' (duration: 181.176373ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:06.928587Z","caller":"traceutil/trace.go:171","msg":"trace[1681193836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:432; }","duration":"167.262591ms","start":"2026-05-11T05:32:06.761309Z","end":"2026-05-11T05:32:06.928572Z","steps":["trace[1681193836] 'agreement among raft nodes before linearized reading' (duration: 167.1013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:11.416641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.960107ms","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-11T05:32:11.416738Z","caller":"traceutil/trace.go:171","msg":"trace[161508086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"445.085921ms","start":"2026-05-11T05:32:10.971632Z","end":"2026-05-11T05:32:11.416718Z","steps":["trace[161508086] 'range keys from in-memory index tree' (duration: 444.874562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:11.416781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:10.971619Z","time spent":"445.150048ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T05:32:11.416776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.744788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:32:11.416846Z","caller":"traceutil/trace.go:171","msg":"trace[1813001690] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:474; }","duration":"424.841372ms","start":"2026-05-11T05:32:10.991988Z","end":"2026-05-11T05:32:11.41683Z","steps":["trace[1813001690] 'range keys from in-memory index tree' (duration: 424.673311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:11.416841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.911601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-11T05:32:11.416843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.925911ms","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-11T05:32:11.416885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:10.991963Z","time spent":"424.91453ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-11T05:32:11.416751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.702251ms","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-05-11T05:32:11.416912Z","caller":"traceutil/trace.go:171","msg":"trace[1895884266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"447.007297ms","start":"2026-05-11T05:32:10.969885Z","end":"2026-05-11T05:32:11.416893Z","steps":["trace[1895884266] 'range keys from in-memory index tree' (duration: 446.843054ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:11.416931Z","caller":"traceutil/trace.go:171","msg":"trace[1125801812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"467.051465ms","start":"2026-05-11T05:32:10.949858Z","end":"2026-05-11T05:32:11.416909Z","steps":["trace[1125801812] 'range keys from in-memory index tree' (duration: 466.74262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:11.416958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:10.969875Z","time spent":"447.074153ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:32:11.416977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:10.94984Z","time spent":"467.128443ms","remote":"127.0.0.1:37952","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-05-11T05:32:11.416993Z","caller":"traceutil/trace.go:171","msg":"trace[2044945311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"449.963139ms","start":"2026-05-11T05:32:10.966977Z","end":"2026-05-11T05:32:11.41694Z","steps":["trace[2044945311] 'range keys from in-memory index tree' (duration: 449.591637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:11.417034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:10.966956Z","time spent":"450.068224ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:32:11.856551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393274949691673,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:32:12.03952Z","caller":"traceutil/trace.go:171","msg":"trace[1488289376] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"874.663673ms","start":"2026-05-11T05:32:11.164835Z","end":"2026-05-11T05:32:12.039499Z","steps":["trace[1488289376] 'process raft request' (duration: 874.5068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.039714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.164806Z","time spent":"874.787958ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:32:12.425097Z","caller":"traceutil/trace.go:171","msg":"trace[1306791661] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.004106892s","start":"2026-05-11T05:32:11.420963Z","end":"2026-05-11T05:32:12.42507Z","steps":["trace[1306791661] 'process raft request' (duration: 1.003989318s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.425273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.420941Z","time spent":"1.004242516s","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:32:12.425098Z","caller":"traceutil/trace.go:171","msg":"trace[1903375604] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.004228336s","start":"2026-05-11T05:32:11.420848Z","end":"2026-05-11T05:32:12.425076Z","steps":["trace[1903375604] 'process raft request' (duration: 1.003965588s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.425469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.42083Z","time spent":"1.004539812s","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:32:12.425473Z","caller":"traceutil/trace.go:171","msg":"trace[594718783] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.004216258s","start":"2026-05-11T05:32:11.421205Z","end":"2026-05-11T05:32:12.425421Z","steps":["trace[594718783] 'process raft request' (duration: 1.003816755s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.425659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.421197Z","time spent":"1.004372781s","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:32:12.425989Z","caller":"traceutil/trace.go:171","msg":"trace[573043591] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:494; }","duration":"1.07008055s","start":"2026-05-11T05:32:11.355889Z","end":"2026-05-11T05:32:12.42597Z","steps":["trace[573043591] 'read index received' (duration: 683.468403ms)","trace[573043591] 'applied index is now lower than readState.Index' (duration: 386.611147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:32:12.426228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070338078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:32:12.4263Z","caller":"traceutil/trace.go:171","msg":"trace[584035647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"1.070438344s","start":"2026-05-11T05:32:11.355842Z","end":"2026-05-11T05:32:12.426281Z","steps":["trace[584035647] 'agreement among raft nodes before linearized reading' (duration: 1.070277351s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.426353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.35582Z","time spent":"1.070520349s","remote":"127.0.0.1:37712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:32:12.426429Z","caller":"traceutil/trace.go:171","msg":"trace[1588124405] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.004147264s","start":"2026-05-11T05:32:11.422266Z","end":"2026-05-11T05:32:12.426413Z","steps":["trace[1588124405] 'process raft request' (duration: 1.003021076s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.426534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.422253Z","time spent":"1.00422846s","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:32:12.426353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.424166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:32:12.426642Z","caller":"traceutil/trace.go:171","msg":"trace[2028664146] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:479; }","duration":"973.732272ms","start":"2026-05-11T05:32:11.452877Z","end":"2026-05-11T05:32:12.426609Z","steps":["trace[2028664146] 'agreement among raft nodes before linearized reading' (duration: 973.427326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:12.426726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:11.452857Z","time spent":"973.847537ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-11T05:32:14.180228Z","caller":"traceutil/trace.go:171","msg":"trace[1496968185] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"217.19655ms","start":"2026-05-11T05:32:13.963007Z","end":"2026-05-11T05:32:14.180203Z","steps":["trace[1496968185] 'read index received' (duration: 216.568107ms)","trace[1496968185] 'applied index is now lower than readState.Index' (duration: 627.303µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:32:14.180295Z","caller":"traceutil/trace.go:171","msg":"trace[1247519914] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"232.5794ms","start":"2026-05-11T05:32:13.947685Z","end":"2026-05-11T05:32:14.180265Z","steps":["trace[1247519914] 'process raft request' (duration: 232.132089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:14.180423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.395631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-05-11T05:32:14.180466Z","caller":"traceutil/trace.go:171","msg":"trace[745245706] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"217.481037ms","start":"2026-05-11T05:32:13.962973Z","end":"2026-05-11T05:32:14.180454Z","steps":["trace[745245706] 'agreement among raft nodes before linearized reading' (duration: 217.326594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:18.966338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393274949691904,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:32:19.287065Z","caller":"traceutil/trace.go:171","msg":"trace[583425098] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"821.657259ms","start":"2026-05-11T05:32:18.465383Z","end":"2026-05-11T05:32:19.287041Z","steps":["trace[583425098] 'read index received' (duration: 818.812694ms)","trace[583425098] 'applied index is now lower than readState.Index' (duration: 2.843225ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:32:19.28718Z","caller":"traceutil/trace.go:171","msg":"trace[822679209] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"909.5967ms","start":"2026-05-11T05:32:18.377574Z","end":"2026-05-11T05:32:19.287171Z","steps":["trace[822679209] 'process raft request' (duration: 907.551628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:19.287624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:18.377552Z","time spent":"909.651228ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:32:19.287892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.472142ms","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-11T05:32:19.287956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.983117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9s8fn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-11T05:32:19.288066Z","caller":"traceutil/trace.go:171","msg":"trace[528612129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"822.661286ms","start":"2026-05-11T05:32:18.465349Z","end":"2026-05-11T05:32:19.28801Z","steps":["trace[528612129] 'agreement among raft nodes before linearized reading' (duration: 822.265249ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:19.288066Z","caller":"traceutil/trace.go:171","msg":"trace[50119510] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9s8fn; range_end:; response_count:1; response_revision:579; }","duration":"189.129672ms","start":"2026-05-11T05:32:19.098892Z","end":"2026-05-11T05:32:19.288022Z","steps":["trace[50119510] 'agreement among raft nodes before linearized reading' (duration: 188.659178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:19.288106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.633077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-11T05:32:19.288119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:18.465331Z","time spent":"822.775381ms","remote":"127.0.0.1:37952","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-05-11T05:32:19.288199Z","caller":"traceutil/trace.go:171","msg":"trace[1961520777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"822.742953ms","start":"2026-05-11T05:32:18.46541Z","end":"2026-05-11T05:32:19.288153Z","steps":["trace[1961520777] 'agreement among raft nodes before linearized reading' (duration: 822.552219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:19.287891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.470588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T05:32:19.28825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:18.465384Z","time spent":"822.85054ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T05:32:19.288331Z","caller":"traceutil/trace.go:171","msg":"trace[564910986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"809.901775ms","start":"2026-05-11T05:32:18.478362Z","end":"2026-05-11T05:32:19.288264Z","steps":["trace[564910986] 'agreement among raft nodes before linearized reading' (duration: 809.274815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:19.288378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:18.478351Z","time spent":"810.013121ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:32:19.292668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.208344ms","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-11T05:32:19.292922Z","caller":"traceutil/trace.go:171","msg":"trace[666183259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"827.521373ms","start":"2026-05-11T05:32:18.465388Z","end":"2026-05-11T05:32:19.292909Z","steps":["trace[666183259] 'agreement among raft nodes before linearized reading' (duration: 821.841213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:19.293741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:18.465368Z","time spent":"828.206381ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T05:32:20.811884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009676071s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T05:32:20.832686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:19.932549Z","time spent":"900.129534ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-11T05:32:20.833175Z","caller":"traceutil/trace.go:171","msg":"trace[145072763] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"476.922035ms","start":"2026-05-11T05:32:20.356228Z","end":"2026-05-11T05:32:20.83315Z","steps":["trace[145072763] 'read index received' (duration: 455.820811ms)","trace[145072763] 'applied index is now lower than readState.Index' (duration: 21.100174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:32:20.833427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.134878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:32:20.833423Z","caller":"traceutil/trace.go:171","msg":"trace[573944101] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.032538697s","start":"2026-05-11T05:32:19.800735Z","end":"2026-05-11T05:32:20.833274Z","steps":["trace[573944101] 'process raft request' (duration: 1.01155009s)","trace[573944101] 'compare' (duration: 20.394686ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:32:20.833472Z","caller":"traceutil/trace.go:171","msg":"trace[1314160347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"477.263084ms","start":"2026-05-11T05:32:20.356194Z","end":"2026-05-11T05:32:20.833458Z","steps":["trace[1314160347] 'agreement among raft nodes before linearized reading' (duration: 477.040611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:20.83352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:20.356174Z","time spent":"477.337391ms","remote":"127.0.0.1:37712","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-11T05:32:20.833588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:19.80071Z","time spent":"1.032796899s","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:32:22.456426Z","caller":"traceutil/trace.go:171","msg":"trace[1462890437] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"100.201749ms","start":"2026-05-11T05:32:22.356202Z","end":"2026-05-11T05:32:22.456404Z","steps":["trace[1462890437] 'read index received' (duration: 100.000114ms)","trace[1462890437] 'applied index is now lower than readState.Index' (duration: 200.665µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:32:22.456611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.390284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:32:22.45666Z","caller":"traceutil/trace.go:171","msg":"trace[1116406060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"100.450562ms","start":"2026-05-11T05:32:22.356195Z","end":"2026-05-11T05:32:22.456646Z","steps":["trace[1116406060] 'agreement among raft nodes before linearized reading' (duration: 100.337185ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:22.456944Z","caller":"traceutil/trace.go:171","msg":"trace[481922633] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"142.882123ms","start":"2026-05-11T05:32:22.314049Z","end":"2026-05-11T05:32:22.456931Z","steps":["trace[481922633] 'process raft request' (duration: 142.211251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:25.4895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.227843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-05-11T05:32:25.489609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.562017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:32:25.48971Z","caller":"traceutil/trace.go:171","msg":"trace[975305674] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"177.489158ms","start":"2026-05-11T05:32:25.312206Z","end":"2026-05-11T05:32:25.489695Z","steps":["trace[975305674] 'range keys from in-memory index tree' (duration: 177.139566ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:25.489738Z","caller":"traceutil/trace.go:171","msg":"trace[1061733272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"133.714144ms","start":"2026-05-11T05:32:25.356003Z","end":"2026-05-11T05:32:25.489717Z","steps":["trace[1061733272] 'range keys from in-memory index tree' (duration: 133.44032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:25.489919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.710809ms","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-11T05:32:25.490064Z","caller":"traceutil/trace.go:171","msg":"trace[1823928244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"100.884764ms","start":"2026-05-11T05:32:25.389155Z","end":"2026-05-11T05:32:25.490039Z","steps":["trace[1823928244] 'range keys from in-memory index tree' (duration: 100.308678ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:32.607944Z","caller":"traceutil/trace.go:171","msg":"trace[1784212717] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"130.435833ms","start":"2026-05-11T05:32:32.477461Z","end":"2026-05-11T05:32:32.607897Z","steps":["trace[1784212717] 'process raft request' (duration: 130.241616ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:35.749144Z","caller":"traceutil/trace.go:171","msg":"trace[1491382521] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"209.235752ms","start":"2026-05-11T05:32:35.539885Z","end":"2026-05-11T05:32:35.74912Z","steps":["trace[1491382521] 'read index received' (duration: 208.954895ms)","trace[1491382521] 'applied index is now lower than readState.Index' (duration: 279.917µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:32:35.749238Z","caller":"traceutil/trace.go:171","msg":"trace[692247178] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"542.275334ms","start":"2026-05-11T05:32:35.206921Z","end":"2026-05-11T05:32:35.749196Z","steps":["trace[692247178] 'process raft request' (duration: 542.027276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:35.749381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.48467ms","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-11T05:32:35.749426Z","caller":"traceutil/trace.go:171","msg":"trace[707180873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"209.578159ms","start":"2026-05-11T05:32:35.539834Z","end":"2026-05-11T05:32:35.749412Z","steps":["trace[707180873] 'agreement among raft nodes before linearized reading' (duration: 209.42136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:35.74954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.08246ms","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-11T05:32:35.749597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.471455ms","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-05-11T05:32:35.749654Z","caller":"traceutil/trace.go:171","msg":"trace[938913909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"205.236857ms","start":"2026-05-11T05:32:35.544398Z","end":"2026-05-11T05:32:35.749635Z","steps":["trace[938913909] 'agreement among raft nodes before linearized reading' (duration: 204.98178ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:35.749741Z","caller":"traceutil/trace.go:171","msg":"trace[214998494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"204.670383ms","start":"2026-05-11T05:32:35.545025Z","end":"2026-05-11T05:32:35.749695Z","steps":["trace[214998494] 'agreement among raft nodes before linearized reading' (duration: 204.456585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:32:35.749854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:32:35.206884Z","time spent":"542.57004ms","remote":"127.0.0.1:37924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:32:35.749973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.622404ms","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-11T05:32:35.750026Z","caller":"traceutil/trace.go:171","msg":"trace[161864085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"204.728293ms","start":"2026-05-11T05:32:35.545287Z","end":"2026-05-11T05:32:35.750015Z","steps":["trace[161864085] 'agreement among raft nodes before linearized reading' (duration: 204.586074ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:32:36.911545Z","caller":"traceutil/trace.go:171","msg":"trace[1136385814] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"155.373452ms","start":"2026-05-11T05:32:36.756149Z","end":"2026-05-11T05:32:36.911523Z","steps":["trace[1136385814] 'process raft request' (duration: 155.228833ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:10.552465Z","caller":"traceutil/trace.go:171","msg":"trace[591567782] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"127.740595ms","start":"2026-05-11T05:34:10.424424Z","end":"2026-05-11T05:34:10.552165Z","steps":["trace[591567782] 'process raft request' (duration: 111.516152ms)","trace[591567782] 'compare' (duration: 16.072154ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:10.894065Z","caller":"traceutil/trace.go:171","msg":"trace[1999200701] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"114.957499ms","start":"2026-05-11T05:34:10.779083Z","end":"2026-05-11T05:34:10.894041Z","steps":["trace[1999200701] 'process raft request' (duration: 50.031996ms)","trace[1999200701] 'compare' (duration: 64.655557ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:29.026324Z","caller":"traceutil/trace.go:171","msg":"trace[1748019197] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"201.79376ms","start":"2026-05-11T05:34:28.824497Z","end":"2026-05-11T05:34:29.026291Z","steps":["trace[1748019197] 'process raft request' (duration: 200.50515ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:47.247511Z","caller":"traceutil/trace.go:171","msg":"trace[861477805] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"113.68174ms","start":"2026-05-11T05:34:47.133815Z","end":"2026-05-11T05:34:47.247496Z","steps":["trace[861477805] 'read index received' (duration: 113.661128ms)","trace[861477805] 'applied index is now lower than readState.Index' (duration: 19.972µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:47.24759Z","caller":"traceutil/trace.go:171","msg":"trace[2056744164] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"118.871359ms","start":"2026-05-11T05:34:47.128698Z","end":"2026-05-11T05:34:47.24757Z","steps":["trace[2056744164] 'process raft request' (duration: 118.691658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:47.247665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.836501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1120"} {"level":"info","ts":"2026-05-11T05:34:47.247692Z","caller":"traceutil/trace.go:171","msg":"trace[95400354] range","detail":"{range_begin:/registry/services/endpoints/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1362; }","duration":"113.886614ms","start":"2026-05-11T05:34:47.133796Z","end":"2026-05-11T05:34:47.247683Z","steps":["trace[95400354] 'agreement among raft nodes before linearized reading' (duration: 113.790798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:03.011395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.264467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:35:03.011523Z","caller":"traceutil/trace.go:171","msg":"trace[1099542960] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"199.755586ms","start":"2026-05-11T05:35:02.811745Z","end":"2026-05-11T05:35:03.011501Z","steps":["trace[1099542960] 'process raft request' (duration: 62.294416ms)","trace[1099542960] 'compare' (duration: 136.891921ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:03.049157Z","caller":"traceutil/trace.go:171","msg":"trace[328558178] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1472; }","duration":"231.813654ms","start":"2026-05-11T05:35:02.817325Z","end":"2026-05-11T05:35:03.049139Z","steps":["trace[328558178] 'read index received' (duration: 56.838317ms)","trace[328558178] 'applied index is now lower than readState.Index' (duration: 174.974577ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:03.049216Z","caller":"traceutil/trace.go:171","msg":"trace[1150160828] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"237.072499ms","start":"2026-05-11T05:35:02.812114Z","end":"2026-05-11T05:35:03.049187Z","steps":["trace[1150160828] 'process raft request' (duration: 236.952371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:03.049278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.942243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-55kzv\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-05-11T05:35:03.049305Z","caller":"traceutil/trace.go:171","msg":"trace[205065340] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-55kzv; range_end:; response_count:1; response_revision:1412; }","duration":"232.003517ms","start":"2026-05-11T05:35:02.817292Z","end":"2026-05-11T05:35:03.049296Z","steps":["trace[205065340] 'agreement among raft nodes before linearized reading' (duration: 231.939422ms)"],"step_count":1}