{"level":"warn","ts":"2026-03-02T13:54:22.387875Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T13:54:22.387978Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-02T13:54:22.388069Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T13:54:22.388084Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-03-02T13:54:22.388103Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T13:54:22.38893Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-03-02T13:54:22.389018Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-02T13:54:22.398688Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.43818ms"} {"level":"info","ts":"2026-03-02T13:54:22.406785Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-03-02T13:54:22.406976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T13:54:22.407081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-03-02T13:54:22.407146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T13:54:22.407209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-03-02T13:54:22.407312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-03-02T13:54:22.414678Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T13:54:22.416085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T13:54:22.417161Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-02T13:54:22.419524Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T13:54:22.422097Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T13:54:22.422226Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T13:54:22.422247Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T13:54:22.422547Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T13:54:22.42465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-03-02T13:54:22.424847Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-03-02T13:54:22.425801Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T13:54:22.425963Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-03-02T13:54:22.426095Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-03-02T13:54:22.426385Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T13:54:22.426423Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T13:54:22.808631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T13:54:22.80868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T13:54:22.808702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-03-02T13:54:22.808713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-03-02T13:54:22.808723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-03-02T13:54:22.808732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-03-02T13:54:22.808739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-03-02T13:54:22.81088Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T13:54:22.813127Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T13:54:22.813133Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T13:54:22.813228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T13:54:22.813364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T13:54:22.81338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T13:54:22.813973Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T13:54:22.814032Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T13:54:22.814052Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T13:54:22.816833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-03-02T13:54:22.817011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T13:54:24.44401Z","caller":"traceutil/trace.go:171","msg":"trace[2069388898] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"145.713184ms","start":"2026-03-02T13:54:24.298183Z","end":"2026-03-02T13:54:24.443896Z","steps":["trace[2069388898] 'process raft request' (duration: 72.606271ms)","trace[2069388898] 'compare' (duration: 72.764781ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:54:24.444113Z","caller":"traceutil/trace.go:171","msg":"trace[1601999350] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"132.931804ms","start":"2026-03-02T13:54:24.311154Z","end":"2026-03-02T13:54:24.444086Z","steps":["trace[1601999350] 'process raft request' (duration: 132.818803ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.444253Z","caller":"traceutil/trace.go:171","msg":"trace[1944467289] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"132.947744ms","start":"2026-03-02T13:54:24.311283Z","end":"2026-03-02T13:54:24.444231Z","steps":["trace[1944467289] 'process raft request' (duration: 132.752302ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.444888Z","caller":"traceutil/trace.go:171","msg":"trace[464442095] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"132.34176ms","start":"2026-03-02T13:54:24.312534Z","end":"2026-03-02T13:54:24.444876Z","steps":["trace[464442095] 'process raft request' (duration: 131.522584ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.445382Z","caller":"traceutil/trace.go:171","msg":"trace[363400885] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"132.450251ms","start":"2026-03-02T13:54:24.312918Z","end":"2026-03-02T13:54:24.445369Z","steps":["trace[363400885] 'process raft request' (duration: 131.797817ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.44563Z","caller":"traceutil/trace.go:171","msg":"trace[12108286] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"132.681042ms","start":"2026-03-02T13:54:24.312922Z","end":"2026-03-02T13:54:24.445603Z","steps":["trace[12108286] 'process raft request' (duration: 132.34845ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.445748Z","caller":"traceutil/trace.go:171","msg":"trace[1318959088] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"131.418084ms","start":"2026-03-02T13:54:24.314319Z","end":"2026-03-02T13:54:24.445737Z","steps":["trace[1318959088] 'process raft request' (duration: 131.259913ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.446059Z","caller":"traceutil/trace.go:171","msg":"trace[1906997749] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"131.375033ms","start":"2026-03-02T13:54:24.314663Z","end":"2026-03-02T13:54:24.446038Z","steps":["trace[1906997749] 'process raft request' (duration: 130.94937ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.44646Z","caller":"traceutil/trace.go:171","msg":"trace[1399144595] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"128.698127ms","start":"2026-03-02T13:54:24.317753Z","end":"2026-03-02T13:54:24.446451Z","steps":["trace[1399144595] 'process raft request' (duration: 128.137413ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.44624Z","caller":"traceutil/trace.go:171","msg":"trace[2038499370] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"134.363512ms","start":"2026-03-02T13:54:24.311865Z","end":"2026-03-02T13:54:24.446228Z","steps":["trace[2038499370] 'read index received' (duration: 59.019284ms)","trace[2038499370] 'applied index is now lower than readState.Index' (duration: 75.343278ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:54:24.446803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.901056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-02T13:54:24.446855Z","caller":"traceutil/trace.go:171","msg":"trace[1375132861] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"135.004197ms","start":"2026-03-02T13:54:24.311837Z","end":"2026-03-02T13:54:24.446842Z","steps":["trace[1375132861] 'agreement among raft nodes before linearized reading' (duration: 134.901056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:24.447494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.020343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-02T13:54:24.447523Z","caller":"traceutil/trace.go:171","msg":"trace[1340047312] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"114.064753ms","start":"2026-03-02T13:54:24.33345Z","end":"2026-03-02T13:54:24.447515Z","steps":["trace[1340047312] 'agreement among raft nodes before linearized reading' (duration: 114.026193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:24.447651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.55694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-2xbhg\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-03-02T13:54:24.447674Z","caller":"traceutil/trace.go:171","msg":"trace[1614411603] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-2xbhg; range_end:; response_count:1; response_revision:19; }","duration":"135.61163ms","start":"2026-03-02T13:54:24.312056Z","end":"2026-03-02T13:54:24.447668Z","steps":["trace[1614411603] 'agreement among raft nodes before linearized reading' (duration: 135.219478ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.608526Z","caller":"traceutil/trace.go:171","msg":"trace[151541179] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"123.212221ms","start":"2026-03-02T13:54:24.485286Z","end":"2026-03-02T13:54:24.608498Z","steps":["trace[151541179] 'process raft request' (duration: 123.10468ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.608683Z","caller":"traceutil/trace.go:171","msg":"trace[376713081] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"124.766631ms","start":"2026-03-02T13:54:24.483904Z","end":"2026-03-02T13:54:24.60867Z","steps":["trace[376713081] 'process raft request' (duration: 124.452399ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.608657Z","caller":"traceutil/trace.go:171","msg":"trace[1584763543] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"105.769621ms","start":"2026-03-02T13:54:24.502866Z","end":"2026-03-02T13:54:24.608635Z","steps":["trace[1584763543] 'process raft request' (duration: 105.56571ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:24.608676Z","caller":"traceutil/trace.go:171","msg":"trace[301064276] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"125.709957ms","start":"2026-03-02T13:54:24.482933Z","end":"2026-03-02T13:54:24.608643Z","steps":["trace[301064276] 'process raft request' (duration: 79.155112ms)","trace[301064276] 'compare' (duration: 46.159923ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:54:39.010584Z","caller":"traceutil/trace.go:171","msg":"trace[308668319] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"108.655462ms","start":"2026-03-02T13:54:38.901904Z","end":"2026-03-02T13:54:39.01056Z","steps":["trace[308668319] 'process raft request' (duration: 108.534737ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:39.578552Z","caller":"traceutil/trace.go:171","msg":"trace[2121426044] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"361.871954ms","start":"2026-03-02T13:54:39.216622Z","end":"2026-03-02T13:54:39.578494Z","steps":["trace[2121426044] 'process raft request' (duration: 361.669866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:39.579481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:39.216602Z","time spent":"362.059232ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:54:41.047611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.748747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T13:54:41.047683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062075523s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:54:41.047836Z","caller":"traceutil/trace.go:171","msg":"trace[2019074348] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:413; }","duration":"1.062249379s","start":"2026-03-02T13:54:39.985565Z","end":"2026-03-02T13:54:41.047814Z","steps":["trace[2019074348] 'range keys from in-memory index tree' (duration: 1.061977009s)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:41.047842Z","caller":"traceutil/trace.go:171","msg":"trace[76803008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"177.984356ms","start":"2026-03-02T13:54:40.86979Z","end":"2026-03-02T13:54:41.047774Z","steps":["trace[76803008] 'range keys from in-memory index tree' (duration: 177.661294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:41.048198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.311723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-03-02T13:54:41.048253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.630809ms","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-03-02T13:54:41.0483Z","caller":"traceutil/trace.go:171","msg":"trace[1664971737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"361.715292ms","start":"2026-03-02T13:54:40.686574Z","end":"2026-03-02T13:54:41.048289Z","steps":["trace[1664971737] 'range keys from in-memory index tree' (duration: 361.555147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:41.048333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:40.686556Z","time spent":"361.769305ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T13:54:41.048351Z","caller":"traceutil/trace.go:171","msg":"trace[1664154563] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:413; }","duration":"943.431238ms","start":"2026-03-02T13:54:40.104844Z","end":"2026-03-02T13:54:41.048275Z","steps":["trace[1664154563] 'range keys from in-memory index tree' (duration: 943.227351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:41.048396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.673326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-02T13:54:41.048491Z","caller":"traceutil/trace.go:171","msg":"trace[1613484075] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:413; }","duration":"489.79981ms","start":"2026-03-02T13:54:40.558673Z","end":"2026-03-02T13:54:41.048473Z","steps":["trace[1613484075] 'range keys from in-memory index tree' (duration: 489.52243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:41.048582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:40.558653Z","time spent":"489.915965ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4346,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-02T13:54:41.048199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035088633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T13:54:41.049608Z","caller":"traceutil/trace.go:171","msg":"trace[1133128629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"1.036488076s","start":"2026-03-02T13:54:40.013078Z","end":"2026-03-02T13:54:41.049566Z","steps":["trace[1133128629] 'range keys from in-memory index tree' (duration: 1.034939638s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:41.049756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:40.01299Z","time spent":"1.036706474s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T13:54:41.048511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:40.104825Z","time spent":"943.631476ms","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4080,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-03-02T13:54:42.070041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.032507ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-02T13:54:42.07016Z","caller":"traceutil/trace.go:171","msg":"trace[1995742910] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"1.055717906s","start":"2026-03-02T13:54:41.014416Z","end":"2026-03-02T13:54:42.070134Z","steps":["trace[1995742910] 'read index received' (duration: 175.449501ms)","trace[1995742910] 'applied index is now lower than readState.Index' (duration: 880.267075ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:54:42.070287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055870861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T13:54:42.070319Z","caller":"traceutil/trace.go:171","msg":"trace[42188225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"1.055954225s","start":"2026-03-02T13:54:41.014356Z","end":"2026-03-02T13:54:42.07031Z","steps":["trace[42188225] 'agreement among raft nodes before linearized reading' (duration: 1.055875312s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:42.070356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.014334Z","time spent":"1.056014547s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T13:54:42.199553Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009770682s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T13:54:42.200082Z","caller":"traceutil/trace.go:171","msg":"trace[706195888] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.143800197s","start":"2026-03-02T13:54:41.056252Z","end":"2026-03-02T13:54:42.200052Z","steps":["trace[706195888] 'process raft request' (duration: 1.143565328s)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:42.200393Z","caller":"traceutil/trace.go:171","msg":"trace[831395194] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.140942328s","start":"2026-03-02T13:54:41.05944Z","end":"2026-03-02T13:54:42.200382Z","steps":["trace[831395194] 'process raft request' (duration: 1.140777882s)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:42.200373Z","caller":"traceutil/trace.go:171","msg":"trace[700450422] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.144076708s","start":"2026-03-02T13:54:41.056257Z","end":"2026-03-02T13:54:42.200334Z","steps":["trace[700450422] 'process raft request' (duration: 1.143704384s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:42.200513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.059432Z","time spent":"1.141007071s","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:54:42.200513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.05624Z","time spent":"1.144204542s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:54:42.200527Z","caller":"traceutil/trace.go:171","msg":"trace[1240806846] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:426; }","duration":"130.321247ms","start":"2026-03-02T13:54:42.070191Z","end":"2026-03-02T13:54:42.200512Z","steps":["trace[1240806846] 'read index received' (duration: 129.59041ms)","trace[1240806846] 'applied index is now lower than readState.Index' (duration: 729.917µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:54:42.2006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.415514ms","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-03-02T13:54:42.200631Z","caller":"traceutil/trace.go:171","msg":"trace[1123803238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"617.482317ms","start":"2026-03-02T13:54:41.583138Z","end":"2026-03-02T13:54:42.20062Z","steps":["trace[1123803238] 'agreement among raft nodes before linearized reading' (duration: 617.419815ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:42.20064Z","caller":"traceutil/trace.go:171","msg":"trace[1664783006] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.134465984s","start":"2026-03-02T13:54:41.066155Z","end":"2026-03-02T13:54:42.200621Z","steps":["trace[1664783006] 'process raft request' (duration: 1.134166633s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:42.200659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.583116Z","time spent":"617.536898ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T13:54:42.200683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.066144Z","time spent":"1.134515986s","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:54:42.200775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.916592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:54:42.200798Z","caller":"traceutil/trace.go:171","msg":"trace[622359581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"536.967004ms","start":"2026-03-02T13:54:41.663824Z","end":"2026-03-02T13:54:42.200791Z","steps":["trace[622359581] 'agreement among raft nodes before linearized reading' (duration: 536.932922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:42.200821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.663809Z","time spent":"537.007386ms","remote":"127.0.0.1:41618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T13:54:42.20045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:41.056233Z","time spent":"1.143963233s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:54:42.209422Z","caller":"traceutil/trace.go:171","msg":"trace[1003716898] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"134.477992ms","start":"2026-03-02T13:54:42.074924Z","end":"2026-03-02T13:54:42.209402Z","steps":["trace[1003716898] 'process raft request' (duration: 134.364188ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:48.41833Z","caller":"traceutil/trace.go:171","msg":"trace[220614739] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"191.110256ms","start":"2026-03-02T13:54:48.227194Z","end":"2026-03-02T13:54:48.418304Z","steps":["trace[220614739] 'process raft request' (duration: 190.984512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:48.418506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.119342ms","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-03-02T13:54:48.418567Z","caller":"traceutil/trace.go:171","msg":"trace[265253539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"184.232756ms","start":"2026-03-02T13:54:48.234326Z","end":"2026-03-02T13:54:48.418558Z","steps":["trace[265253539] 'agreement among raft nodes before linearized reading' (duration: 184.06668ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:48.418342Z","caller":"traceutil/trace.go:171","msg":"trace[1114010429] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:478; }","duration":"183.941086ms","start":"2026-03-02T13:54:48.234364Z","end":"2026-03-02T13:54:48.418306Z","steps":["trace[1114010429] 'read index received' (duration: 183.930236ms)","trace[1114010429] 'applied index is now lower than readState.Index' (duration: 9.01µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:54:48.428326Z","caller":"traceutil/trace.go:171","msg":"trace[754240732] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"163.925518ms","start":"2026-03-02T13:54:48.264383Z","end":"2026-03-02T13:54:48.428308Z","steps":["trace[754240732] 'process raft request' (duration: 159.710852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:48.428629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.160964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T13:54:48.428618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.291162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-02T13:54:48.428694Z","caller":"traceutil/trace.go:171","msg":"trace[490656694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"126.266687ms","start":"2026-03-02T13:54:48.302409Z","end":"2026-03-02T13:54:48.428675Z","steps":["trace[490656694] 'agreement among raft nodes before linearized reading' (duration: 126.152033ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:48.428705Z","caller":"traceutil/trace.go:171","msg":"trace[1258159169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"189.435237ms","start":"2026-03-02T13:54:48.239251Z","end":"2026-03-02T13:54:48.428686Z","steps":["trace[1258159169] 'agreement among raft nodes before linearized reading' (duration: 189.254511ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.753649Z","caller":"traceutil/trace.go:171","msg":"trace[1780390468] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"289.746489ms","start":"2026-03-02T13:54:56.463878Z","end":"2026-03-02T13:54:56.753625Z","steps":["trace[1780390468] 'read index received' (duration: 289.587464ms)","trace[1780390468] 'applied index is now lower than readState.Index' (duration: 158.065µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:54:56.75381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.873895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T13:54:56.753755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:55.903244Z","time spent":"850.498913ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T13:54:56.753845Z","caller":"traceutil/trace.go:171","msg":"trace[1816270227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"285.962628ms","start":"2026-03-02T13:54:56.467871Z","end":"2026-03-02T13:54:56.753834Z","steps":["trace[1816270227] 'agreement among raft nodes before linearized reading' (duration: 285.879246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:56.753864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.985016ms","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-03-02T13:54:56.753929Z","caller":"traceutil/trace.go:171","msg":"trace[300203529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"290.10408ms","start":"2026-03-02T13:54:56.463805Z","end":"2026-03-02T13:54:56.753909Z","steps":["trace[300203529] 'agreement among raft nodes before linearized reading' (duration: 289.918204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:56.754118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.413552ms","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-03-02T13:54:56.754149Z","caller":"traceutil/trace.go:171","msg":"trace[666830332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"181.468603ms","start":"2026-03-02T13:54:56.572674Z","end":"2026-03-02T13:54:56.754142Z","steps":["trace[666830332] 'agreement among raft nodes before linearized reading' (duration: 181.38566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:56.754259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.218606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T13:54:56.754283Z","caller":"traceutil/trace.go:171","msg":"trace[1558914935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"286.289138ms","start":"2026-03-02T13:54:56.467987Z","end":"2026-03-02T13:54:56.754276Z","steps":["trace[1558914935] 'agreement among raft nodes before linearized reading' (duration: 286.222006ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.884747Z","caller":"traceutil/trace.go:171","msg":"trace[2140386991] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"121.575548ms","start":"2026-03-02T13:54:56.763112Z","end":"2026-03-02T13:54:56.884687Z","steps":["trace[2140386991] 'process raft request' (duration: 117.949392ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.884788Z","caller":"traceutil/trace.go:171","msg":"trace[1030059596] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"120.256366ms","start":"2026-03-02T13:54:56.764497Z","end":"2026-03-02T13:54:56.884754Z","steps":["trace[1030059596] 'process raft request' (duration: 120.134732ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.885268Z","caller":"traceutil/trace.go:171","msg":"trace[564258306] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"120.272986ms","start":"2026-03-02T13:54:56.764716Z","end":"2026-03-02T13:54:56.884989Z","steps":["trace[564258306] 'process raft request' (duration: 119.979077ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.885305Z","caller":"traceutil/trace.go:171","msg":"trace[89390861] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:532; }","duration":"117.232529ms","start":"2026-03-02T13:54:56.768048Z","end":"2026-03-02T13:54:56.88528Z","steps":["trace[89390861] 'read index received' (duration: 112.984884ms)","trace[89390861] 'applied index is now lower than readState.Index' (duration: 4.246655ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:54:56.885489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.723749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4419"} {"level":"warn","ts":"2026-03-02T13:54:56.885491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.440736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:54:56.885484Z","caller":"traceutil/trace.go:171","msg":"trace[1605523902] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"120.545005ms","start":"2026-03-02T13:54:56.764906Z","end":"2026-03-02T13:54:56.885451Z","steps":["trace[1605523902] 'process raft request' (duration: 120.230885ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.885522Z","caller":"traceutil/trace.go:171","msg":"trace[615498277] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:519; }","duration":"108.809512ms","start":"2026-03-02T13:54:56.776705Z","end":"2026-03-02T13:54:56.885514Z","steps":["trace[615498277] 'agreement among raft nodes before linearized reading' (duration: 108.73328ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.88555Z","caller":"traceutil/trace.go:171","msg":"trace[1809009420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"117.545679ms","start":"2026-03-02T13:54:56.767985Z","end":"2026-03-02T13:54:56.885531Z","steps":["trace[1809009420] 'agreement among raft nodes before linearized reading' (duration: 117.415705ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:56.885393Z","caller":"traceutil/trace.go:171","msg":"trace[972289429] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"120.431541ms","start":"2026-03-02T13:54:56.764783Z","end":"2026-03-02T13:54:56.885214Z","steps":["trace[972289429] 'process raft request' (duration: 120.140462ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:54:58.899905Z","caller":"traceutil/trace.go:171","msg":"trace[1839436118] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"214.265358ms","start":"2026-03-02T13:54:58.68562Z","end":"2026-03-02T13:54:58.899885Z","steps":["trace[1839436118] 'read index received' (duration: 214.079962ms)","trace[1839436118] 'applied index is now lower than readState.Index' (duration: 184.486µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:54:58.900156Z","caller":"traceutil/trace.go:171","msg":"trace[1214017712] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"459.352785ms","start":"2026-03-02T13:54:58.440792Z","end":"2026-03-02T13:54:58.900145Z","steps":["trace[1214017712] 'process raft request' (duration: 458.963283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:54:58.900276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:54:58.440765Z","time spent":"459.419257ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:54:58.90047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.847516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:54:58.90051Z","caller":"traceutil/trace.go:171","msg":"trace[710371721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"214.909908ms","start":"2026-03-02T13:54:58.685585Z","end":"2026-03-02T13:54:58.900494Z","steps":["trace[710371721] 'agreement among raft nodes before linearized reading' (duration: 214.860126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:03.331316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.305721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T13:55:03.331392Z","caller":"traceutil/trace.go:171","msg":"trace[1540427173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"360.400363ms","start":"2026-03-02T13:55:02.970973Z","end":"2026-03-02T13:55:03.331374Z","steps":["trace[1540427173] 'range keys from in-memory index tree' (duration: 360.219068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:03.331431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:02.970969Z","time spent":"360.453355ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T13:55:03.331692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.596488ms","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-03-02T13:55:03.331747Z","caller":"traceutil/trace.go:171","msg":"trace[2052675359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"361.661891ms","start":"2026-03-02T13:55:02.970072Z","end":"2026-03-02T13:55:03.331734Z","steps":["trace[2052675359] 'range keys from in-memory index tree' (duration: 361.513007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:03.331789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:02.970067Z","time spent":"361.708322ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T13:55:03.331828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.749744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T13:55:03.331854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.00355ms","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-03-02T13:55:03.331904Z","caller":"traceutil/trace.go:171","msg":"trace[769583897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"360.852026ms","start":"2026-03-02T13:55:02.971034Z","end":"2026-03-02T13:55:03.331886Z","steps":["trace[769583897] 'range keys from in-memory index tree' (duration: 360.621929ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:03.331913Z","caller":"traceutil/trace.go:171","msg":"trace[1152035398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"365.088313ms","start":"2026-03-02T13:55:02.96681Z","end":"2026-03-02T13:55:03.331898Z","steps":["trace[1152035398] 'range keys from in-memory index tree' (duration: 364.273239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:03.331957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:02.971032Z","time spent":"360.914918ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T13:55:03.331957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:02.96678Z","time spent":"365.165884ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T13:55:04.164578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094248180763559,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T13:55:05.652314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:04.18317Z","time spent":"1.469137351s","remote":"127.0.0.1:52452","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-02T13:55:05.652744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391015733s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T13:55:05.653034Z","caller":"traceutil/trace.go:171","msg":"trace[1104644559] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:575; }","duration":"1.989061318s","start":"2026-03-02T13:55:03.663951Z","end":"2026-03-02T13:55:05.653013Z","steps":["trace[1104644559] 'read index received' (duration: 597.53667ms)","trace[1104644559] 'applied index is now lower than readState.Index' (duration: 1.391523498s)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:05.653129Z","caller":"traceutil/trace.go:171","msg":"trace[1316888138] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"2.317286113s","start":"2026-03-02T13:55:03.335826Z","end":"2026-03-02T13:55:05.653112Z","steps":["trace[1316888138] 'process raft request' (duration: 925.745684ms)","trace[1316888138] 'compare' (duration: 1.390696794s)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:05.653135Z","caller":"traceutil/trace.go:171","msg":"trace[191029668] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.317223111s","start":"2026-03-02T13:55:03.335893Z","end":"2026-03-02T13:55:05.653116Z","steps":["trace[191029668] 'process raft request' (duration: 2.316960663s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:05.653208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:03.335799Z","time spent":"2.317364714s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:05.653234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:03.335885Z","time spent":"2.317283382s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:55:05.653315Z","caller":"traceutil/trace.go:171","msg":"trace[2035871403] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"2.317231141s","start":"2026-03-02T13:55:03.336051Z","end":"2026-03-02T13:55:05.653282Z","steps":["trace[2035871403] 'process raft request' (duration: 2.316905481s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:05.653367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989418508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:55:05.653409Z","caller":"traceutil/trace.go:171","msg":"trace[1125213000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"1.989492061s","start":"2026-03-02T13:55:03.663902Z","end":"2026-03-02T13:55:05.653394Z","steps":["trace[1125213000] 'agreement among raft nodes before linearized reading' (duration: 1.989431519s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:05.653418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:03.336033Z","time spent":"2.317328684s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:05.65345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:03.663882Z","time spent":"1.989559052s","remote":"127.0.0.1:41618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T13:55:06.174616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.788028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T13:55:06.174744Z","caller":"traceutil/trace.go:171","msg":"trace[1185418414] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"521.648627ms","start":"2026-03-02T13:55:05.653074Z","end":"2026-03-02T13:55:06.174723Z","steps":["trace[1185418414] 'read index received' (duration: 161.564591ms)","trace[1185418414] 'applied index is now lower than readState.Index' (duration: 360.082476ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:06.174863Z","caller":"traceutil/trace.go:171","msg":"trace[931192754] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"907.399478ms","start":"2026-03-02T13:55:05.267444Z","end":"2026-03-02T13:55:06.174844Z","steps":["trace[931192754] 'process raft request' (duration: 547.301311ms)","trace[931192754] 'compare' (duration: 359.658794ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:55:06.174956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:05.267419Z","time spent":"907.492491ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:06.174947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.273093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T13:55:06.175118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.99113ms","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-03-02T13:55:06.17515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.076326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-02T13:55:06.175163Z","caller":"traceutil/trace.go:171","msg":"trace[1867154857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"516.5279ms","start":"2026-03-02T13:55:05.658614Z","end":"2026-03-02T13:55:06.175142Z","steps":["trace[1867154857] 'agreement among raft nodes before linearized reading' (duration: 516.257763ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:06.175176Z","caller":"traceutil/trace.go:171","msg":"trace[1602497537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"799.103853ms","start":"2026-03-02T13:55:05.376058Z","end":"2026-03-02T13:55:06.175162Z","steps":["trace[1602497537] 'agreement among raft nodes before linearized reading' (duration: 798.876087ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:06.175196Z","caller":"traceutil/trace.go:171","msg":"trace[563646025] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:560; }","duration":"273.18226ms","start":"2026-03-02T13:55:05.901996Z","end":"2026-03-02T13:55:06.175178Z","steps":["trace[563646025] 'agreement among raft nodes before linearized reading' (duration: 272.999544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:06.175211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:05.37604Z","time spent":"799.163945ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T13:55:06.175213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:05.658602Z","time spent":"516.602122ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T13:55:07.167075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094248180763571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T13:55:07.66793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094248180763571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T13:55:08.16879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094248180763571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T13:55:08.635486Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.28967866s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T13:55:08.666442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999832896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T13:55:08.666509Z","caller":"traceutil/trace.go:171","msg":"trace[1339578312] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999956789s","start":"2026-03-02T13:55:06.666538Z","end":"2026-03-02T13:55:08.666495Z","steps":["trace[1339578312] 'agreement among raft nodes before linearized reading' (duration: 1.999865237s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:08.666545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:06.666519Z","time spent":"2.000019002s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 13:55:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T13:55:10.668073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000000524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T13:55:10.668163Z","caller":"traceutil/trace.go:171","msg":"trace[1787658247] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000145569s","start":"2026-03-02T13:55:08.667995Z","end":"2026-03-02T13:55:10.66814Z","steps":["trace[1787658247] 'agreement among raft nodes before linearized reading' (duration: 2.000036676s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:10.668251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:08.667973Z","time spent":"2.000262651s","remote":"127.0.0.1:41618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 13:55:10 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T13:55:11.17902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:06.182397Z","time spent":"4.996615768s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/03/02 13:55:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T13:55:11.230765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741231764s","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-03-02T13:55:11.230877Z","caller":"traceutil/trace.go:171","msg":"trace[855971503] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:560; }","duration":"1.741354857s","start":"2026-03-02T13:55:09.489506Z","end":"2026-03-02T13:55:11.230861Z","steps":["trace[855971503] 'range keys from in-memory index tree' (duration: 1.741216243s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.231737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.885795738s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T13:55:11.231897Z","caller":"traceutil/trace.go:171","msg":"trace[224511503] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"4.565308089s","start":"2026-03-02T13:55:06.666579Z","end":"2026-03-02T13:55:11.231888Z","steps":["trace[224511503] 'read index received' (duration: 1.969031245s)","trace[224511503] 'applied index is now lower than readState.Index' (duration: 2.596276144s)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:11.232237Z","caller":"traceutil/trace.go:171","msg":"trace[1280521939] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"4.983775245s","start":"2026-03-02T13:55:06.248454Z","end":"2026-03-02T13:55:11.232229Z","steps":["trace[1280521939] 'process raft request' (duration: 4.983355814s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.232474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:06.248428Z","time spent":"4.984012191s","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:11.346519Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.085082351s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T13:55:11.708289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.808346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-02T13:55:11.70887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034165535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:55:11.708975Z","caller":"traceutil/trace.go:171","msg":"trace[1463668424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.034309858s","start":"2026-03-02T13:55:10.674639Z","end":"2026-03-02T13:55:11.708949Z","steps":["trace[1463668424] 'agreement among raft nodes before linearized reading' (duration: 1.034112073s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.70903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:10.674629Z","time spent":"1.034387751s","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T13:55:11.708926Z","caller":"traceutil/trace.go:171","msg":"trace[1392461804] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"476.979265ms","start":"2026-03-02T13:55:11.231915Z","end":"2026-03-02T13:55:11.708894Z","steps":["trace[1392461804] 'read index received' (duration: 115.323136ms)","trace[1392461804] 'applied index is now lower than readState.Index' (duration: 361.479564ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:11.709332Z","caller":"traceutil/trace.go:171","msg":"trace[754189956] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"2.447979784s","start":"2026-03-02T13:55:09.261331Z","end":"2026-03-02T13:55:11.709311Z","steps":["trace[754189956] 'process raft request' (duration: 2.085896783s)","trace[754189956] 'compare' (duration: 360.620551ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:55:11.709417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.05207879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T13:55:11.709479Z","caller":"traceutil/trace.go:171","msg":"trace[1479433014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"4.052201354s","start":"2026-03-02T13:55:07.657263Z","end":"2026-03-02T13:55:11.709464Z","steps":["trace[1479433014] 'agreement among raft nodes before linearized reading' (duration: 4.052020659s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.709487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:09.261307Z","time spent":"2.448072956s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:11.709533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:07.657241Z","time spent":"4.052276205s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T13:55:11.709628Z","caller":"traceutil/trace.go:171","msg":"trace[2080450120] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.037993789s","start":"2026-03-02T13:55:10.671595Z","end":"2026-03-02T13:55:11.709589Z","steps":["trace[2080450120] 'process raft request' (duration: 1.037023103s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.709719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.05206198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T13:55:11.709682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.052493701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T13:55:11.709848Z","caller":"traceutil/trace.go:171","msg":"trace[642457787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"4.052690667s","start":"2026-03-02T13:55:07.657141Z","end":"2026-03-02T13:55:11.709832Z","steps":["trace[642457787] 'agreement among raft nodes before linearized reading' (duration: 4.052171233s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.709934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.965029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-02T13:55:11.70978Z","caller":"traceutil/trace.go:171","msg":"trace[93361222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"4.052121571s","start":"2026-03-02T13:55:07.657644Z","end":"2026-03-02T13:55:11.709766Z","steps":["trace[93361222] 'agreement among raft nodes before linearized reading' (duration: 4.051988308s)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:11.709996Z","caller":"traceutil/trace.go:171","msg":"trace[196328512] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:564; }","duration":"475.055252ms","start":"2026-03-02T13:55:11.234926Z","end":"2026-03-02T13:55:11.709981Z","steps":["trace[196328512] 'agreement among raft nodes before linearized reading' (duration: 474.910228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:11.710052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:07.657609Z","time spent":"4.052425019s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T13:55:11.710065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:11.234915Z","time spent":"475.141914ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-02T13:55:11.709787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:10.671564Z","time spent":"1.038153004s","remote":"127.0.0.1:41684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T13:55:11.70996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:07.657125Z","time spent":"4.05279523s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T13:55:12.075684Z","caller":"traceutil/trace.go:171","msg":"trace[2136591702] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"360.479208ms","start":"2026-03-02T13:55:11.715165Z","end":"2026-03-02T13:55:12.075644Z","steps":["trace[2136591702] 'process raft request' (duration: 322.964971ms)","trace[2136591702] 'compare' (duration: 37.224279ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:12.075735Z","caller":"traceutil/trace.go:171","msg":"trace[244946598] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"357.420484ms","start":"2026-03-02T13:55:11.718285Z","end":"2026-03-02T13:55:12.075706Z","steps":["trace[244946598] 'read index received' (duration: 319.858437ms)","trace[244946598] 'applied index is now lower than readState.Index' (duration: 37.560667ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:12.075763Z","caller":"traceutil/trace.go:171","msg":"trace[491458358] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"360.192489ms","start":"2026-03-02T13:55:11.715545Z","end":"2026-03-02T13:55:12.075738Z","steps":["trace[491458358] 'process raft request' (duration: 359.973693ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:12.075814Z","caller":"traceutil/trace.go:171","msg":"trace[228345988] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"359.802009ms","start":"2026-03-02T13:55:11.716006Z","end":"2026-03-02T13:55:12.075808Z","steps":["trace[228345988] 'process raft request' (duration: 359.641565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:12.075879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:11.715153Z","time spent":"360.616741ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:12.075909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:11.715999Z","time spent":"359.84219ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:12.075943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:11.715537Z","time spent":"360.294793ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:55:12.07598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.680001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:55:12.076264Z","caller":"traceutil/trace.go:171","msg":"trace[792956999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"357.942179ms","start":"2026-03-02T13:55:11.71827Z","end":"2026-03-02T13:55:12.076212Z","steps":["trace[792956999] 'agreement among raft nodes before linearized reading' (duration: 357.61499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:12.076297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:11.718264Z","time spent":"358.025961ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T13:55:13.344212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.804397ms","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-03-02T13:55:13.344274Z","caller":"traceutil/trace.go:171","msg":"trace[997243350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"155.912429ms","start":"2026-03-02T13:55:13.188347Z","end":"2026-03-02T13:55:13.344259Z","steps":["trace[997243350] 'range keys from in-memory index tree' (duration: 155.625201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:14.407515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.348798ms","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-03-02T13:55:14.407589Z","caller":"traceutil/trace.go:171","msg":"trace[1125525929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"312.473152ms","start":"2026-03-02T13:55:14.095097Z","end":"2026-03-02T13:55:14.407571Z","steps":["trace[1125525929] 'range keys from in-memory index tree' (duration: 312.089812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:14.407625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:14.095079Z","time spent":"312.538724ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T13:55:14.407646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.424331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T13:55:14.407717Z","caller":"traceutil/trace.go:171","msg":"trace[321935229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"312.524473ms","start":"2026-03-02T13:55:14.095173Z","end":"2026-03-02T13:55:14.407698Z","steps":["trace[321935229] 'range keys from in-memory index tree' (duration: 312.215085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:14.407758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:14.095161Z","time spent":"312.588244ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T13:55:14.407843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.705108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T13:55:14.407911Z","caller":"traceutil/trace.go:171","msg":"trace[622989579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"312.811911ms","start":"2026-03-02T13:55:14.095081Z","end":"2026-03-02T13:55:14.407893Z","steps":["trace[622989579] 'range keys from in-memory index tree' (duration: 312.502312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:14.407951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:14.095062Z","time spent":"312.879613ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T13:55:15.464409Z","caller":"traceutil/trace.go:171","msg":"trace[723622721] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"213.494264ms","start":"2026-03-02T13:55:15.250897Z","end":"2026-03-02T13:55:15.464391Z","steps":["trace[723622721] 'process raft request' (duration: 213.389541ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:15.465248Z","caller":"traceutil/trace.go:171","msg":"trace[194832335] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:625; }","duration":"110.474464ms","start":"2026-03-02T13:55:15.354766Z","end":"2026-03-02T13:55:15.46524Z","steps":["trace[194832335] 'read index received' (duration: 110.470744ms)","trace[194832335] 'applied index is now lower than readState.Index' (duration: 2.83µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:55:15.46539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.616998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-02T13:55:15.465412Z","caller":"traceutil/trace.go:171","msg":"trace[1273165713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"110.69953ms","start":"2026-03-02T13:55:15.354706Z","end":"2026-03-02T13:55:15.465406Z","steps":["trace[1273165713] 'agreement among raft nodes before linearized reading' (duration: 110.562967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:17.16372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.521587ms","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-03-02T13:55:17.16388Z","caller":"traceutil/trace.go:171","msg":"trace[1336526190] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:636; }","duration":"119.725213ms","start":"2026-03-02T13:55:17.044136Z","end":"2026-03-02T13:55:17.163861Z","steps":["trace[1336526190] 'range keys from in-memory index tree' (duration: 119.368494ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:21.428179Z","caller":"traceutil/trace.go:171","msg":"trace[800644109] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"155.241166ms","start":"2026-03-02T13:55:21.27291Z","end":"2026-03-02T13:55:21.428151Z","steps":["trace[800644109] 'read index received' (duration: 155.054412ms)","trace[800644109] 'applied index is now lower than readState.Index' (duration: 185.384µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:21.428208Z","caller":"traceutil/trace.go:171","msg":"trace[256766157] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"159.46896ms","start":"2026-03-02T13:55:21.268704Z","end":"2026-03-02T13:55:21.428173Z","steps":["trace[256766157] 'process raft request' (duration: 159.293176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:21.428372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.163134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T13:55:21.42836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.419011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:55:21.428407Z","caller":"traceutil/trace.go:171","msg":"trace[1681955315] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:678; }","duration":"155.281087ms","start":"2026-03-02T13:55:21.273117Z","end":"2026-03-02T13:55:21.428398Z","steps":["trace[1681955315] 'agreement among raft nodes before linearized reading' (duration: 155.192584ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:21.428434Z","caller":"traceutil/trace.go:171","msg":"trace[1869704791] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:678; }","duration":"155.551034ms","start":"2026-03-02T13:55:21.272867Z","end":"2026-03-02T13:55:21.428418Z","steps":["trace[1869704791] 'agreement among raft nodes before linearized reading' (duration: 155.37853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:21.428673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.265917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:55:21.428721Z","caller":"traceutil/trace.go:171","msg":"trace[1518748517] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:678; }","duration":"155.343068ms","start":"2026-03-02T13:55:21.273365Z","end":"2026-03-02T13:55:21.428708Z","steps":["trace[1518748517] 'agreement among raft nodes before linearized reading' (duration: 155.237646ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:48.898144Z","caller":"traceutil/trace.go:171","msg":"trace[1332566942] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"184.497807ms","start":"2026-03-02T13:55:48.713623Z","end":"2026-03-02T13:55:48.898121Z","steps":["trace[1332566942] 'process raft request' (duration: 184.349844ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:48.899122Z","caller":"traceutil/trace.go:171","msg":"trace[890064598] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:852; }","duration":"185.423485ms","start":"2026-03-02T13:55:48.713678Z","end":"2026-03-02T13:55:48.899101Z","steps":["trace[890064598] 'read index received' (duration: 185.412225ms)","trace[890064598] 'applied index is now lower than readState.Index' (duration: 9.59µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:55:48.899333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.629249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T13:55:48.899391Z","caller":"traceutil/trace.go:171","msg":"trace[1255960830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"185.74713ms","start":"2026-03-02T13:55:48.713631Z","end":"2026-03-02T13:55:48.899378Z","steps":["trace[1255960830] 'agreement among raft nodes before linearized reading' (duration: 185.545526ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:48.926885Z","caller":"traceutil/trace.go:171","msg":"trace[1759724803] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"210.187891ms","start":"2026-03-02T13:55:48.716682Z","end":"2026-03-02T13:55:48.92687Z","steps":["trace[1759724803] 'process raft request' (duration: 210.149841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:48.927028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.669671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T13:55:48.927084Z","caller":"traceutil/trace.go:171","msg":"trace[936811024] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:822; }","duration":"114.757032ms","start":"2026-03-02T13:55:48.812314Z","end":"2026-03-02T13:55:48.927071Z","steps":["trace[936811024] 'agreement among raft nodes before linearized reading' (duration: 114.674101ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:49.244258Z","caller":"traceutil/trace.go:171","msg":"trace[1341151533] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"258.494826ms","start":"2026-03-02T13:55:48.985741Z","end":"2026-03-02T13:55:49.244236Z","steps":["trace[1341151533] 'read index received' (duration: 258.359213ms)","trace[1341151533] 'applied index is now lower than readState.Index' (duration: 134.823µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:55:49.24442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.656829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:55:49.244461Z","caller":"traceutil/trace.go:171","msg":"trace[776444002] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:823; }","duration":"258.746471ms","start":"2026-03-02T13:55:48.985705Z","end":"2026-03-02T13:55:49.244451Z","steps":["trace[776444002] 'agreement among raft nodes before linearized reading' (duration: 258.640559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:49.24454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.218664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T13:55:49.24462Z","caller":"traceutil/trace.go:171","msg":"trace[1313653660] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:823; }","duration":"113.302446ms","start":"2026-03-02T13:55:49.131298Z","end":"2026-03-02T13:55:49.244601Z","steps":["trace[1313653660] 'agreement among raft nodes before linearized reading' (duration: 113.125473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:49.244605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.811327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-02T13:55:49.244729Z","caller":"traceutil/trace.go:171","msg":"trace[1964979087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"237.990301ms","start":"2026-03-02T13:55:49.006714Z","end":"2026-03-02T13:55:49.244704Z","steps":["trace[1964979087] 'agreement among raft nodes before linearized reading' (duration: 237.673165ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:49.24438Z","caller":"traceutil/trace.go:171","msg":"trace[1431886790] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"342.23433ms","start":"2026-03-02T13:55:48.902116Z","end":"2026-03-02T13:55:49.24435Z","steps":["trace[1431886790] 'process raft request' (duration: 341.991556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:55:49.245048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:55:48.902103Z","time spent":"342.796301ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:55:51.56365Z","caller":"traceutil/trace.go:171","msg":"trace[1685674529] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"227.326938ms","start":"2026-03-02T13:55:51.336305Z","end":"2026-03-02T13:55:51.563632Z","steps":["trace[1685674529] 'process raft request' (duration: 227.128985ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:53.770472Z","caller":"traceutil/trace.go:171","msg":"trace[1221074347] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"199.489846ms","start":"2026-03-02T13:55:53.570948Z","end":"2026-03-02T13:55:53.770438Z","steps":["trace[1221074347] 'process raft request' (duration: 199.345863ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:55.187677Z","caller":"traceutil/trace.go:171","msg":"trace[1499686028] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"147.603704ms","start":"2026-03-02T13:55:55.040047Z","end":"2026-03-02T13:55:55.187651Z","steps":["trace[1499686028] 'process raft request' (duration: 49.578335ms)","trace[1499686028] 'compare' (duration: 97.917697ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:55:55.466746Z","caller":"traceutil/trace.go:171","msg":"trace[733694198] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"137.418187ms","start":"2026-03-02T13:55:55.329306Z","end":"2026-03-02T13:55:55.466724Z","steps":["trace[733694198] 'process raft request' (duration: 137.296885ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:55:56.015979Z","caller":"traceutil/trace.go:171","msg":"trace[128275145] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"105.354241ms","start":"2026-03-02T13:55:55.910601Z","end":"2026-03-02T13:55:56.015956Z","steps":["trace[128275145] 'process raft request' (duration: 105.212378ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:00.186204Z","caller":"traceutil/trace.go:171","msg":"trace[618156024] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"100.66554ms","start":"2026-03-02T13:56:00.085504Z","end":"2026-03-02T13:56:00.186169Z","steps":["trace[618156024] 'process raft request' (duration: 100.444226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:12.577484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.117312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T13:56:12.577555Z","caller":"traceutil/trace.go:171","msg":"trace[902558084] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:886; }","duration":"112.226715ms","start":"2026-03-02T13:56:12.465301Z","end":"2026-03-02T13:56:12.577528Z","steps":["trace[902558084] 'count revisions from in-memory index tree' (duration: 112.046141ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:17.667014Z","caller":"traceutil/trace.go:171","msg":"trace[2078773148] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"166.556267ms","start":"2026-03-02T13:56:17.500437Z","end":"2026-03-02T13:56:17.666993Z","steps":["trace[2078773148] 'process raft request' (duration: 166.321694ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:18.755444Z","caller":"traceutil/trace.go:171","msg":"trace[1527585692] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"258.198546ms","start":"2026-03-02T13:56:18.497227Z","end":"2026-03-02T13:56:18.755425Z","steps":["trace[1527585692] 'read index received' (duration: 258.016113ms)","trace[1527585692] 'applied index is now lower than readState.Index' (duration: 181.763µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:56:18.755529Z","caller":"traceutil/trace.go:171","msg":"trace[111482314] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"310.55988ms","start":"2026-03-02T13:56:18.444933Z","end":"2026-03-02T13:56:18.755493Z","steps":["trace[111482314] 'process raft request' (duration: 310.371247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:18.755695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:56:18.444916Z","time spent":"310.699653ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T13:56:18.755832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.592072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-02T13:56:18.75587Z","caller":"traceutil/trace.go:171","msg":"trace[1168845380] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:904; }","duration":"258.683204ms","start":"2026-03-02T13:56:18.497176Z","end":"2026-03-02T13:56:18.75586Z","steps":["trace[1168845380] 'agreement among raft nodes before linearized reading' (duration: 258.324448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:18.755977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.917579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:56:18.756062Z","caller":"traceutil/trace.go:171","msg":"trace[261494418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"128.055412ms","start":"2026-03-02T13:56:18.627986Z","end":"2026-03-02T13:56:18.756041Z","steps":["trace[261494418] 'agreement among raft nodes before linearized reading' (duration: 127.904889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:19.07604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.41284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-02T13:56:19.076172Z","caller":"traceutil/trace.go:171","msg":"trace[725925411] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:904; }","duration":"209.605483ms","start":"2026-03-02T13:56:18.866537Z","end":"2026-03-02T13:56:19.076143Z","steps":["trace[725925411] 'range keys from in-memory index tree' (duration: 208.875142ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:19.891968Z","caller":"traceutil/trace.go:171","msg":"trace[340057641] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"197.36656ms","start":"2026-03-02T13:56:19.694578Z","end":"2026-03-02T13:56:19.891944Z","steps":["trace[340057641] 'process raft request' (duration: 197.218928ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:19.975162Z","caller":"traceutil/trace.go:171","msg":"trace[72424541] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"276.629597ms","start":"2026-03-02T13:56:19.698497Z","end":"2026-03-02T13:56:19.975127Z","steps":["trace[72424541] 'process raft request' (duration: 251.540129ms)","trace[72424541] 'compare' (duration: 24.951555ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T13:56:25.863637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.571286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:56:25.863699Z","caller":"traceutil/trace.go:171","msg":"trace[181174700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"199.685847ms","start":"2026-03-02T13:56:25.663999Z","end":"2026-03-02T13:56:25.863685Z","steps":["trace[181174700] 'range keys from in-memory index tree' (duration: 199.477044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:26.132784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.208058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"warn","ts":"2026-03-02T13:56:26.132842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.815587ms","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-03-02T13:56:26.132886Z","caller":"traceutil/trace.go:171","msg":"trace[265122124] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:922; }","duration":"150.363291ms","start":"2026-03-02T13:56:25.982503Z","end":"2026-03-02T13:56:26.132866Z","steps":["trace[265122124] 'range keys from in-memory index tree' (duration: 150.019186ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:26.132965Z","caller":"traceutil/trace.go:171","msg":"trace[929095587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"152.9938ms","start":"2026-03-02T13:56:25.979948Z","end":"2026-03-02T13:56:26.132941Z","steps":["trace[929095587] 'range keys from in-memory index tree' (duration: 152.628564ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:26.51144Z","caller":"traceutil/trace.go:171","msg":"trace[87216277] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"374.086646ms","start":"2026-03-02T13:56:26.137335Z","end":"2026-03-02T13:56:26.511421Z","steps":["trace[87216277] 'process raft request' (duration: 373.958324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:26.51157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:56:26.137313Z","time spent":"374.191887ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:56:26.551047Z","caller":"traceutil/trace.go:171","msg":"trace[1443673790] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"413.188695ms","start":"2026-03-02T13:56:26.137843Z","end":"2026-03-02T13:56:26.551032Z","steps":["trace[1443673790] 'process raft request' (duration: 413.159365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:26.551162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:56:26.137816Z","time spent":"413.279657ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:56:26.55136Z","caller":"traceutil/trace.go:171","msg":"trace[2088074588] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"413.689092ms","start":"2026-03-02T13:56:26.137641Z","end":"2026-03-02T13:56:26.55133Z","steps":["trace[2088074588] 'process raft request' (duration: 411.900566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:26.551575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:56:26.13762Z","time spent":"413.802535ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:56:30.562317Z","caller":"traceutil/trace.go:171","msg":"trace[2060182540] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"141.268717ms","start":"2026-03-02T13:56:30.421017Z","end":"2026-03-02T13:56:30.562286Z","steps":["trace[2060182540] 'process raft request' (duration: 140.808061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:34.766437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.572895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T13:56:34.766475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.213965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:56:34.766526Z","caller":"traceutil/trace.go:171","msg":"trace[1270645935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"101.306577ms","start":"2026-03-02T13:56:34.665207Z","end":"2026-03-02T13:56:34.766514Z","steps":["trace[1270645935] 'range keys from in-memory index tree' (duration: 101.138144ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:34.766521Z","caller":"traceutil/trace.go:171","msg":"trace[2102090529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"133.692386ms","start":"2026-03-02T13:56:34.632811Z","end":"2026-03-02T13:56:34.766503Z","steps":["trace[2102090529] 'range keys from in-memory index tree' (duration: 133.418492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:34.766813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.528908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:56:34.766873Z","caller":"traceutil/trace.go:171","msg":"trace[594947976] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:961; }","duration":"146.63654ms","start":"2026-03-02T13:56:34.620222Z","end":"2026-03-02T13:56:34.766858Z","steps":["trace[594947976] 'count revisions from in-memory index tree' (duration: 146.420637ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:34.872802Z","caller":"traceutil/trace.go:171","msg":"trace[1679283198] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"101.610491ms","start":"2026-03-02T13:56:34.771165Z","end":"2026-03-02T13:56:34.872776Z","steps":["trace[1679283198] 'process raft request' (duration: 100.057509ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:34.872958Z","caller":"traceutil/trace.go:171","msg":"trace[894794342] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"101.737372ms","start":"2026-03-02T13:56:34.771206Z","end":"2026-03-02T13:56:34.872943Z","steps":["trace[894794342] 'process raft request' (duration: 101.525319ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:46.842878Z","caller":"traceutil/trace.go:171","msg":"trace[408453160] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"107.382305ms","start":"2026-03-02T13:56:46.735473Z","end":"2026-03-02T13:56:46.842856Z","steps":["trace[408453160] 'read index received' (duration: 107.151651ms)","trace[408453160] 'applied index is now lower than readState.Index' (duration: 229.714µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:56:46.842979Z","caller":"traceutil/trace.go:171","msg":"trace[1345462467] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"113.121721ms","start":"2026-03-02T13:56:46.729824Z","end":"2026-03-02T13:56:46.842946Z","steps":["trace[1345462467] 'process raft request' (duration: 112.875797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:46.843031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.539497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:56:46.843068Z","caller":"traceutil/trace.go:171","msg":"trace[1139250695] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1016; }","duration":"107.602987ms","start":"2026-03-02T13:56:46.735454Z","end":"2026-03-02T13:56:46.843056Z","steps":["trace[1139250695] 'agreement among raft nodes before linearized reading' (duration: 107.529276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:47.473644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.012758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-02T13:56:47.473724Z","caller":"traceutil/trace.go:171","msg":"trace[93511258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"318.15109ms","start":"2026-03-02T13:56:47.155555Z","end":"2026-03-02T13:56:47.473706Z","steps":["trace[93511258] 'range keys from in-memory index tree' (duration: 317.765654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:47.473759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:56:47.155536Z","time spent":"318.215301ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T13:56:48.86242Z","caller":"traceutil/trace.go:171","msg":"trace[925396289] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"169.583754ms","start":"2026-03-02T13:56:48.692806Z","end":"2026-03-02T13:56:48.86239Z","steps":["trace[925396289] 'read index received' (duration: 169.354501ms)","trace[925396289] 'applied index is now lower than readState.Index' (duration: 227.723µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T13:56:48.862573Z","caller":"traceutil/trace.go:171","msg":"trace[281724897] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"417.191641ms","start":"2026-03-02T13:56:48.445309Z","end":"2026-03-02T13:56:48.862501Z","steps":["trace[281724897] 'process raft request' (duration: 416.864047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:48.862702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.868868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T13:56:48.862726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.820688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T13:56:48.862794Z","caller":"traceutil/trace.go:171","msg":"trace[1947142432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"169.991399ms","start":"2026-03-02T13:56:48.692772Z","end":"2026-03-02T13:56:48.862764Z","steps":["trace[1947142432] 'agreement among raft nodes before linearized reading' (duration: 169.777526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T13:56:48.862821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T13:56:48.445286Z","time spent":"417.391263ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T13:56:48.862878Z","caller":"traceutil/trace.go:171","msg":"trace[532594] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1023; }","duration":"164.019721ms","start":"2026-03-02T13:56:48.698798Z","end":"2026-03-02T13:56:48.862818Z","steps":["trace[532594] 'agreement among raft nodes before linearized reading' (duration: 163.818338ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T13:56:49.062069Z","caller":"traceutil/trace.go:171","msg":"trace[1202130663] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"173.089276ms","start":"2026-03-02T13:56:48.888954Z","end":"2026-03-02T13:56:49.062044Z","steps":["trace[1202130663] 'process raft request' (duration: 172.907584ms)"],"step_count":1}