{"level":"warn","ts":"2026-04-10T01:05:01.10783Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T01:05:01.107912Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-10T01:05:01.107975Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-10T01:05:01.107983Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-10T01:05:01.108Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-10T01:05:01.108658Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-10T01:05:01.108735Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-10T01:05:01.118295Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.259554ms"} {"level":"info","ts":"2026-04-10T01:05:01.130825Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-10T01:05:01.130908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T01:05:01.131023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-10T01:05:01.131033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T01:05:01.131082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-10T01:05:01.131188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-10T01:05:01.136717Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T01:05:01.138324Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T01:05:01.139575Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-10T01:05:01.141287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T01:05:01.141462Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T01:05:01.141529Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T01:05:01.142016Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T01:05:01.142052Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-10T01:05:01.142501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-10T01:05:01.142614Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-10T01:05:01.144959Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-10T01:05:01.145052Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-10T01:05:01.145075Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-10T01:05:01.14551Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T01:05:01.145558Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T01:05:02.131513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T01:05:02.131585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T01:05:02.131625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-10T01:05:02.131644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-10T01:05:02.131656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-10T01:05:02.131671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-10T01:05:02.131685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-10T01:05:02.132787Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T01:05:02.133069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T01:05:02.133148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T01:05:02.13317Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:05:02.133301Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T01:05:02.133454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T01:05:02.13442Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:05:02.134597Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:05:02.134675Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T01:05:02.135502Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T01:05:02.144795Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-04-10T01:05:03.920835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.644284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-10T01:05:03.921151Z","caller":"traceutil/trace.go:171","msg":"trace[1549092360] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"155.210761ms","start":"2026-04-10T01:05:03.765895Z","end":"2026-04-10T01:05:03.921105Z","steps":["trace[1549092360] 'process raft request' (duration: 41.532523ms)","trace[1549092360] 'compare' (duration: 112.532144ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:03.921203Z","caller":"traceutil/trace.go:171","msg":"trace[1315827497] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"140.251005ms","start":"2026-04-10T01:05:03.780938Z","end":"2026-04-10T01:05:03.921189Z","steps":["trace[1315827497] 'process raft request' (duration: 140.205505ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:03.921315Z","caller":"traceutil/trace.go:171","msg":"trace[1603526971] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"140.397035ms","start":"2026-04-10T01:05:03.7809Z","end":"2026-04-10T01:05:03.921297Z","steps":["trace[1603526971] 'process raft request' (duration: 140.149305ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:04.907088Z","caller":"traceutil/trace.go:171","msg":"trace[1050030827] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"100.084772ms","start":"2026-04-10T01:05:04.806979Z","end":"2026-04-10T01:05:04.907063Z","steps":["trace[1050030827] 'process raft request' (duration: 64.068325ms)","trace[1050030827] 'compare' (duration: 35.909006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:05.295492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.332671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-10T01:05:05.295612Z","caller":"traceutil/trace.go:171","msg":"trace[1215927308] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"170.123124ms","start":"2026-04-10T01:05:05.125469Z","end":"2026-04-10T01:05:05.295592Z","steps":["trace[1215927308] 'process raft request' (duration: 16.620272ms)","trace[1215927308] 'compare' (duration: 153.17759ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:05.530389Z","caller":"traceutil/trace.go:171","msg":"trace[281769638] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"193.863856ms","start":"2026-04-10T01:05:05.336491Z","end":"2026-04-10T01:05:05.530355Z","steps":["trace[281769638] 'process raft request' (duration: 134.846748ms)","trace[281769638] 'compare' (duration: 58.819117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:05.783131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.634963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-10T01:05:05.783269Z","caller":"traceutil/trace.go:171","msg":"trace[1548915749] linearizableReadLoop","detail":"{readStateIndex:108; appliedIndex:107; }","duration":"212.881701ms","start":"2026-04-10T01:05:05.570371Z","end":"2026-04-10T01:05:05.783253Z","steps":["trace[1548915749] 'read index received' (duration: 83.987416ms)","trace[1548915749] 'applied index is now lower than readState.Index' (duration: 128.893065ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:05.783343Z","caller":"traceutil/trace.go:171","msg":"trace[1475884136] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"245.313832ms","start":"2026-04-10T01:05:05.53799Z","end":"2026-04-10T01:05:05.783304Z","steps":["trace[1475884136] 'process raft request' (duration: 116.442258ms)","trace[1475884136] 'compare' (duration: 128.459563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:05.783368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.995892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-10T01:05:05.783442Z","caller":"traceutil/trace.go:171","msg":"trace[1934828323] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:0; response_revision:104; }","duration":"213.106043ms","start":"2026-04-10T01:05:05.570323Z","end":"2026-04-10T01:05:05.783429Z","steps":["trace[1934828323] 'agreement among raft nodes before linearized reading' (duration: 212.973301ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:06.296086Z","caller":"traceutil/trace.go:171","msg":"trace[178134597] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"146.98738ms","start":"2026-04-10T01:05:06.149073Z","end":"2026-04-10T01:05:06.29606Z","steps":["trace[178134597] 'process raft request' (duration: 63.647411ms)","trace[178134597] 'compare' (duration: 83.179677ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:06.719125Z","caller":"traceutil/trace.go:171","msg":"trace[555352513] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"134.112486ms","start":"2026-04-10T01:05:06.584986Z","end":"2026-04-10T01:05:06.719099Z","steps":["trace[555352513] 'process raft request' (duration: 40.975854ms)","trace[555352513] 'compare' (duration: 93.014692ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:06.94345Z","caller":"traceutil/trace.go:171","msg":"trace[676794977] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"157.406027ms","start":"2026-04-10T01:05:06.786018Z","end":"2026-04-10T01:05:06.943424Z","steps":["trace[676794977] 'process raft request' (duration: 147.693185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:07.195043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.339806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:07.195149Z","caller":"traceutil/trace.go:171","msg":"trace[1999625021] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"172.032186ms","start":"2026-04-10T01:05:07.023099Z","end":"2026-04-10T01:05:07.195131Z","steps":["trace[1999625021] 'process raft request' (duration: 28.53928ms)","trace[1999625021] 'compare' (duration: 143.187844ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:07.195618Z","caller":"traceutil/trace.go:171","msg":"trace[242064794] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"170.975128ms","start":"2026-04-10T01:05:07.02462Z","end":"2026-04-10T01:05:07.195595Z","steps":["trace[242064794] 'process raft request' (duration: 170.867378ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:07.356854Z","caller":"traceutil/trace.go:171","msg":"trace[335988649] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"132.716278ms","start":"2026-04-10T01:05:07.224116Z","end":"2026-04-10T01:05:07.356832Z","steps":["trace[335988649] 'process raft request' (duration: 76.1125ms)","trace[335988649] 'compare' (duration: 56.497227ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:07.61726Z","caller":"traceutil/trace.go:171","msg":"trace[1840508103] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"171.267892ms","start":"2026-04-10T01:05:07.445322Z","end":"2026-04-10T01:05:07.61659Z","steps":["trace[1840508103] 'process raft request' (duration: 76.356402ms)","trace[1840508103] 'compare' (duration: 94.324835ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:07.950546Z","caller":"traceutil/trace.go:171","msg":"trace[2107592283] linearizableReadLoop","detail":"{readStateIndex:164; appliedIndex:162; }","duration":"146.994643ms","start":"2026-04-10T01:05:07.803529Z","end":"2026-04-10T01:05:07.950524Z","steps":["trace[2107592283] 'read index received' (duration: 92.35144ms)","trace[2107592283] 'applied index is now lower than readState.Index' (duration: 54.642212ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:07.950649Z","caller":"traceutil/trace.go:171","msg":"trace[287708180] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"167.293172ms","start":"2026-04-10T01:05:07.78332Z","end":"2026-04-10T01:05:07.950613Z","steps":["trace[287708180] 'process raft request' (duration: 167.141841ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:07.950667Z","caller":"traceutil/trace.go:171","msg":"trace[1389482338] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"169.71298ms","start":"2026-04-10T01:05:07.780893Z","end":"2026-04-10T01:05:07.950606Z","steps":["trace[1389482338] 'process raft request' (duration: 114.993987ms)","trace[1389482338] 'compare' (duration: 54.39326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:07.951054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.486076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-10T01:05:07.951179Z","caller":"traceutil/trace.go:171","msg":"trace[705451940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:160; }","duration":"147.644638ms","start":"2026-04-10T01:05:07.803505Z","end":"2026-04-10T01:05:07.95115Z","steps":["trace[705451940] 'agreement among raft nodes before linearized reading' (duration: 147.113434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:08.275771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.130396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:08.275969Z","caller":"traceutil/trace.go:171","msg":"trace[1206312149] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"231.256678ms","start":"2026-04-10T01:05:08.044667Z","end":"2026-04-10T01:05:08.275923Z","steps":["trace[1206312149] 'process raft request' (duration: 21.87769ms)","trace[1206312149] 'compare' (duration: 208.801933ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:08.711858Z","caller":"traceutil/trace.go:171","msg":"trace[501660955] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"430.870926ms","start":"2026-04-10T01:05:08.280945Z","end":"2026-04-10T01:05:08.711816Z","steps":["trace[501660955] 'process raft request' (duration: 379.405091ms)","trace[501660955] 'compare' (duration: 51.280173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:08.711905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:08.303355Z","time spent":"408.546581ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-10T01:05:08.712064Z","caller":"traceutil/trace.go:171","msg":"trace[2084813344] linearizableReadLoop","detail":"{readStateIndex:170; appliedIndex:169; }","duration":"409.57885ms","start":"2026-04-10T01:05:08.302322Z","end":"2026-04-10T01:05:08.711901Z","steps":["trace[2084813344] 'read index received' (duration: 358.007325ms)","trace[2084813344] 'applied index is now lower than readState.Index' (duration: 51.568395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:08.712226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.873453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-10T01:05:08.71223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:08.280924Z","time spent":"431.035718ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:08.712339Z","caller":"traceutil/trace.go:171","msg":"trace[696192630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:166; }","duration":"410.039253ms","start":"2026-04-10T01:05:08.302262Z","end":"2026-04-10T01:05:08.712301Z","steps":["trace[696192630] 'agreement among raft nodes before linearized reading' (duration: 409.781042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:08.712388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:08.302238Z","time spent":"410.137035ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:05:09.248286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.044282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:09.248504Z","caller":"traceutil/trace.go:171","msg":"trace[1467278547] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"530.657723ms","start":"2026-04-10T01:05:08.717819Z","end":"2026-04-10T01:05:09.248477Z","steps":["trace[1467278547] 'process raft request' (duration: 94.287358ms)","trace[1467278547] 'compare' (duration: 435.891151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:09.24859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:08.717798Z","time spent":"530.754424ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:09.249151Z","caller":"traceutil/trace.go:171","msg":"trace[559152570] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"457.287958ms","start":"2026-04-10T01:05:08.791851Z","end":"2026-04-10T01:05:09.249139Z","steps":["trace[559152570] 'process raft request' (duration: 456.576501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:09.249231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:08.791811Z","time spent":"457.38974ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:09.489391Z","caller":"traceutil/trace.go:171","msg":"trace[922679273] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"213.149725ms","start":"2026-04-10T01:05:09.276197Z","end":"2026-04-10T01:05:09.489347Z","steps":["trace[922679273] 'process raft request' (duration: 169.451427ms)","trace[922679273] 'compare' (duration: 43.521406ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:09.495202Z","caller":"traceutil/trace.go:171","msg":"trace[703288518] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"217.615995ms","start":"2026-04-10T01:05:09.277544Z","end":"2026-04-10T01:05:09.49516Z","steps":["trace[703288518] 'process raft request' (duration: 217.464154ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:09.495251Z","caller":"traceutil/trace.go:171","msg":"trace[216009629] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"218.1099ms","start":"2026-04-10T01:05:09.277098Z","end":"2026-04-10T01:05:09.495207Z","steps":["trace[216009629] 'process raft request' (duration: 217.752577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:09.993402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.092815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:09.993672Z","caller":"traceutil/trace.go:171","msg":"trace[416835178] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:179; }","duration":"414.084298ms","start":"2026-04-10T01:05:09.579561Z","end":"2026-04-10T01:05:09.993645Z","steps":["trace[416835178] 'read index received' (duration: 156.609759ms)","trace[416835178] 'applied index is now lower than readState.Index' (duration: 257.472719ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:09.993768Z","caller":"traceutil/trace.go:171","msg":"trace[1093723607] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"493.579423ms","start":"2026-04-10T01:05:09.500164Z","end":"2026-04-10T01:05:09.993743Z","steps":["trace[1093723607] 'process raft request' (duration: 235.979953ms)","trace[1093723607] 'compare' (duration: 256.874833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:09.993971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.372001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:09.994093Z","caller":"traceutil/trace.go:171","msg":"trace[16359266] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:0; response_revision:175; }","duration":"414.571432ms","start":"2026-04-10T01:05:09.579498Z","end":"2026-04-10T01:05:09.994069Z","steps":["trace[16359266] 'agreement among raft nodes before linearized reading' (duration: 414.28859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:09.994192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:09.579472Z","time spent":"414.702044ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" "} {"level":"warn","ts":"2026-04-10T01:05:09.994207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:09.500156Z","time spent":"493.721994ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:09.994082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.300938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:09.994115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.889326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:09.994568Z","caller":"traceutil/trace.go:171","msg":"trace[65991512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"291.853303ms","start":"2026-04-10T01:05:09.702659Z","end":"2026-04-10T01:05:09.994512Z","steps":["trace[65991512] 'agreement among raft nodes before linearized reading' (duration: 291.223757ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:09.99462Z","caller":"traceutil/trace.go:171","msg":"trace[2138305011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"219.429591ms","start":"2026-04-10T01:05:09.775169Z","end":"2026-04-10T01:05:09.994598Z","steps":["trace[2138305011] 'agreement among raft nodes before linearized reading' (duration: 218.853826ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:10.233968Z","caller":"traceutil/trace.go:171","msg":"trace[31952768] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"235.258295ms","start":"2026-04-10T01:05:09.99867Z","end":"2026-04-10T01:05:10.233929Z","steps":["trace[31952768] 'process raft request' (duration: 208.432857ms)","trace[31952768] 'compare' (duration: 26.569336ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:10.287587Z","caller":"traceutil/trace.go:171","msg":"trace[262437238] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"288.169763ms","start":"2026-04-10T01:05:09.99939Z","end":"2026-04-10T01:05:10.28756Z","steps":["trace[262437238] 'process raft request' (duration: 288.066312ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:10.287998Z","caller":"traceutil/trace.go:171","msg":"trace[588246301] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"288.809189ms","start":"2026-04-10T01:05:09.998754Z","end":"2026-04-10T01:05:10.287563Z","steps":["trace[588246301] 'process raft request' (duration: 288.577437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:10.797529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.064884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:10.797759Z","caller":"traceutil/trace.go:171","msg":"trace[56619024] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"506.307484ms","start":"2026-04-10T01:05:10.29135Z","end":"2026-04-10T01:05:10.797657Z","steps":["trace[56619024] 'process raft request' (duration: 72.036169ms)","trace[56619024] 'compare' (duration: 433.836731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:10.797868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:10.291329Z","time spent":"506.503187ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:10.842031Z","caller":"traceutil/trace.go:171","msg":"trace[1092153029] linearizableReadLoop","detail":"{readStateIndex:186; appliedIndex:184; }","duration":"139.15454ms","start":"2026-04-10T01:05:10.702863Z","end":"2026-04-10T01:05:10.842017Z","steps":["trace[1092153029] 'read index received' (duration: 138.991587ms)","trace[1092153029] 'applied index is now lower than readState.Index' (duration: 162.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:10.842102Z","caller":"traceutil/trace.go:171","msg":"trace[722808705] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"531.334546ms","start":"2026-04-10T01:05:10.310763Z","end":"2026-04-10T01:05:10.842097Z","steps":["trace[722808705] 'process raft request' (duration: 531.211854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:10.842171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:10.310737Z","time spent":"531.378846ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:10.842271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.423522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:10.842319Z","caller":"traceutil/trace.go:171","msg":"trace[888548756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:181; }","duration":"139.519663ms","start":"2026-04-10T01:05:10.702765Z","end":"2026-04-10T01:05:10.842285Z","steps":["trace[888548756] 'agreement among raft nodes before linearized reading' (duration: 139.483493ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:10.842432Z","caller":"traceutil/trace.go:171","msg":"trace[1035600305] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"550.166413ms","start":"2026-04-10T01:05:10.292241Z","end":"2026-04-10T01:05:10.842408Z","steps":["trace[1035600305] 'process raft request' (duration: 549.653927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:10.842553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:10.292225Z","time spent":"550.274423ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:11.821558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.895547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:11.821692Z","caller":"traceutil/trace.go:171","msg":"trace[1077565963] linearizableReadLoop","detail":"{readStateIndex:188; appliedIndex:186; }","duration":"946.149809ms","start":"2026-04-10T01:05:10.87553Z","end":"2026-04-10T01:05:11.821679Z","steps":["trace[1077565963] 'read index received' (duration: 39.08045ms)","trace[1077565963] 'applied index is now lower than readState.Index' (duration: 907.068499ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:11.821758Z","caller":"traceutil/trace.go:171","msg":"trace[663560008] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"972.29537ms","start":"2026-04-10T01:05:10.849455Z","end":"2026-04-10T01:05:11.821751Z","steps":["trace[663560008] 'process raft request' (duration: 65.151771ms)","trace[663560008] 'compare' (duration: 906.772535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:11.821845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:10.849426Z","time spent":"972.395531ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:11.822007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.470142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:11.822027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.495016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:05:11.822032Z","caller":"traceutil/trace.go:171","msg":"trace[401887495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:183; }","duration":"946.512922ms","start":"2026-04-10T01:05:10.875513Z","end":"2026-04-10T01:05:11.822026Z","steps":["trace[401887495] 'agreement among raft nodes before linearized reading' (duration: 946.453011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:11.822048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:10.875504Z","time spent":"946.541182ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T01:05:11.822048Z","caller":"traceutil/trace.go:171","msg":"trace[1976783085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:183; }","duration":"568.547066ms","start":"2026-04-10T01:05:11.253494Z","end":"2026-04-10T01:05:11.822041Z","steps":["trace[1976783085] 'agreement among raft nodes before linearized reading' (duration: 568.507726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:11.822093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.253474Z","time spent":"568.612757ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T01:05:11.822119Z","caller":"traceutil/trace.go:171","msg":"trace[1923055951] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"969.282729ms","start":"2026-04-10T01:05:10.852797Z","end":"2026-04-10T01:05:11.822079Z","steps":["trace[1923055951] 'process raft request' (duration: 968.836075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:11.822261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:10.852778Z","time spent":"969.397731ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:11.822267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.374511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T01:05:11.82237Z","caller":"traceutil/trace.go:171","msg":"trace[123253570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:183; }","duration":"322.508743ms","start":"2026-04-10T01:05:11.499855Z","end":"2026-04-10T01:05:11.822364Z","steps":["trace[123253570] 'agreement among raft nodes before linearized reading' (duration: 322.387782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:11.822391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.499831Z","time spent":"322.554623ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:05:12.340571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.272254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:12.34075Z","caller":"traceutil/trace.go:171","msg":"trace[609073624] linearizableReadLoop","detail":"{readStateIndex:189; appliedIndex:188; }","duration":"514.828307ms","start":"2026-04-10T01:05:11.825879Z","end":"2026-04-10T01:05:12.340708Z","steps":["trace[609073624] 'read index received' (duration: 176.26835ms)","trace[609073624] 'applied index is now lower than readState.Index' (duration: 338.557507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:12.340924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.010019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a4d952321f0fc7\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-04-10T01:05:12.341019Z","caller":"traceutil/trace.go:171","msg":"trace[761324381] range","detail":"{range_begin:/registry/events/default/instance.18a4d952321f0fc7; range_end:; response_count:1; response_revision:184; }","duration":"515.143861ms","start":"2026-04-10T01:05:11.825856Z","end":"2026-04-10T01:05:12.341Z","steps":["trace[761324381] 'agreement among raft nodes before linearized reading' (duration: 514.952438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:12.341075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.825846Z","time spent":"515.220663ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":675,"request content":"key:\"/registry/events/default/instance.18a4d952321f0fc7\" "} {"level":"info","ts":"2026-04-10T01:05:12.341216Z","caller":"traceutil/trace.go:171","msg":"trace[376228712] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"515.300982ms","start":"2026-04-10T01:05:11.825484Z","end":"2026-04-10T01:05:12.340785Z","steps":["trace[376228712] 'process raft request' (duration: 176.650713ms)","trace[376228712] 'compare' (duration: 338.100873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:12.34153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.825469Z","time spent":"515.92387ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:12.52104Z","caller":"traceutil/trace.go:171","msg":"trace[1930511004] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"693.429262ms","start":"2026-04-10T01:05:11.827588Z","end":"2026-04-10T01:05:12.521017Z","steps":["trace[1930511004] 'process raft request' (duration: 693.245889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:12.521785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.827571Z","time spent":"694.13182ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:12.52104Z","caller":"traceutil/trace.go:171","msg":"trace[945605242] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"695.078281ms","start":"2026-04-10T01:05:11.825933Z","end":"2026-04-10T01:05:12.521012Z","steps":["trace[945605242] 'process raft request' (duration: 639.449161ms)","trace[945605242] 'compare' (duration: 55.348656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:12.522033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.825925Z","time spent":"696.063992ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:12.52121Z","caller":"traceutil/trace.go:171","msg":"trace[1208995945] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"177.647211ms","start":"2026-04-10T01:05:12.343533Z","end":"2026-04-10T01:05:12.521181Z","steps":["trace[1208995945] 'process raft request' (duration: 177.56696ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:12.521284Z","caller":"traceutil/trace.go:171","msg":"trace[938171642] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"575.160941ms","start":"2026-04-10T01:05:11.94611Z","end":"2026-04-10T01:05:12.521271Z","steps":["trace[938171642] 'process raft request' (duration: 574.781156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:12.522413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.946083Z","time spent":"576.295424ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:12.521337Z","caller":"traceutil/trace.go:171","msg":"trace[1431905110] linearizableReadLoop","detail":"{readStateIndex:192; appliedIndex:189; }","duration":"180.487395ms","start":"2026-04-10T01:05:12.340815Z","end":"2026-04-10T01:05:12.521302Z","steps":["trace[1431905110] 'read index received' (duration: 124.687695ms)","trace[1431905110] 'applied index is now lower than readState.Index' (duration: 55.7953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:12.52143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.917921ms","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-04-10T01:05:12.522644Z","caller":"traceutil/trace.go:171","msg":"trace[700800179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:188; }","duration":"231.180745ms","start":"2026-04-10T01:05:12.291439Z","end":"2026-04-10T01:05:12.52262Z","steps":["trace[700800179] 'agreement among raft nodes before linearized reading' (duration: 229.88899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:12.521484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.463354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:12.522902Z","caller":"traceutil/trace.go:171","msg":"trace[1976135026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:188; }","duration":"647.91155ms","start":"2026-04-10T01:05:11.874965Z","end":"2026-04-10T01:05:12.522876Z","steps":["trace[1976135026] 'agreement among raft nodes before linearized reading' (duration: 646.433482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:12.52298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:11.874956Z","time spent":"647.999622ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:13.173556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.089428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:13.173764Z","caller":"traceutil/trace.go:171","msg":"trace[19244136] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:194; }","duration":"568.622732ms","start":"2026-04-10T01:05:12.605125Z","end":"2026-04-10T01:05:13.173747Z","steps":["trace[19244136] 'read index received' (duration: 293.151299ms)","trace[19244136] 'applied index is now lower than readState.Index' (duration: 275.470443ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:13.17396Z","caller":"traceutil/trace.go:171","msg":"trace[1046088380] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"644.410615ms","start":"2026-04-10T01:05:12.529439Z","end":"2026-04-10T01:05:13.17385Z","steps":["trace[1046088380] 'process raft request' (duration: 368.942252ms)","trace[1046088380] 'compare' (duration: 274.853875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:13.174037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:12.529429Z","time spent":"644.569896ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:13.174165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.023527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:13.174233Z","caller":"traceutil/trace.go:171","msg":"trace[376440692] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:191; }","duration":"569.152628ms","start":"2026-04-10T01:05:12.605063Z","end":"2026-04-10T01:05:13.174215Z","steps":["trace[376440692] 'agreement among raft nodes before linearized reading' (duration: 568.969946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.174269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:12.605043Z","time spent":"569.219828ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" "} {"level":"info","ts":"2026-04-10T01:05:13.174401Z","caller":"traceutil/trace.go:171","msg":"trace[63152888] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"644.229345ms","start":"2026-04-10T01:05:12.53016Z","end":"2026-04-10T01:05:13.174389Z","steps":["trace[63152888] 'process raft request' (duration: 643.495865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.174461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.626706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:13.174471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:12.530117Z","time spent":"644.320705ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:13.174507Z","caller":"traceutil/trace.go:171","msg":"trace[1288319070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:191; }","duration":"198.700988ms","start":"2026-04-10T01:05:12.975792Z","end":"2026-04-10T01:05:13.174493Z","steps":["trace[1288319070] 'agreement among raft nodes before linearized reading' (duration: 198.624608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.174519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.584859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:13.174608Z","caller":"traceutil/trace.go:171","msg":"trace[653070245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:191; }","duration":"266.68884ms","start":"2026-04-10T01:05:12.9079Z","end":"2026-04-10T01:05:13.174589Z","steps":["trace[653070245] 'agreement among raft nodes before linearized reading' (duration: 266.546919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.174418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.271849ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:13.175028Z","caller":"traceutil/trace.go:171","msg":"trace[778631215] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:191; }","duration":"448.878896ms","start":"2026-04-10T01:05:12.726125Z","end":"2026-04-10T01:05:13.175004Z","steps":["trace[778631215] 'agreement among raft nodes before linearized reading' (duration: 448.223678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.90333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.385737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:13.90401Z","caller":"traceutil/trace.go:171","msg":"trace[529705762] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"724.780227ms","start":"2026-04-10T01:05:13.1792Z","end":"2026-04-10T01:05:13.90398Z","steps":["trace[529705762] 'process raft request' (duration: 267.58876ms)","trace[529705762] 'compare' (duration: 455.805069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:13.904161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.179183Z","time spent":"724.903668ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:13.90423Z","caller":"traceutil/trace.go:171","msg":"trace[1461087424] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"724.186088ms","start":"2026-04-10T01:05:13.180016Z","end":"2026-04-10T01:05:13.904202Z","steps":["trace[1461087424] 'process raft request' (duration: 723.963656ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:13.904222Z","caller":"traceutil/trace.go:171","msg":"trace[1251665431] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"724.686285ms","start":"2026-04-10T01:05:13.179409Z","end":"2026-04-10T01:05:13.904095Z","steps":["trace[1251665431] 'process raft request' (duration: 724.429652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.904417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.18001Z","time spent":"724.29455ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:13.904559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.179403Z","time spent":"725.004989ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:13.90487Z","caller":"traceutil/trace.go:171","msg":"trace[1957748404] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"719.805894ms","start":"2026-04-10T01:05:13.185031Z","end":"2026-04-10T01:05:13.904836Z","steps":["trace[1957748404] 'process raft request' (duration: 719.042145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.905007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.619436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:13.905078Z","caller":"traceutil/trace.go:171","msg":"trace[8345682] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:0; response_revision:196; }","duration":"644.767858ms","start":"2026-04-10T01:05:13.260292Z","end":"2026-04-10T01:05:13.90506Z","steps":["trace[8345682] 'agreement among raft nodes before linearized reading' (duration: 644.620187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.90505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.185023Z","time spent":"719.932106ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:13.905119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.260266Z","time spent":"644.845189ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" "} {"level":"info","ts":"2026-04-10T01:05:13.905503Z","caller":"traceutil/trace.go:171","msg":"trace[1611952235] linearizableReadLoop","detail":"{readStateIndex:201; appliedIndex:197; }","duration":"644.271472ms","start":"2026-04-10T01:05:13.260348Z","end":"2026-04-10T01:05:13.904619Z","steps":["trace[1611952235] 'read index received' (duration: 186.378806ms)","trace[1611952235] 'applied index is now lower than readState.Index' (duration: 457.890686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:13.969335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.497841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:13.969344Z","caller":"traceutil/trace.go:171","msg":"trace[799609334] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"153.765369ms","start":"2026-04-10T01:05:13.815532Z","end":"2026-04-10T01:05:13.969298Z","steps":["trace[799609334] 'process raft request' (duration: 153.378604ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:13.969413Z","caller":"traceutil/trace.go:171","msg":"trace[311558172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:197; }","duration":"393.619754ms","start":"2026-04-10T01:05:13.575779Z","end":"2026-04-10T01:05:13.969399Z","steps":["trace[311558172] 'agreement among raft nodes before linearized reading' (duration: 393.473781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.969394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.777488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:13.969465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.575689Z","time spent":"393.759255ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T01:05:13.96948Z","caller":"traceutil/trace.go:171","msg":"trace[969351295] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:197; }","duration":"539.900329ms","start":"2026-04-10T01:05:13.429561Z","end":"2026-04-10T01:05:13.969461Z","steps":["trace[969351295] 'agreement among raft nodes before linearized reading' (duration: 539.760787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:13.969529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.429542Z","time spent":"539.97275ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-10T01:05:14.484796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.857494ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:14.484854Z","caller":"traceutil/trace.go:171","msg":"trace[1465505766] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:197; }","duration":"469.935605ms","start":"2026-04-10T01:05:14.014906Z","end":"2026-04-10T01:05:14.484841Z","steps":["trace[1465505766] 'range keys from in-memory index tree' (duration: 469.844694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.48488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.341872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:14.485026Z","caller":"traceutil/trace.go:171","msg":"trace[1069744863] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"575.064177ms","start":"2026-04-10T01:05:13.909953Z","end":"2026-04-10T01:05:14.485017Z","steps":["trace[1069744863] 'process raft request' (duration: 274.532382ms)","trace[1069744863] 'compare' (duration: 300.20995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:14.485079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.909932Z","time spent":"575.124887ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:14.485091Z","caller":"traceutil/trace.go:171","msg":"trace[164565020] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:202; }","duration":"515.845078ms","start":"2026-04-10T01:05:13.969178Z","end":"2026-04-10T01:05:14.485023Z","steps":["trace[164565020] 'read index received' (duration: 215.319854ms)","trace[164565020] 'applied index is now lower than readState.Index' (duration: 300.524054ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:14.485155Z","caller":"traceutil/trace.go:171","msg":"trace[1889472747] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"575.098628ms","start":"2026-04-10T01:05:13.910026Z","end":"2026-04-10T01:05:14.485124Z","steps":["trace[1889472747] 'process raft request' (duration: 574.928375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.485269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.890078ms","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-04-10T01:05:14.485258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.322121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a4d952321f0fc7\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-04-10T01:05:14.485292Z","caller":"traceutil/trace.go:171","msg":"trace[1904844469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:199; }","duration":"575.956668ms","start":"2026-04-10T01:05:13.909327Z","end":"2026-04-10T01:05:14.485284Z","steps":["trace[1904844469] 'agreement among raft nodes before linearized reading' (duration: 575.915258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.485314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.909304Z","time spent":"576.005918ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:05:14.485319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.910008Z","time spent":"575.24066ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:14.485683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.665994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-10T01:05:14.485873Z","caller":"traceutil/trace.go:171","msg":"trace[1373000461] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:199; }","duration":"575.925758ms","start":"2026-04-10T01:05:13.909917Z","end":"2026-04-10T01:05:14.485843Z","steps":["trace[1373000461] 'agreement among raft nodes before linearized reading' (duration: 575.306529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.486065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.909899Z","time spent":"576.095571ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4002,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-04-10T01:05:14.486145Z","caller":"traceutil/trace.go:171","msg":"trace[1944842096] range","detail":"{range_begin:/registry/events/default/instance.18a4d952321f0fc7; range_end:; response_count:1; response_revision:199; }","duration":"576.197632ms","start":"2026-04-10T01:05:13.909893Z","end":"2026-04-10T01:05:14.48609Z","steps":["trace[1944842096] 'agreement among raft nodes before linearized reading' (duration: 575.281819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.486467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.909878Z","time spent":"576.568126ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":675,"request content":"key:\"/registry/events/default/instance.18a4d952321f0fc7\" "} {"level":"info","ts":"2026-04-10T01:05:14.486885Z","caller":"traceutil/trace.go:171","msg":"trace[1008960436] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"430.372319ms","start":"2026-04-10T01:05:14.056491Z","end":"2026-04-10T01:05:14.486863Z","steps":["trace[1008960436] 'process raft request' (duration: 430.200466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.486923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.983903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3474"} {"level":"info","ts":"2026-04-10T01:05:14.486963Z","caller":"traceutil/trace.go:171","msg":"trace[570516346] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:200; }","duration":"513.044504ms","start":"2026-04-10T01:05:13.973908Z","end":"2026-04-10T01:05:14.486953Z","steps":["trace[570516346] 'agreement among raft nodes before linearized reading' (duration: 512.892482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.486992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:13.973845Z","time spent":"513.136975ms","remote":"127.0.0.1:35306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3496,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-10T01:05:14.487006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:14.056458Z","time spent":"430.498141ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:14.487124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.875552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:14.48715Z","caller":"traceutil/trace.go:171","msg":"trace[442934792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:200; }","duration":"411.936294ms","start":"2026-04-10T01:05:14.075207Z","end":"2026-04-10T01:05:14.487144Z","steps":["trace[442934792] 'agreement among raft nodes before linearized reading' (duration: 411.898044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.487169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:14.075189Z","time spent":"411.975664ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:14.48698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.656803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T01:05:14.48793Z","caller":"traceutil/trace.go:171","msg":"trace[270516939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:200; }","duration":"142.647275ms","start":"2026-04-10T01:05:14.345269Z","end":"2026-04-10T01:05:14.487916Z","steps":["trace[270516939] 'agreement among raft nodes before linearized reading' (duration: 141.634832ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:14.77564Z","caller":"traceutil/trace.go:171","msg":"trace[1275375254] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"284.829975ms","start":"2026-04-10T01:05:14.490769Z","end":"2026-04-10T01:05:14.775599Z","steps":["trace[1275375254] 'process raft request' (duration: 231.818341ms)","trace[1275375254] 'compare' (duration: 52.76302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:14.775868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.249962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a4d952321f27e1\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-04-10T01:05:14.775927Z","caller":"traceutil/trace.go:171","msg":"trace[1249831080] range","detail":"{range_begin:/registry/events/default/instance.18a4d952321f27e1; range_end:; response_count:1; response_revision:206; }","duration":"249.356264ms","start":"2026-04-10T01:05:14.526557Z","end":"2026-04-10T01:05:14.775913Z","steps":["trace[1249831080] 'agreement among raft nodes before linearized reading' (duration: 249.218281ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:14.775933Z","caller":"traceutil/trace.go:171","msg":"trace[1159255457] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"284.871175ms","start":"2026-04-10T01:05:14.49105Z","end":"2026-04-10T01:05:14.775921Z","steps":["trace[1159255457] 'process raft request' (duration: 284.440209ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:14.776043Z","caller":"traceutil/trace.go:171","msg":"trace[2086349257] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"283.438596ms","start":"2026-04-10T01:05:14.492582Z","end":"2026-04-10T01:05:14.776021Z","steps":["trace[2086349257] 'process raft request' (duration: 283.019371ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:14.775725Z","caller":"traceutil/trace.go:171","msg":"trace[144633330] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"284.578501ms","start":"2026-04-10T01:05:14.491086Z","end":"2026-04-10T01:05:14.775665Z","steps":["trace[144633330] 'process raft request' (duration: 284.448949ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:14.775729Z","caller":"traceutil/trace.go:171","msg":"trace[340741962] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:207; }","duration":"249.076389ms","start":"2026-04-10T01:05:14.526593Z","end":"2026-04-10T01:05:14.775669Z","steps":["trace[340741962] 'read index received' (duration: 195.930433ms)","trace[340741962] 'applied index is now lower than readState.Index' (duration: 53.144636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:14.776661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.755459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-10T01:05:14.776726Z","caller":"traceutil/trace.go:171","msg":"trace[1916137010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:206; }","duration":"249.86912ms","start":"2026-04-10T01:05:14.526839Z","end":"2026-04-10T01:05:14.776708Z","steps":["trace[1916137010] 'agreement among raft nodes before linearized reading' (duration: 249.715258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:14.776832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.627467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:14.776913Z","caller":"traceutil/trace.go:171","msg":"trace[108088768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:206; }","duration":"100.775349ms","start":"2026-04-10T01:05:14.67612Z","end":"2026-04-10T01:05:14.776895Z","steps":["trace[108088768] 'agreement among raft nodes before linearized reading' (duration: 100.624406ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:15.149922Z","caller":"traceutil/trace.go:171","msg":"trace[592458553] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"153.594228ms","start":"2026-04-10T01:05:14.996302Z","end":"2026-04-10T01:05:15.149896Z","steps":["trace[592458553] 'read index received' (duration: 84.81553ms)","trace[592458553] 'applied index is now lower than readState.Index' (duration: 68.777748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:15.150076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.75047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a4d952321ecc83\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-04-10T01:05:15.15011Z","caller":"traceutil/trace.go:171","msg":"trace[1206430558] range","detail":"{range_begin:/registry/events/default/instance.18a4d952321ecc83; range_end:; response_count:1; response_revision:215; }","duration":"153.821021ms","start":"2026-04-10T01:05:14.99628Z","end":"2026-04-10T01:05:15.150101Z","steps":["trace[1206430558] 'agreement among raft nodes before linearized reading' (duration: 153.713509ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:15.150148Z","caller":"traceutil/trace.go:171","msg":"trace[345805507] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"154.46177ms","start":"2026-04-10T01:05:14.995667Z","end":"2026-04-10T01:05:15.150129Z","steps":["trace[345805507] 'process raft request' (duration: 85.509788ms)","trace[345805507] 'compare' (duration: 68.586446ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:15.34365Z","caller":"traceutil/trace.go:171","msg":"trace[89804493] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"134.228431ms","start":"2026-04-10T01:05:15.209348Z","end":"2026-04-10T01:05:15.343576Z","steps":["trace[89804493] 'process raft request' (duration: 52.915486ms)","trace[89804493] 'compare' (duration: 80.475803ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:15.348691Z","caller":"traceutil/trace.go:171","msg":"trace[1700082853] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"138.118254ms","start":"2026-04-10T01:05:15.210548Z","end":"2026-04-10T01:05:15.348667Z","steps":["trace[1700082853] 'process raft request' (duration: 137.898831ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:15.348837Z","caller":"traceutil/trace.go:171","msg":"trace[2127434431] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"137.873081ms","start":"2026-04-10T01:05:15.210934Z","end":"2026-04-10T01:05:15.348807Z","steps":["trace[2127434431] 'process raft request' (duration: 137.679179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:15.692405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.647596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:15.692554Z","caller":"traceutil/trace.go:171","msg":"trace[1170653591] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"262.852632ms","start":"2026-04-10T01:05:15.429686Z","end":"2026-04-10T01:05:15.692538Z","steps":["trace[1170653591] 'process raft request' (duration: 262.810362ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:15.692648Z","caller":"traceutil/trace.go:171","msg":"trace[92074086] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"263.571162ms","start":"2026-04-10T01:05:15.429039Z","end":"2026-04-10T01:05:15.69261Z","steps":["trace[92074086] 'process raft request' (duration: 160.638253ms)","trace[92074086] 'compare' (duration: 102.516873ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:15.893223Z","caller":"traceutil/trace.go:171","msg":"trace[393843461] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"122.18458ms","start":"2026-04-10T01:05:15.771002Z","end":"2026-04-10T01:05:15.893186Z","steps":["trace[393843461] 'read index received' (duration: 66.258103ms)","trace[393843461] 'applied index is now lower than readState.Index' (duration: 55.924857ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:15.893255Z","caller":"traceutil/trace.go:171","msg":"trace[1957446160] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"122.84267ms","start":"2026-04-10T01:05:15.770382Z","end":"2026-04-10T01:05:15.893224Z","steps":["trace[1957446160] 'process raft request' (duration: 66.851851ms)","trace[1957446160] 'compare' (duration: 55.790116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:15.893465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.424834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:15.893645Z","caller":"traceutil/trace.go:171","msg":"trace[1599477337] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:cloud-provider; range_end:; response_count:0; response_revision:228; }","duration":"122.656318ms","start":"2026-04-10T01:05:15.770961Z","end":"2026-04-10T01:05:15.893617Z","steps":["trace[1599477337] 'agreement among raft nodes before linearized reading' (duration: 122.349643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:15.894224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.817853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:15.894623Z","caller":"traceutil/trace.go:171","msg":"trace[1175360676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:228; }","duration":"119.406482ms","start":"2026-04-10T01:05:15.77492Z","end":"2026-04-10T01:05:15.894327Z","steps":["trace[1175360676] 'agreement among raft nodes before linearized reading' (duration: 118.50668ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:16.662431Z","caller":"traceutil/trace.go:171","msg":"trace[1920800183] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:259; }","duration":"134.959646ms","start":"2026-04-10T01:05:16.527447Z","end":"2026-04-10T01:05:16.662407Z","steps":["trace[1920800183] 'read index received' (duration: 128.325707ms)","trace[1920800183] 'applied index is now lower than readState.Index' (duration: 6.632869ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:16.662513Z","caller":"traceutil/trace.go:171","msg":"trace[208142067] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"460.670908ms","start":"2026-04-10T01:05:16.201809Z","end":"2026-04-10T01:05:16.66248Z","steps":["trace[208142067] 'process raft request' (duration: 453.889767ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:16.662573Z","caller":"traceutil/trace.go:171","msg":"trace[1600803245] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"460.663116ms","start":"2026-04-10T01:05:16.201882Z","end":"2026-04-10T01:05:16.662546Z","steps":["trace[1600803245] 'process raft request' (duration: 460.478374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:16.662619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.138609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:05:16.662662Z","caller":"traceutil/trace.go:171","msg":"trace[426486397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:256; }","duration":"135.218229ms","start":"2026-04-10T01:05:16.527433Z","end":"2026-04-10T01:05:16.662652Z","steps":["trace[426486397] 'agreement among raft nodes before linearized reading' (duration: 135.067417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:16.66267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.201781Z","time spent":"460.80487ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:16.662721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.201861Z","time spent":"460.788938ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:17.276563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289649494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:05:18.776716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000571918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-10T01:05:18.776926Z","caller":"traceutil/trace.go:171","msg":"trace[1638238942] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000876413s","start":"2026-04-10T01:05:16.776027Z","end":"2026-04-10T01:05:18.776904Z","steps":["trace[1638238942] 'agreement among raft nodes before linearized reading' (duration: 2.00063892s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:18.777011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.776012Z","time spent":"2.000983955s","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/10 01:05:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-10T01:05:19.203162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000386912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-10T01:05:19.203257Z","caller":"traceutil/trace.go:171","msg":"trace[1967742858] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000550314s","start":"2026-04-10T01:05:17.202688Z","end":"2026-04-10T01:05:19.203238Z","steps":["trace[1967742858] 'agreement among raft nodes before linearized reading' (duration: 2.000429712s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:19.203307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:17.202667Z","time spent":"2.000631296s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/10 01:05:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-10T01:05:20.144604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.866854067s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:20.144787Z","caller":"traceutil/trace.go:171","msg":"trace[676901286] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:264; }","duration":"3.368656018s","start":"2026-04-10T01:05:16.776115Z","end":"2026-04-10T01:05:20.144771Z","steps":["trace[676901286] 'read index received' (duration: 501.587437ms)","trace[676901286] 'applied index is now lower than readState.Index' (duration: 2.867067661s)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:20.144943Z","caller":"traceutil/trace.go:171","msg":"trace[1244361424] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"3.463072302s","start":"2026-04-10T01:05:16.681859Z","end":"2026-04-10T01:05:20.144931Z","steps":["trace[1244361424] 'process raft request' (duration: 595.827288ms)","trace[1244361424] 'compare' (duration: 2.866246446s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:20.145001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.681833Z","time spent":"3.463144204s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":714,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:20.145202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839864675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:20.145279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462056262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:05:20.145299Z","caller":"traceutil/trace.go:171","msg":"trace[511079549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:261; }","duration":"1.840012898s","start":"2026-04-10T01:05:18.305264Z","end":"2026-04-10T01:05:20.145277Z","steps":["trace[511079549] 'agreement among raft nodes before linearized reading' (duration: 1.839882965s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.145306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270166127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:20.145305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.7927827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-10T01:05:20.145319Z","caller":"traceutil/trace.go:171","msg":"trace[1733030063] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"3.458198032s","start":"2026-04-10T01:05:16.687089Z","end":"2026-04-10T01:05:20.145287Z","steps":["trace[1733030063] 'process raft request' (duration: 3.457615411s)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:20.145347Z","caller":"traceutil/trace.go:171","msg":"trace[631627857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:261; }","duration":"1.270238877s","start":"2026-04-10T01:05:18.875098Z","end":"2026-04-10T01:05:20.145337Z","steps":["trace[631627857] 'agreement among raft nodes before linearized reading' (duration: 1.270169826s)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:20.145344Z","caller":"traceutil/trace.go:171","msg":"trace[2091847373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:261; }","duration":"1.462163603s","start":"2026-04-10T01:05:18.683159Z","end":"2026-04-10T01:05:20.145322Z","steps":["trace[2091847373] 'agreement among raft nodes before linearized reading' (duration: 1.461923369s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.145356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:18.305243Z","time spent":"1.840098778s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:20.145377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:18.875081Z","time spent":"1.270289618s","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:20.145389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:18.683134Z","time spent":"1.462243394s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:05:20.145405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.687072Z","time spent":"3.458290132s","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:20.145424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.386410108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-10T01:05:20.145458Z","caller":"traceutil/trace.go:171","msg":"trace[1110717807] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:261; }","duration":"2.386472469s","start":"2026-04-10T01:05:17.758977Z","end":"2026-04-10T01:05:20.14545Z","steps":["trace[1110717807] 'agreement among raft nodes before linearized reading' (duration: 2.386267085s)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:20.145366Z","caller":"traceutil/trace.go:171","msg":"trace[1727748490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:261; }","duration":"2.792893202s","start":"2026-04-10T01:05:17.352457Z","end":"2026-04-10T01:05:20.14535Z","steps":["trace[1727748490] 'agreement among raft nodes before linearized reading' (duration: 2.79274245s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.145497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:17.75896Z","time spent":"2.38652888s","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-10T01:05:20.145507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:17.352434Z","time spent":"2.793064527s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T01:05:20.145666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.362604031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-10T01:05:20.145702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.341131181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:05:20.145739Z","caller":"traceutil/trace.go:171","msg":"trace[848854351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:261; }","duration":"3.362739104s","start":"2026-04-10T01:05:16.782985Z","end":"2026-04-10T01:05:20.145724Z","steps":["trace[848854351] 'agreement among raft nodes before linearized reading' (duration: 3.362104432s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.145794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.782947Z","time spent":"3.362832555s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T01:05:20.145748Z","caller":"traceutil/trace.go:171","msg":"trace[1378945265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:261; }","duration":"3.341231275s","start":"2026-04-10T01:05:16.804507Z","end":"2026-04-10T01:05:20.145738Z","steps":["trace[1378945265] 'agreement among raft nodes before linearized reading' (duration: 3.340884119s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.145918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:16.804483Z","time spent":"3.341423747s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:05:20.147407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.326031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:20.147462Z","caller":"traceutil/trace.go:171","msg":"trace[1854716094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:261; }","duration":"444.454212ms","start":"2026-04-10T01:05:19.702992Z","end":"2026-04-10T01:05:20.147446Z","steps":["trace[1854716094] 'agreement among raft nodes before linearized reading' (duration: 442.259833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.147496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:19.702969Z","time spent":"444.519394ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} 2026/04/10 01:05:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-10T01:05:20.963549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.277217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:20.963679Z","caller":"traceutil/trace.go:171","msg":"trace[53318809] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"812.789876ms","start":"2026-04-10T01:05:20.150863Z","end":"2026-04-10T01:05:20.963652Z","steps":["trace[53318809] 'process raft request' (duration: 465.332066ms)","trace[53318809] 'compare' (duration: 347.145075ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:20.963841Z","caller":"traceutil/trace.go:171","msg":"trace[1341750060] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"812.916898ms","start":"2026-04-10T01:05:20.150896Z","end":"2026-04-10T01:05:20.963813Z","steps":["trace[1341750060] 'read index received' (duration: 465.315005ms)","trace[1341750060] 'applied index is now lower than readState.Index' (duration: 347.599853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:20.963826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:20.150847Z","time spent":"812.927378ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:20.963972Z","caller":"traceutil/trace.go:171","msg":"trace[2004111762] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"813.03031ms","start":"2026-04-10T01:05:20.150934Z","end":"2026-04-10T01:05:20.963964Z","steps":["trace[2004111762] 'process raft request' (duration: 812.774796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.964021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.104861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-10T01:05:20.964041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:20.150921Z","time spent":"813.086911ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:20.964057Z","caller":"traceutil/trace.go:171","msg":"trace[1619302268] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:266; }","duration":"813.246454ms","start":"2026-04-10T01:05:20.1508Z","end":"2026-04-10T01:05:20.964046Z","steps":["trace[1619302268] 'agreement among raft nodes before linearized reading' (duration: 813.162632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.964091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:20.150785Z","time spent":"813.298285ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-public\" "} {"level":"info","ts":"2026-04-10T01:05:20.964097Z","caller":"traceutil/trace.go:171","msg":"trace[951110749] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"812.661234ms","start":"2026-04-10T01:05:20.151424Z","end":"2026-04-10T01:05:20.964086Z","steps":["trace[951110749] 'process raft request' (duration: 812.366488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.964162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:20.151408Z","time spent":"812.717605ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:20.963889Z","caller":"traceutil/trace.go:171","msg":"trace[1667835535] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"810.413053ms","start":"2026-04-10T01:05:20.153463Z","end":"2026-04-10T01:05:20.963876Z","steps":["trace[1667835535] 'process raft request' (duration: 810.369182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.964313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.106583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:20.96431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:20.153458Z","time spent":"810.80029ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:20.964344Z","caller":"traceutil/trace.go:171","msg":"trace[1632509308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:266; }","duration":"789.163323ms","start":"2026-04-10T01:05:20.175169Z","end":"2026-04-10T01:05:20.964333Z","steps":["trace[1632509308] 'agreement among raft nodes before linearized reading' (duration: 789.117732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:20.964374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:20.175153Z","time spent":"789.215523ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:21.578124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289649509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:05:22.078247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289649509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:05:22.365604Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296645546s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T01:05:22.366087Z","caller":"traceutil/trace.go:171","msg":"trace[1199454406] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"1.297195497s","start":"2026-04-10T01:05:21.068862Z","end":"2026-04-10T01:05:22.366058Z","steps":["trace[1199454406] 'process raft request' (duration: 1.296990923s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:22.366265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:21.06884Z","time spent":"1.29733488s","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:22.990162Z","caller":"traceutil/trace.go:171","msg":"trace[2087450134] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:272; }","duration":"1.912727937s","start":"2026-04-10T01:05:21.077412Z","end":"2026-04-10T01:05:22.99014Z","steps":["trace[2087450134] 'read index received' (duration: 1.288398654s)","trace[2087450134] 'applied index is now lower than readState.Index' (duration: 624.327893ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:22.990334Z","caller":"traceutil/trace.go:171","msg":"trace[473687735] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"1.784108555s","start":"2026-04-10T01:05:21.206212Z","end":"2026-04-10T01:05:22.990321Z","steps":["trace[473687735] 'process raft request' (duration: 1.765594298s)","trace[473687735] 'compare' (duration: 17.963436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:22.9904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:21.206185Z","time spent":"1.784169936s","remote":"127.0.0.1:35224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:22.990698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.638762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4134"} {"level":"info","ts":"2026-04-10T01:05:22.990831Z","caller":"traceutil/trace.go:171","msg":"trace[152055187] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:269; }","duration":"619.782545ms","start":"2026-04-10T01:05:22.371004Z","end":"2026-04-10T01:05:22.990787Z","steps":["trace[152055187] 'agreement among raft nodes before linearized reading' (duration: 619.591271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:22.990712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913291498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"warn","ts":"2026-04-10T01:05:22.990921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:22.370987Z","time spent":"619.920768ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4156,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-10T01:05:22.990956Z","caller":"traceutil/trace.go:171","msg":"trace[1291169606] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:269; }","duration":"1.913571713s","start":"2026-04-10T01:05:21.077366Z","end":"2026-04-10T01:05:22.990938Z","steps":["trace[1291169606] 'agreement among raft nodes before linearized reading' (duration: 1.913248137s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:22.990777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.570805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:22.991037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:21.077347Z","time spent":"1.913678785s","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":135,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-10T01:05:22.991085Z","caller":"traceutil/trace.go:171","msg":"trace[2002609917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:269; }","duration":"455.933601ms","start":"2026-04-10T01:05:22.535127Z","end":"2026-04-10T01:05:22.991061Z","steps":["trace[2002609917] 'agreement among raft nodes before linearized reading' (duration: 455.538114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:22.99116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:22.535104Z","time spent":"456.042844ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T01:05:23.434789Z","caller":"traceutil/trace.go:171","msg":"trace[1339229659] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"133.692654ms","start":"2026-04-10T01:05:23.301074Z","end":"2026-04-10T01:05:23.434767Z","steps":["trace[1339229659] 'process raft request' (duration: 133.537912ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:32.3787Z","caller":"traceutil/trace.go:171","msg":"trace[1989782057] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"283.430852ms","start":"2026-04-10T01:05:32.095244Z","end":"2026-04-10T01:05:32.378675Z","steps":["trace[1989782057] 'process raft request' (duration: 283.255958ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:46.587487Z","caller":"traceutil/trace.go:171","msg":"trace[968084410] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"114.213105ms","start":"2026-04-10T01:05:46.473225Z","end":"2026-04-10T01:05:46.587438Z","steps":["trace[968084410] 'process raft request' (duration: 112.305452ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:50.419773Z","caller":"traceutil/trace.go:171","msg":"trace[910123090] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"167.641265ms","start":"2026-04-10T01:05:50.252075Z","end":"2026-04-10T01:05:50.419716Z","steps":["trace[910123090] 'process raft request' (duration: 166.93499ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:50.432631Z","caller":"traceutil/trace.go:171","msg":"trace[498331940] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"116.860673ms","start":"2026-04-10T01:05:50.315745Z","end":"2026-04-10T01:05:50.432606Z","steps":["trace[498331940] 'process raft request' (duration: 115.249497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.248115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289650182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T01:05:51.39816Z","caller":"traceutil/trace.go:171","msg":"trace[1392241210] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"673.922835ms","start":"2026-04-10T01:05:50.724212Z","end":"2026-04-10T01:05:51.398135Z","steps":["trace[1392241210] 'process raft request' (duration: 669.192296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.398378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:50.724174Z","time spent":"674.108001ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:51.40116Z","caller":"traceutil/trace.go:171","msg":"trace[1078997844] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:630; }","duration":"653.450185ms","start":"2026-04-10T01:05:50.747689Z","end":"2026-04-10T01:05:51.401139Z","steps":["trace[1078997844] 'read index received' (duration: 645.824772ms)","trace[1078997844] 'applied index is now lower than readState.Index' (duration: 7.624543ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:51.401457Z","caller":"traceutil/trace.go:171","msg":"trace[2006920541] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"655.533668ms","start":"2026-04-10T01:05:50.745911Z","end":"2026-04-10T01:05:51.401444Z","steps":["trace[2006920541] 'process raft request' (duration: 654.981547ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:51.401437Z","caller":"traceutil/trace.go:171","msg":"trace[230907754] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"350.061989ms","start":"2026-04-10T01:05:51.051317Z","end":"2026-04-10T01:05:51.401379Z","steps":["trace[230907754] 'process raft request' (duration: 349.794579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.401519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:50.745897Z","time spent":"655.591709ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:51.401563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.734428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-10T01:05:51.401569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:51.051291Z","time spent":"350.216574ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:51.401605Z","caller":"traceutil/trace.go:171","msg":"trace[247058111] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:612; }","duration":"323.817362ms","start":"2026-04-10T01:05:51.077772Z","end":"2026-04-10T01:05:51.401589Z","steps":["trace[247058111] 'agreement among raft nodes before linearized reading' (duration: 323.721658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.40163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:51.077748Z","time spent":"323.876652ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-04-10T01:05:51.401709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.026945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-10T01:05:51.401707Z","caller":"traceutil/trace.go:171","msg":"trace[1791205710] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"549.128163ms","start":"2026-04-10T01:05:50.852568Z","end":"2026-04-10T01:05:51.401697Z","steps":["trace[1791205710] 'process raft request' (duration: 548.445729ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:51.401745Z","caller":"traceutil/trace.go:171","msg":"trace[75259263] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:612; }","duration":"654.063005ms","start":"2026-04-10T01:05:50.747674Z","end":"2026-04-10T01:05:51.401737Z","steps":["trace[75259263] 'agreement among raft nodes before linearized reading' (duration: 653.945111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.401759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.659714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-10T01:05:51.401767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:50.747615Z","time spent":"654.14732ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-10T01:05:51.401768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:50.852537Z","time spent":"549.206836ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:51.401805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.122513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-10T01:05:51.401779Z","caller":"traceutil/trace.go:171","msg":"trace[1297152751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"253.701555ms","start":"2026-04-10T01:05:51.148071Z","end":"2026-04-10T01:05:51.401773Z","steps":["trace[1297152751] 'agreement among raft nodes before linearized reading' (duration: 253.663183ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:51.401825Z","caller":"traceutil/trace.go:171","msg":"trace[1876171068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"258.167315ms","start":"2026-04-10T01:05:51.143653Z","end":"2026-04-10T01:05:51.40182Z","steps":["trace[1876171068] 'agreement among raft nodes before linearized reading' (duration: 258.138304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.401856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.859538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-10T01:05:51.401879Z","caller":"traceutil/trace.go:171","msg":"trace[1989721575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"148.913031ms","start":"2026-04-10T01:05:51.252961Z","end":"2026-04-10T01:05:51.401874Z","steps":["trace[1989721575] 'agreement among raft nodes before linearized reading' (duration: 148.873519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:51.401602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.727712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:05:51.401909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.242448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:05:51.401934Z","caller":"traceutil/trace.go:171","msg":"trace[255275580] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:612; }","duration":"152.116985ms","start":"2026-04-10T01:05:51.24981Z","end":"2026-04-10T01:05:51.401927Z","steps":["trace[255275580] 'agreement among raft nodes before linearized reading' (duration: 151.768653ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:51.40196Z","caller":"traceutil/trace.go:171","msg":"trace[849866543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"272.380033ms","start":"2026-04-10T01:05:51.129571Z","end":"2026-04-10T01:05:51.401951Z","steps":["trace[849866543] 'agreement among raft nodes before linearized reading' (duration: 272.29218ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:52.277552Z","caller":"traceutil/trace.go:171","msg":"trace[414995279] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"227.041063ms","start":"2026-04-10T01:05:52.050481Z","end":"2026-04-10T01:05:52.277522Z","steps":["trace[414995279] 'read index received' (duration: 226.927189ms)","trace[414995279] 'applied index is now lower than readState.Index' (duration: 112.284µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:52.277801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.301143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-10T01:05:52.277839Z","caller":"traceutil/trace.go:171","msg":"trace[840217570] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:624; }","duration":"227.400686ms","start":"2026-04-10T01:05:52.050425Z","end":"2026-04-10T01:05:52.277825Z","steps":["trace[840217570] 'agreement among raft nodes before linearized reading' (duration: 227.212419ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:52.278154Z","caller":"traceutil/trace.go:171","msg":"trace[823909138] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"409.361429ms","start":"2026-04-10T01:05:51.868779Z","end":"2026-04-10T01:05:52.27814Z","steps":["trace[823909138] 'process raft request' (duration: 408.534439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:52.278242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:51.868759Z","time spent":"409.421852ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:52.950508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289650228,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:05:53.389315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.11185532s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T01:05:53.389755Z","caller":"traceutil/trace.go:171","msg":"trace[545372303] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.134730729s","start":"2026-04-10T01:05:52.254997Z","end":"2026-04-10T01:05:53.389727Z","steps":["trace[545372303] 'process raft request' (duration: 1.134583403s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:53.389931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:52.254947Z","time spent":"1.134908065s","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:53.78116Z","caller":"traceutil/trace.go:171","msg":"trace[2113279497] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.123558454s","start":"2026-04-10T01:05:52.657501Z","end":"2026-04-10T01:05:53.781059Z","steps":["trace[2113279497] 'process raft request' (duration: 1.122933941s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:53.7815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331510459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-10T01:05:53.78157Z","caller":"traceutil/trace.go:171","msg":"trace[1287383536] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:628; }","duration":"1.331660254s","start":"2026-04-10T01:05:52.44989Z","end":"2026-04-10T01:05:53.78155Z","steps":["trace[1287383536] 'agreement among raft nodes before linearized reading' (duration: 1.331356642s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:53.781622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:52.449867Z","time spent":"1.331741196s","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-04-10T01:05:53.781787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:52.657448Z","time spent":"1.124052003s","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:53.781194Z","caller":"traceutil/trace.go:171","msg":"trace[1075961016] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.331220658s","start":"2026-04-10T01:05:52.449947Z","end":"2026-04-10T01:05:53.781168Z","steps":["trace[1075961016] 'read index received' (duration: 939.687264ms)","trace[1075961016] 'applied index is now lower than readState.Index' (duration: 391.532114ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:53.782148Z","caller":"traceutil/trace.go:171","msg":"trace[1177207066] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"698.1456ms","start":"2026-04-10T01:05:53.083987Z","end":"2026-04-10T01:05:53.782132Z","steps":["trace[1177207066] 'process raft request' (duration: 696.747719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:53.782235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.08394Z","time spent":"698.251245ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:53.78241Z","caller":"traceutil/trace.go:171","msg":"trace[471543813] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"506.780804ms","start":"2026-04-10T01:05:53.275601Z","end":"2026-04-10T01:05:53.782382Z","steps":["trace[471543813] 'process raft request' (duration: 505.456175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:53.78346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.275565Z","time spent":"507.852693ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T01:05:54.001186Z","caller":"traceutil/trace.go:171","msg":"trace[1572437697] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"218.979686ms","start":"2026-04-10T01:05:53.78217Z","end":"2026-04-10T01:05:54.00115Z","steps":["trace[1572437697] 'read index received' (duration: 205.950349ms)","trace[1572437697] 'applied index is now lower than readState.Index' (duration: 13.028007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:54.001402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.039593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T01:05:54.001484Z","caller":"traceutil/trace.go:171","msg":"trace[1205528111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"578.180059ms","start":"2026-04-10T01:05:53.423284Z","end":"2026-04-10T01:05:54.001464Z","steps":["trace[1205528111] 'agreement among raft nodes before linearized reading' (duration: 577.972011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.001531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.423256Z","time spent":"578.266612ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:05:54.001553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.180608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-10T01:05:54.001601Z","caller":"traceutil/trace.go:171","msg":"trace[1618159615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"578.277322ms","start":"2026-04-10T01:05:53.423305Z","end":"2026-04-10T01:05:54.001582Z","steps":["trace[1618159615] 'agreement among raft nodes before linearized reading' (duration: 578.004882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.001642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.423289Z","time spent":"578.344595ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T01:05:54.001704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15196341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-10T01:05:54.001735Z","caller":"traceutil/trace.go:171","msg":"trace[1608674519] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"597.167814ms","start":"2026-04-10T01:05:53.404549Z","end":"2026-04-10T01:05:54.001717Z","steps":["trace[1608674519] 'process raft request' (duration: 583.66564ms)","trace[1608674519] 'compare' (duration: 12.666583ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:54.001798Z","caller":"traceutil/trace.go:171","msg":"trace[210108662] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"1.152094645s","start":"2026-04-10T01:05:52.849681Z","end":"2026-04-10T01:05:54.001775Z","steps":["trace[210108662] 'agreement among raft nodes before linearized reading' (duration: 1.151854575s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.001848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:52.849662Z","time spent":"1.152176466s","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-10T01:05:54.001869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.40451Z","time spent":"597.298498ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:54.001993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220236079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:54.002051Z","caller":"traceutil/trace.go:171","msg":"trace[990556233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.220308011s","start":"2026-04-10T01:05:52.781708Z","end":"2026-04-10T01:05:54.002016Z","steps":["trace[990556233] 'agreement among raft nodes before linearized reading' (duration: 1.220250719s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.002078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:52.781687Z","time spent":"1.220382954s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:54.002118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.378805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-10T01:05:54.002164Z","caller":"traceutil/trace.go:171","msg":"trace[2023170616] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"351.422486ms","start":"2026-04-10T01:05:53.65073Z","end":"2026-04-10T01:05:54.002152Z","steps":["trace[2023170616] 'agreement among raft nodes before linearized reading' (duration: 351.273851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.002197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.650646Z","time spent":"351.544482ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-10T01:05:54.00222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.005792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13220"} {"level":"info","ts":"2026-04-10T01:05:54.002247Z","caller":"traceutil/trace.go:171","msg":"trace[176660201] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"106.059253ms","start":"2026-04-10T01:05:53.896176Z","end":"2026-04-10T01:05:54.002235Z","steps":["trace[176660201] 'agreement among raft nodes before linearized reading' (duration: 106.010362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.002328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.982059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-10T01:05:54.002365Z","caller":"traceutil/trace.go:171","msg":"trace[732273949] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"552.071072ms","start":"2026-04-10T01:05:53.450284Z","end":"2026-04-10T01:05:54.002355Z","steps":["trace[732273949] 'agreement among raft nodes before linearized reading' (duration: 551.985209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.002396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.450254Z","time spent":"552.135824ms","remote":"127.0.0.1:35178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-10T01:05:54.002633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.717398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-10T01:05:54.002703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.783549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:05:54.002741Z","caller":"traceutil/trace.go:171","msg":"trace[510725894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"590.867094ms","start":"2026-04-10T01:05:53.411865Z","end":"2026-04-10T01:05:54.002732Z","steps":["trace[510725894] 'agreement among raft nodes before linearized reading' (duration: 590.77919ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:54.002686Z","caller":"traceutil/trace.go:171","msg":"trace[908990304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"590.821081ms","start":"2026-04-10T01:05:53.411853Z","end":"2026-04-10T01:05:54.002674Z","steps":["trace[908990304] 'agreement among raft nodes before linearized reading' (duration: 590.692487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:54.002772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.411825Z","time spent":"590.941076ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:05:54.002859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:53.411825Z","time spent":"591.019319ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T01:05:55.931022Z","caller":"traceutil/trace.go:171","msg":"trace[100397192] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"119.206106ms","start":"2026-04-10T01:05:55.811789Z","end":"2026-04-10T01:05:55.930995Z","steps":["trace[100397192] 'process raft request' (duration: 70.313404ms)","trace[100397192] 'compare' (duration: 48.512138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:56.204458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.820858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T01:05:56.204525Z","caller":"traceutil/trace.go:171","msg":"trace[256000920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"148.942952ms","start":"2026-04-10T01:05:56.055567Z","end":"2026-04-10T01:05:56.20451Z","steps":["trace[256000920] 'range keys from in-memory index tree' (duration: 148.690943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:56.204562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.815388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:05:56.204672Z","caller":"traceutil/trace.go:171","msg":"trace[2027861499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"148.961493ms","start":"2026-04-10T01:05:56.055689Z","end":"2026-04-10T01:05:56.204651Z","steps":["trace[2027861499] 'range keys from in-memory index tree' (duration: 148.660872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:56.204697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.135556ms","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-04-10T01:05:56.204743Z","caller":"traceutil/trace.go:171","msg":"trace[192436723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"160.208559ms","start":"2026-04-10T01:05:56.044519Z","end":"2026-04-10T01:05:56.204728Z","steps":["trace[192436723] 'range keys from in-memory index tree' (duration: 159.97246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:56.204613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.712248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:05:56.204794Z","caller":"traceutil/trace.go:171","msg":"trace[1008183515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"146.907376ms","start":"2026-04-10T01:05:56.057877Z","end":"2026-04-10T01:05:56.204784Z","steps":["trace[1008183515] 'range keys from in-memory index tree' (duration: 146.619024ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:56.423231Z","caller":"traceutil/trace.go:171","msg":"trace[954529531] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"212.78075ms","start":"2026-04-10T01:05:56.210411Z","end":"2026-04-10T01:05:56.423192Z","steps":["trace[954529531] 'process raft request' (duration: 212.419646ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:56.597985Z","caller":"traceutil/trace.go:171","msg":"trace[879231170] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"387.50989ms","start":"2026-04-10T01:05:56.21044Z","end":"2026-04-10T01:05:56.59795Z","steps":["trace[879231170] 'process raft request' (duration: 343.382339ms)","trace[879231170] 'compare' (duration: 43.786608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:56.598303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:56.2104Z","time spent":"387.679215ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:56.598299Z","caller":"traceutil/trace.go:171","msg":"trace[1915759073] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"385.516424ms","start":"2026-04-10T01:05:56.212739Z","end":"2026-04-10T01:05:56.598255Z","steps":["trace[1915759073] 'process raft request' (duration: 385.079438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:56.598537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:56.212714Z","time spent":"385.694902ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:56.655691Z","caller":"traceutil/trace.go:171","msg":"trace[1400895245] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"431.325ms","start":"2026-04-10T01:05:56.22432Z","end":"2026-04-10T01:05:56.655645Z","steps":["trace[1400895245] 'process raft request' (duration: 431.018348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:56.655886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:56.224292Z","time spent":"431.496435ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:05:56.656576Z","caller":"traceutil/trace.go:171","msg":"trace[590117469] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:674; }","duration":"364.444186ms","start":"2026-04-10T01:05:56.292112Z","end":"2026-04-10T01:05:56.656556Z","steps":["trace[590117469] 'read index received' (duration: 130.856047ms)","trace[590117469] 'applied index is now lower than readState.Index' (duration: 233.586779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:56.656837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.722757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-10T01:05:56.656914Z","caller":"traceutil/trace.go:171","msg":"trace[1979553784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:656; }","duration":"364.852431ms","start":"2026-04-10T01:05:56.292044Z","end":"2026-04-10T01:05:56.656896Z","steps":["trace[1979553784] 'agreement among raft nodes before linearized reading' (duration: 364.592771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:56.656964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:56.292015Z","time spent":"364.935804ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-10T01:05:56.657926Z","caller":"traceutil/trace.go:171","msg":"trace[1348806915] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"271.963652ms","start":"2026-04-10T01:05:56.385943Z","end":"2026-04-10T01:05:56.657907Z","steps":["trace[1348806915] 'process raft request' (duration: 269.855622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:58.281348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289650323,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T01:05:58.453759Z","caller":"traceutil/trace.go:171","msg":"trace[1087950853] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"673.46425ms","start":"2026-04-10T01:05:57.780255Z","end":"2026-04-10T01:05:58.453719Z","steps":["trace[1087950853] 'read index received' (duration: 590.603363ms)","trace[1087950853] 'applied index is now lower than readState.Index' (duration: 82.858537ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:05:58.453962Z","caller":"traceutil/trace.go:171","msg":"trace[1882798999] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"699.06589ms","start":"2026-04-10T01:05:57.754878Z","end":"2026-04-10T01:05:58.453944Z","steps":["trace[1882798999] 'process raft request' (duration: 616.036747ms)","trace[1882798999] 'compare' (duration: 82.19946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:58.454082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:57.754846Z","time spent":"699.156924ms","remote":"127.0.0.1:35482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T01:05:58.454371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.085814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:58.454449Z","caller":"traceutil/trace.go:171","msg":"trace[363113921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"674.228808ms","start":"2026-04-10T01:05:57.7802Z","end":"2026-04-10T01:05:58.454429Z","steps":["trace[363113921] 'agreement among raft nodes before linearized reading' (duration: 674.035731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:58.454482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:57.780176Z","time spent":"674.300441ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:05:58.454453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.667235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-67jtc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-10T01:05:58.454614Z","caller":"traceutil/trace.go:171","msg":"trace[646700382] range","detail":"{range_begin:/registry/pods/kube-system/cilium-67jtc; range_end:; response_count:1; response_revision:665; }","duration":"422.903064ms","start":"2026-04-10T01:05:58.031656Z","end":"2026-04-10T01:05:58.454559Z","steps":["trace[646700382] 'agreement among raft nodes before linearized reading' (duration: 422.331751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:58.454706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.031627Z","time spent":"423.026558ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-67jtc\" "} {"level":"warn","ts":"2026-04-10T01:05:58.907026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.117127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:05:58.907142Z","caller":"traceutil/trace.go:171","msg":"trace[713080168] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"304.927649ms","start":"2026-04-10T01:05:58.602201Z","end":"2026-04-10T01:05:58.907129Z","steps":["trace[713080168] 'read index received' (duration: 123.668775ms)","trace[713080168] 'applied index is now lower than readState.Index' (duration: 181.257404ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:58.907414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.550484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-10T01:05:58.907441Z","caller":"traceutil/trace.go:171","msg":"trace[889678624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"304.654319ms","start":"2026-04-10T01:05:58.602779Z","end":"2026-04-10T01:05:58.907434Z","steps":["trace[889678624] 'agreement among raft nodes before linearized reading' (duration: 304.556185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:58.907463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.602752Z","time spent":"304.70639ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T01:05:58.907512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.290053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-10T01:05:58.907582Z","caller":"traceutil/trace.go:171","msg":"trace[917509810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"305.398857ms","start":"2026-04-10T01:05:58.602164Z","end":"2026-04-10T01:05:58.907563Z","steps":["trace[917509810] 'agreement among raft nodes before linearized reading' (duration: 305.136377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:58.907615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.602125Z","time spent":"305.48329ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T01:05:58.907667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.949044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:05:58.907694Z","caller":"traceutil/trace.go:171","msg":"trace[2015095067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"126.004026ms","start":"2026-04-10T01:05:58.781684Z","end":"2026-04-10T01:05:58.907688Z","steps":["trace[2015095067] 'agreement among raft nodes before linearized reading' (duration: 125.961795ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:58.907761Z","caller":"traceutil/trace.go:171","msg":"trace[1390290765] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"428.512801ms","start":"2026-04-10T01:05:58.479238Z","end":"2026-04-10T01:05:58.907751Z","steps":["trace[1390290765] 'process raft request' (duration: 246.608642ms)","trace[1390290765] 'compare' (duration: 180.783625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:05:58.907826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.479214Z","time spent":"428.571313ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:05:58.907878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.945805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-10T01:05:58.907898Z","caller":"traceutil/trace.go:171","msg":"trace[1966774491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"246.999398ms","start":"2026-04-10T01:05:58.660893Z","end":"2026-04-10T01:05:58.907893Z","steps":["trace[1966774491] 'agreement among raft nodes before linearized reading' (duration: 246.949837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:58.907827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.27802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-10T01:05:58.907928Z","caller":"traceutil/trace.go:171","msg":"trace[1909533633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:667; }","duration":"238.439727ms","start":"2026-04-10T01:05:58.669482Z","end":"2026-04-10T01:05:58.907922Z","steps":["trace[1909533633] 'agreement among raft nodes before linearized reading' (duration: 238.296532ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:05:59.71415Z","caller":"traceutil/trace.go:171","msg":"trace[924862395] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"802.63614ms","start":"2026-04-10T01:05:58.911457Z","end":"2026-04-10T01:05:59.714094Z","steps":["trace[924862395] 'process raft request' (duration: 802.358289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:05:59.71441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.911441Z","time spent":"802.828298ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:06:00.282588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289650332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:06:00.762242Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045862651s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T01:06:01.781968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999664924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-10T01:06:01.782072Z","caller":"traceutil/trace.go:171","msg":"trace[1859531661] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99980977s","start":"2026-04-10T01:05:59.782244Z","end":"2026-04-10T01:06:01.782054Z","steps":["trace[1859531661] 'agreement among raft nodes before linearized reading' (duration: 1.999691595s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.782119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:59.782222Z","time spent":"1.999885883s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/10 01:06:01 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-10T01:06:01.844717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082229473s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:06:01.845082Z","caller":"traceutil/trace.go:171","msg":"trace[1562620351] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"2.93289124s","start":"2026-04-10T01:05:58.912163Z","end":"2026-04-10T01:06:01.845055Z","steps":["trace[1562620351] 'process raft request' (duration: 1.85024781s)","trace[1562620351] 'compare' (duration: 1.081933831s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:06:01.845217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.912151Z","time spent":"2.932990242s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:06:01.845575Z","caller":"traceutil/trace.go:171","msg":"trace[1503057048] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:692; }","duration":"2.06327799s","start":"2026-04-10T01:05:59.782284Z","end":"2026-04-10T01:06:01.845562Z","steps":["trace[1503057048] 'read index received' (duration: 980.060398ms)","trace[1503057048] 'applied index is now lower than readState.Index' (duration: 1.083216912s)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:06:01.845644Z","caller":"traceutil/trace.go:171","msg":"trace[1381801933] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"2.932693402s","start":"2026-04-10T01:05:58.912944Z","end":"2026-04-10T01:06:01.845638Z","steps":["trace[1381801933] 'process raft request' (duration: 2.931873679s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.845683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.912934Z","time spent":"2.932726863s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:06:01.845838Z","caller":"traceutil/trace.go:171","msg":"trace[1909855910] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"2.932593638s","start":"2026-04-10T01:05:58.913238Z","end":"2026-04-10T01:06:01.845832Z","steps":["trace[1909855910] 'process raft request' (duration: 2.93164824s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.846713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.913231Z","time spent":"2.933459823s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:06:01.847014Z","caller":"traceutil/trace.go:171","msg":"trace[214833238] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"2.890925149s","start":"2026-04-10T01:05:58.956061Z","end":"2026-04-10T01:06:01.846986Z","steps":["trace[214833238] 'process raft request' (duration: 2.889056323s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.847162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:05:58.956036Z","time spent":"2.891046802s","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:06:01.847182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302687338s","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-04-10T01:06:01.847233Z","caller":"traceutil/trace.go:171","msg":"trace[1575941173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.302778042s","start":"2026-04-10T01:06:00.54444Z","end":"2026-04-10T01:06:01.847218Z","steps":["trace[1575941173] 'agreement among raft nodes before linearized reading' (duration: 1.302550123s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.847267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:00.544421Z","time spent":"1.302838244s","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T01:06:01.84739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.335206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:06:01.847444Z","caller":"traceutil/trace.go:171","msg":"trace[1917410922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:672; }","duration":"128.426111ms","start":"2026-04-10T01:06:01.719Z","end":"2026-04-10T01:06:01.847426Z","steps":["trace[1917410922] 'agreement among raft nodes before linearized reading' (duration: 128.271284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.84762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.772329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-10T01:06:01.847674Z","caller":"traceutil/trace.go:171","msg":"trace[360563539] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:672; }","duration":"768.893594ms","start":"2026-04-10T01:06:01.078769Z","end":"2026-04-10T01:06:01.847663Z","steps":["trace[360563539] 'agreement among raft nodes before linearized reading' (duration: 768.745919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:01.847712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:01.078749Z","time spent":"768.955416ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"info","ts":"2026-04-10T01:06:02.074851Z","caller":"traceutil/trace.go:171","msg":"trace[1342137048] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"220.226208ms","start":"2026-04-10T01:06:01.854597Z","end":"2026-04-10T01:06:02.074823Z","steps":["trace[1342137048] 'process raft request' (duration: 220.072092ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:02.075024Z","caller":"traceutil/trace.go:171","msg":"trace[2125275966] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"222.342552ms","start":"2026-04-10T01:06:01.852671Z","end":"2026-04-10T01:06:02.075013Z","steps":["trace[2125275966] 'process raft request' (duration: 221.747978ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:02.075027Z","caller":"traceutil/trace.go:171","msg":"trace[1758168048] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"220.520249ms","start":"2026-04-10T01:06:01.854484Z","end":"2026-04-10T01:06:02.075004Z","steps":["trace[1758168048] 'process raft request' (duration: 220.126623ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:02.075047Z","caller":"traceutil/trace.go:171","msg":"trace[469388360] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"178.082056ms","start":"2026-04-10T01:06:01.896952Z","end":"2026-04-10T01:06:02.075035Z","steps":["trace[469388360] 'process raft request' (duration: 177.750573ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:02.07476Z","caller":"traceutil/trace.go:171","msg":"trace[206321935] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:697; }","duration":"215.977617ms","start":"2026-04-10T01:06:01.858763Z","end":"2026-04-10T01:06:02.074741Z","steps":["trace[206321935] 'read index received' (duration: 59.483291ms)","trace[206321935] 'applied index is now lower than readState.Index' (duration: 156.493596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:06:02.075397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.616614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:02.075437Z","caller":"traceutil/trace.go:171","msg":"trace[801162600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"216.692516ms","start":"2026-04-10T01:06:01.858733Z","end":"2026-04-10T01:06:02.075426Z","steps":["trace[801162600] 'agreement among raft nodes before linearized reading' (duration: 216.579292ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:02.075722Z","caller":"traceutil/trace.go:171","msg":"trace[1796368260] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"156.411193ms","start":"2026-04-10T01:06:01.919293Z","end":"2026-04-10T01:06:02.075704Z","steps":["trace[1796368260] 'process raft request' (duration: 156.304389ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:06.291822Z","caller":"traceutil/trace.go:171","msg":"trace[1881246517] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"132.279126ms","start":"2026-04-10T01:06:06.159515Z","end":"2026-04-10T01:06:06.291794Z","steps":["trace[1881246517] 'read index received' (duration: 126.282627ms)","trace[1881246517] 'applied index is now lower than readState.Index' (duration: 5.992719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:06:06.292074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.525913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-10T01:06:06.292119Z","caller":"traceutil/trace.go:171","msg":"trace[1654255735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:710; }","duration":"195.640088ms","start":"2026-04-10T01:06:06.096467Z","end":"2026-04-10T01:06:06.292107Z","steps":["trace[1654255735] 'agreement among raft nodes before linearized reading' (duration: 195.430459ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:06.292Z","caller":"traceutil/trace.go:171","msg":"trace[1311839392] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"186.679434ms","start":"2026-04-10T01:06:06.105295Z","end":"2026-04-10T01:06:06.291974Z","steps":["trace[1311839392] 'process raft request' (duration: 180.35652ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:06.315928Z","caller":"traceutil/trace.go:171","msg":"trace[1269044892] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"150.823659ms","start":"2026-04-10T01:06:06.165078Z","end":"2026-04-10T01:06:06.315901Z","steps":["trace[1269044892] 'process raft request' (duration: 150.716015ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:09.233516Z","caller":"traceutil/trace.go:171","msg":"trace[1751376207] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"117.788144ms","start":"2026-04-10T01:06:09.115704Z","end":"2026-04-10T01:06:09.233492Z","steps":["trace[1751376207] 'process raft request' (duration: 117.638018ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:16.597682Z","caller":"traceutil/trace.go:171","msg":"trace[1687248279] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"232.039625ms","start":"2026-04-10T01:06:16.365629Z","end":"2026-04-10T01:06:16.597669Z","steps":["trace[1687248279] 'process raft request' (duration: 231.955001ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:16.597693Z","caller":"traceutil/trace.go:171","msg":"trace[1367607235] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"216.855962ms","start":"2026-04-10T01:06:16.380818Z","end":"2026-04-10T01:06:16.597674Z","steps":["trace[1367607235] 'read index received' (duration: 216.836761ms)","trace[1367607235] 'applied index is now lower than readState.Index' (duration: 17.701µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:06:16.59785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.018708ms","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-04-10T01:06:16.597971Z","caller":"traceutil/trace.go:171","msg":"trace[58740325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"217.171497ms","start":"2026-04-10T01:06:16.380774Z","end":"2026-04-10T01:06:16.597946Z","steps":["trace[58740325] 'agreement among raft nodes before linearized reading' (duration: 216.954266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.637956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.345767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.638011Z","caller":"traceutil/trace.go:171","msg":"trace[496625021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:769; }","duration":"135.411379ms","start":"2026-04-10T01:06:16.502589Z","end":"2026-04-10T01:06:16.638001Z","steps":["trace[496625021] 'agreement among raft nodes before linearized reading' (duration: 135.317855ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:16.638145Z","caller":"traceutil/trace.go:171","msg":"trace[1315409405] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"257.207787ms","start":"2026-04-10T01:06:16.38093Z","end":"2026-04-10T01:06:16.638137Z","steps":["trace[1315409405] 'process raft request' (duration: 256.797448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.638111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.761874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.638273Z","caller":"traceutil/trace.go:171","msg":"trace[195504549] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:769; }","duration":"161.957113ms","start":"2026-04-10T01:06:16.476295Z","end":"2026-04-10T01:06:16.638252Z","steps":["trace[195504549] 'agreement among raft nodes before linearized reading' (duration: 161.750983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.638539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.251865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.638578Z","caller":"traceutil/trace.go:171","msg":"trace[1147601451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:769; }","duration":"162.333751ms","start":"2026-04-10T01:06:16.476235Z","end":"2026-04-10T01:06:16.638568Z","steps":["trace[1147601451] 'agreement among raft nodes before linearized reading' (duration: 162.271638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.63876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.386968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:06:16.638859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.562159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.641554Z","caller":"traceutil/trace.go:171","msg":"trace[796321464] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:770; }","duration":"197.275461ms","start":"2026-04-10T01:06:16.444261Z","end":"2026-04-10T01:06:16.641537Z","steps":["trace[796321464] 'agreement among raft nodes before linearized reading' (duration: 194.502077ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:16.638832Z","caller":"traceutil/trace.go:171","msg":"trace[52975717] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:769; }","duration":"135.477262ms","start":"2026-04-10T01:06:16.503326Z","end":"2026-04-10T01:06:16.638803Z","steps":["trace[52975717] 'agreement among raft nodes before linearized reading' (duration: 135.347407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.638885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.236097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.641706Z","caller":"traceutil/trace.go:171","msg":"trace[1456856276] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:770; }","duration":"139.096585ms","start":"2026-04-10T01:06:16.502596Z","end":"2026-04-10T01:06:16.641692Z","steps":["trace[1456856276] 'agreement among raft nodes before linearized reading' (duration: 136.268238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.638942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.836354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.641787Z","caller":"traceutil/trace.go:171","msg":"trace[1797451520] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:770; }","duration":"250.716623ms","start":"2026-04-10T01:06:16.39106Z","end":"2026-04-10T01:06:16.641777Z","steps":["trace[1797451520] 'agreement among raft nodes before linearized reading' (duration: 247.860396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.63897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.97102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.641891Z","caller":"traceutil/trace.go:171","msg":"trace[1594771697] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:770; }","duration":"250.901342ms","start":"2026-04-10T01:06:16.390984Z","end":"2026-04-10T01:06:16.641886Z","steps":["trace[1594771697] 'agreement among raft nodes before linearized reading' (duration: 247.97339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.638999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.729409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.642171Z","caller":"traceutil/trace.go:171","msg":"trace[1272850693] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:770; }","duration":"250.934604ms","start":"2026-04-10T01:06:16.391228Z","end":"2026-04-10T01:06:16.642163Z","steps":["trace[1272850693] 'agreement among raft nodes before linearized reading' (duration: 247.657357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.639057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.133275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.642225Z","caller":"traceutil/trace.go:171","msg":"trace[1942527311] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:770; }","duration":"198.332869ms","start":"2026-04-10T01:06:16.443885Z","end":"2026-04-10T01:06:16.642218Z","steps":["trace[1942527311] 'agreement among raft nodes before linearized reading' (duration: 195.154226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.63908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.412587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.642296Z","caller":"traceutil/trace.go:171","msg":"trace[1311537027] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:770; }","duration":"198.632122ms","start":"2026-04-10T01:06:16.443639Z","end":"2026-04-10T01:06:16.642271Z","steps":["trace[1311537027] 'agreement among raft nodes before linearized reading' (duration: 195.429178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.639102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.72124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.642485Z","caller":"traceutil/trace.go:171","msg":"trace[758251747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:770; }","duration":"233.101822ms","start":"2026-04-10T01:06:16.409367Z","end":"2026-04-10T01:06:16.642468Z","steps":["trace[758251747] 'agreement among raft nodes before linearized reading' (duration: 229.723721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.639124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.265415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.642559Z","caller":"traceutil/trace.go:171","msg":"trace[910975834] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:770; }","duration":"233.710669ms","start":"2026-04-10T01:06:16.408837Z","end":"2026-04-10T01:06:16.642548Z","steps":["trace[910975834] 'agreement among raft nodes before linearized reading' (duration: 230.275594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:16.639146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.523595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:16.642704Z","caller":"traceutil/trace.go:171","msg":"trace[1038432236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:770; }","duration":"234.095987ms","start":"2026-04-10T01:06:16.408596Z","end":"2026-04-10T01:06:16.642692Z","steps":["trace[1038432236] 'agreement among raft nodes before linearized reading' (duration: 230.537587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:20.282763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842633289650628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T01:06:20.359297Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.212659301s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T01:06:20.359832Z","caller":"traceutil/trace.go:171","msg":"trace[767188612] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"577.977792ms","start":"2026-04-10T01:06:19.781829Z","end":"2026-04-10T01:06:20.359807Z","steps":["trace[767188612] 'read index received' (duration: 577.642537ms)","trace[767188612] 'applied index is now lower than readState.Index' (duration: 332.155µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:06:20.360213Z","caller":"traceutil/trace.go:171","msg":"trace[198217498] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.213658137s","start":"2026-04-10T01:06:19.146541Z","end":"2026-04-10T01:06:20.360199Z","steps":["trace[198217498] 'process raft request' (duration: 1.213129472s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:20.360321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:19.146525Z","time spent":"1.213726551s","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:06:20.360584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.319166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:06:20.360671Z","caller":"traceutil/trace.go:171","msg":"trace[1204037541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"424.442161ms","start":"2026-04-10T01:06:19.936208Z","end":"2026-04-10T01:06:20.36065Z","steps":["trace[1204037541] 'agreement among raft nodes before linearized reading' (duration: 424.225441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:20.360678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.16919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-10T01:06:20.360719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:19.936191Z","time spent":"424.518925ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-10T01:06:20.360735Z","caller":"traceutil/trace.go:171","msg":"trace[469123137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"419.257195ms","start":"2026-04-10T01:06:19.941458Z","end":"2026-04-10T01:06:20.360715Z","steps":["trace[469123137] 'agreement among raft nodes before linearized reading' (duration: 419.030154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:20.360732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.781129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T01:06:20.360791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:19.94145Z","time spent":"419.331918ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T01:06:20.360822Z","caller":"traceutil/trace.go:171","msg":"trace[1257771983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"579.072823ms","start":"2026-04-10T01:06:19.781729Z","end":"2026-04-10T01:06:20.360801Z","steps":["trace[1257771983] 'agreement among raft nodes before linearized reading' (duration: 578.80641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:20.360873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:19.781707Z","time spent":"579.157826ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:06:20.360838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.916438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-10T01:06:20.361014Z","caller":"traceutil/trace.go:171","msg":"trace[609539045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"424.254314ms","start":"2026-04-10T01:06:19.936739Z","end":"2026-04-10T01:06:20.360994Z","steps":["trace[609539045] 'agreement among raft nodes before linearized reading' (duration: 423.759251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:20.361087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:19.936721Z","time spent":"424.351458ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-10T01:06:25.235234Z","caller":"traceutil/trace.go:171","msg":"trace[1909791225] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"127.308906ms","start":"2026-04-10T01:06:25.107899Z","end":"2026-04-10T01:06:25.235208Z","steps":["trace[1909791225] 'process raft request' (duration: 127.118528ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:58.679458Z","caller":"traceutil/trace.go:171","msg":"trace[413490651] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"206.378501ms","start":"2026-04-10T01:06:58.473044Z","end":"2026-04-10T01:06:58.679422Z","steps":["trace[413490651] 'read index received' (duration: 206.114727ms)","trace[413490651] 'applied index is now lower than readState.Index' (duration: 262.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:06:58.679588Z","caller":"traceutil/trace.go:171","msg":"trace[228038430] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"379.464428ms","start":"2026-04-10T01:06:58.300078Z","end":"2026-04-10T01:06:58.679542Z","steps":["trace[228038430] 'process raft request' (duration: 379.084158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:58.679704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.616884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-10T01:06:58.67978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.861737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-10T01:06:58.679784Z","caller":"traceutil/trace.go:171","msg":"trace[1955703410] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1026; }","duration":"206.763983ms","start":"2026-04-10T01:06:58.473002Z","end":"2026-04-10T01:06:58.679766Z","steps":["trace[1955703410] 'agreement among raft nodes before linearized reading' (duration: 206.541051ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:58.679824Z","caller":"traceutil/trace.go:171","msg":"trace[718418633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"123.969783ms","start":"2026-04-10T01:06:58.555844Z","end":"2026-04-10T01:06:58.679814Z","steps":["trace[718418633] 'agreement among raft nodes before linearized reading' (duration: 123.772462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:58.679812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.300047Z","time spent":"379.643229ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:06:58.680056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.48244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-10T01:06:58.680124Z","caller":"traceutil/trace.go:171","msg":"trace[1150902589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"119.598605ms","start":"2026-04-10T01:06:58.560511Z","end":"2026-04-10T01:06:58.68011Z","steps":["trace[1150902589] 'agreement among raft nodes before linearized reading' (duration: 119.353782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:58.680462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.538982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:06:58.68051Z","caller":"traceutil/trace.go:171","msg":"trace[718638059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"119.628547ms","start":"2026-04-10T01:06:58.560867Z","end":"2026-04-10T01:06:58.680495Z","steps":["trace[718638059] 'agreement among raft nodes before linearized reading' (duration: 119.384014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:59.176998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.841604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:06:59.177184Z","caller":"traceutil/trace.go:171","msg":"trace[764924538] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"492.211709ms","start":"2026-04-10T01:06:58.684927Z","end":"2026-04-10T01:06:59.177139Z","steps":["trace[764924538] 'process raft request' (duration: 228.060068ms)","trace[764924538] 'compare' (duration: 263.628524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T01:06:59.177287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.684902Z","time spent":"492.338546ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:06:59.395571Z","caller":"traceutil/trace.go:171","msg":"trace[1220262151] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1064; }","duration":"613.262688ms","start":"2026-04-10T01:06:58.782277Z","end":"2026-04-10T01:06:59.39554Z","steps":["trace[1220262151] 'read index received' (duration: 130.633095ms)","trace[1220262151] 'applied index is now lower than readState.Index' (duration: 482.628283ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:06:59.395621Z","caller":"traceutil/trace.go:171","msg":"trace[1548527842] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"709.613793ms","start":"2026-04-10T01:06:58.685978Z","end":"2026-04-10T01:06:59.395592Z","steps":["trace[1548527842] 'process raft request' (duration: 707.806855ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:59.395629Z","caller":"traceutil/trace.go:171","msg":"trace[326602507] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"709.332488ms","start":"2026-04-10T01:06:58.686272Z","end":"2026-04-10T01:06:59.395605Z","steps":["trace[326602507] 'process raft request' (duration: 709.155268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:59.395777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.48775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:06:59.395844Z","caller":"traceutil/trace.go:171","msg":"trace[2090742063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"613.614938ms","start":"2026-04-10T01:06:58.782211Z","end":"2026-04-10T01:06:59.395826Z","steps":["trace[2090742063] 'agreement among raft nodes before linearized reading' (duration: 613.489971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:59.395905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.78218Z","time spent":"613.712993ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T01:06:59.395834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.686263Z","time spent":"709.476905ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:06:59.395907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.607797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-10T01:06:59.39609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.99767ms","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-04-10T01:06:59.39611Z","caller":"traceutil/trace.go:171","msg":"trace[1440030593] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1029; }","duration":"302.927865ms","start":"2026-04-10T01:06:59.093163Z","end":"2026-04-10T01:06:59.396091Z","steps":["trace[1440030593] 'agreement among raft nodes before linearized reading' (duration: 302.631918ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:06:59.396203Z","caller":"traceutil/trace.go:171","msg":"trace[1954136383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1029; }","duration":"461.183461ms","start":"2026-04-10T01:06:58.934996Z","end":"2026-04-10T01:06:59.39618Z","steps":["trace[1954136383] 'agreement among raft nodes before linearized reading' (duration: 460.831262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:59.396244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.934975Z","time spent":"461.262235ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T01:06:59.396246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:59.09313Z","time spent":"303.058421ms","remote":"127.0.0.1:35674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-10T01:06:59.396456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.685948Z","time spent":"709.782772ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:06:59.395883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.610725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-10T01:06:59.397363Z","caller":"traceutil/trace.go:171","msg":"trace[1416741373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"565.139188ms","start":"2026-04-10T01:06:58.832203Z","end":"2026-04-10T01:06:59.397343Z","steps":["trace[1416741373] 'agreement among raft nodes before linearized reading' (duration: 563.539271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:06:59.39802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:06:58.832178Z","time spent":"565.820936ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T01:07:25.750279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.06618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T01:07:25.750603Z","caller":"traceutil/trace.go:171","msg":"trace[1808671679] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"218.990975ms","start":"2026-04-10T01:07:25.531577Z","end":"2026-04-10T01:07:25.750568Z","steps":["trace[1808671679] 'process raft request' (duration: 106.550193ms)","trace[1808671679] 'compare' (duration: 111.977986ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:07:25.750644Z","caller":"traceutil/trace.go:171","msg":"trace[685065126] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"169.071057ms","start":"2026-04-10T01:07:25.581551Z","end":"2026-04-10T01:07:25.750622Z","steps":["trace[685065126] 'process raft request' (duration: 169.009664ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:07:25.750787Z","caller":"traceutil/trace.go:171","msg":"trace[612573015] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"217.885341ms","start":"2026-04-10T01:07:25.532888Z","end":"2026-04-10T01:07:25.750773Z","steps":["trace[612573015] 'process raft request' (duration: 217.470667ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:08:05.272642Z","caller":"traceutil/trace.go:171","msg":"trace[2026833869] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"215.460873ms","start":"2026-04-10T01:08:05.057148Z","end":"2026-04-10T01:08:05.272609Z","steps":["trace[2026833869] 'process raft request' (duration: 215.205968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:05.94546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.56886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T01:08:05.945553Z","caller":"traceutil/trace.go:171","msg":"trace[1170018113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"157.721209ms","start":"2026-04-10T01:08:05.787812Z","end":"2026-04-10T01:08:05.945533Z","steps":["trace[1170018113] 'range keys from in-memory index tree' (duration: 157.369507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:05.945673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.366381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T01:08:05.945906Z","caller":"traceutil/trace.go:171","msg":"trace[740955207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"163.628967ms","start":"2026-04-10T01:08:05.782239Z","end":"2026-04-10T01:08:05.945868Z","steps":["trace[740955207] 'range keys from in-memory index tree' (duration: 163.305218ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T01:08:06.260206Z","caller":"traceutil/trace.go:171","msg":"trace[614567594] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"211.734928ms","start":"2026-04-10T01:08:06.048416Z","end":"2026-04-10T01:08:06.260151Z","steps":["trace[614567594] 'process raft request' (duration: 211.361175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:06.593452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.678359ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-10T01:08:14.531912Z","caller":"traceutil/trace.go:171","msg":"trace[812867621] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"451.503918ms","start":"2026-04-10T01:08:14.080373Z","end":"2026-04-10T01:08:14.531877Z","steps":["trace[812867621] 'process raft request' (duration: 443.105245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:14.53218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:08:14.080351Z","time spent":"451.735272ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T01:08:14.537131Z","caller":"traceutil/trace.go:171","msg":"trace[1146537580] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1365; }","duration":"450.920283ms","start":"2026-04-10T01:08:14.086192Z","end":"2026-04-10T01:08:14.537113Z","steps":["trace[1146537580] 'read index received' (duration: 437.416466ms)","trace[1146537580] 'applied index is now lower than readState.Index' (duration: 13.501917ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T01:08:14.537321Z","caller":"traceutil/trace.go:171","msg":"trace[979590485] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"455.936389ms","start":"2026-04-10T01:08:14.081368Z","end":"2026-04-10T01:08:14.537304Z","steps":["trace[979590485] 'process raft request' (duration: 455.640081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:14.537446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.777536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-10T01:08:14.538177Z","caller":"traceutil/trace.go:171","msg":"trace[1090533004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1313; }","duration":"241.566095ms","start":"2026-04-10T01:08:14.296584Z","end":"2026-04-10T01:08:14.53815Z","steps":["trace[1090533004] 'agreement among raft nodes before linearized reading' (duration: 240.730865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:14.538114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:08:14.08134Z","time spent":"456.701965ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T01:08:14.537648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.37977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T01:08:14.538536Z","caller":"traceutil/trace.go:171","msg":"trace[1661856207] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1313; }","duration":"452.345401ms","start":"2026-04-10T01:08:14.086156Z","end":"2026-04-10T01:08:14.538502Z","steps":["trace[1661856207] 'agreement among raft nodes before linearized reading' (duration: 451.239042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T01:08:14.538654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T01:08:14.086146Z","time spent":"452.464627ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-10T01:08:30.454418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.664136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-04-10T01:08:30.454497Z","caller":"traceutil/trace.go:171","msg":"trace[536232488] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1368; }","duration":"101.788994ms","start":"2026-04-10T01:08:30.352692Z","end":"2026-04-10T01:08:30.454481Z","steps":["trace[536232488] 'range keys from in-memory index tree' (duration: 101.553368ms)"],"step_count":1}