{"level":"warn","ts":"2026-02-12T08:58:07.705545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T08:58:07.705791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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://162.253.55.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-02-12T08:58:07.705913Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T08:58:07.707069Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-12T08:58:07.707151Z","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-02-12T08:58:07.707863Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-12T08:58:07.70925Z","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":2,"max-cpu-available":2,"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://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-02-12T08:58:07.713925Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.376626ms"} {"level":"info","ts":"2026-02-12T08:58:07.744526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-12T08:58:07.744822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T08:58:07.744913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-12T08:58:07.744996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T08:58:07.74508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-12T08:58:07.745195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-12T08:58:07.753515Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T08:58:07.760472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T08:58:07.7634Z","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-02-12T08:58:07.769088Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T08:58:07.769676Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T08:58:07.76993Z","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-02-12T08:58:07.770028Z","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-02-12T08:58:07.77023Z","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-02-12T08:58:07.781502Z","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-02-12T08:58:07.782375Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-12T08:58:07.78255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-12T08:58:07.78256Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T08:58:07.782603Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T08:58:07.807389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-12T08:58:07.807463Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-12T08:58:08.145593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T08:58:08.145756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T08:58:08.145842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-12T08:58:08.145885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-12T08:58:08.14594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-12T08:58:08.145993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-12T08:58:08.146164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-12T08:58:08.151493Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T08:58:08.151744Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T08:58:08.152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T08:58:08.152223Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T08:58:08.153361Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T08:58:08.15338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T08:58:08.155067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-02-12T08:58:08.158466Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T08:58:08.159811Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T08:58:08.159862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T08:58:08.166803Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T08:58:26.083689Z","caller":"traceutil/trace.go:171","msg":"trace[1236621090] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"438.809037ms","start":"2026-02-12T08:58:25.644856Z","end":"2026-02-12T08:58:26.083665Z","steps":["trace[1236621090] 'process raft request' (duration: 438.651493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:26.105043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:25.644838Z","time spent":"459.871742ms","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:58:27.104885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.09441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-12T08:58:27.104953Z","caller":"traceutil/trace.go:171","msg":"trace[44562936] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:442; }","duration":"995.193873ms","start":"2026-02-12T08:58:26.109748Z","end":"2026-02-12T08:58:27.104942Z","steps":["trace[44562936] 'range keys from in-memory index tree' (duration: 995.033578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:27.104977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:26.109736Z","time spent":"995.236563ms","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-12T08:58:27.105431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.358212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T08:58:27.105462Z","caller":"traceutil/trace.go:171","msg":"trace[1370217298] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"679.812123ms","start":"2026-02-12T08:58:26.425643Z","end":"2026-02-12T08:58:27.105455Z","steps":["trace[1370217298] 'read index received' (duration: 27.191µs)","trace[1370217298] 'applied index is now lower than readState.Index' (duration: 679.784392ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:58:27.105579Z","caller":"traceutil/trace.go:171","msg":"trace[1906155974] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"995.089479ms","start":"2026-02-12T08:58:26.110482Z","end":"2026-02-12T08:58:27.105572Z","steps":["trace[1906155974] 'process raft request' (duration: 310.480812ms)","trace[1906155974] 'compare' (duration: 684.28202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:58:27.105606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:26.110457Z","time spent":"995.133751ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:58:27.105677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.032847ms","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-02-12T08:58:27.105689Z","caller":"traceutil/trace.go:171","msg":"trace[1133058682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:443; }","duration":"680.068529ms","start":"2026-02-12T08:58:26.425616Z","end":"2026-02-12T08:58:27.105685Z","steps":["trace[1133058682] 'agreement among raft nodes before linearized reading' (duration: 680.042278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:27.105702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:26.425602Z","time spent":"680.096979ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T08:58:27.105762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.650969ms","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-02-12T08:58:27.105773Z","caller":"traceutil/trace.go:171","msg":"trace[2070179705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:443; }","duration":"679.69547ms","start":"2026-02-12T08:58:26.426074Z","end":"2026-02-12T08:58:27.10577Z","steps":["trace[2070179705] 'agreement among raft nodes before linearized reading' (duration: 679.67434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:27.105784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:26.426053Z","time spent":"679.728961ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T08:58:27.105859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.193931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:58:27.105871Z","caller":"traceutil/trace.go:171","msg":"trace[2051597158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:443; }","duration":"413.205461ms","start":"2026-02-12T08:58:26.692661Z","end":"2026-02-12T08:58:27.105867Z","steps":["trace[2051597158] 'agreement among raft nodes before linearized reading' (duration: 413.185951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:27.105882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:26.692626Z","time spent":"413.252902ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T08:58:27.105941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.135944ms","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-02-12T08:58:27.105952Z","caller":"traceutil/trace.go:171","msg":"trace[756185842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:443; }","duration":"499.173115ms","start":"2026-02-12T08:58:26.606775Z","end":"2026-02-12T08:58:27.105948Z","steps":["trace[756185842] 'agreement among raft nodes before linearized reading' (duration: 499.152435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:27.105963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:26.60676Z","time spent":"499.201025ms","remote":"127.0.0.1:47078","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-02-12T08:58:27.251389Z","caller":"traceutil/trace.go:171","msg":"trace[322530458] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:455; }","duration":"129.504768ms","start":"2026-02-12T08:58:27.121869Z","end":"2026-02-12T08:58:27.251374Z","steps":["trace[322530458] 'read index received' (duration: 3.957545ms)","trace[322530458] 'applied index is now lower than readState.Index' (duration: 125.546693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:58:27.251463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.57768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:58:27.251479Z","caller":"traceutil/trace.go:171","msg":"trace[1113530081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"129.625851ms","start":"2026-02-12T08:58:27.121847Z","end":"2026-02-12T08:58:27.251473Z","steps":["trace[1113530081] 'agreement among raft nodes before linearized reading' (duration: 129.583871ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:27.251569Z","caller":"traceutil/trace.go:171","msg":"trace[132562005] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"134.441386ms","start":"2026-02-12T08:58:27.117105Z","end":"2026-02-12T08:58:27.251546Z","steps":["trace[132562005] 'process raft request' (duration: 134.17992ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:27.2517Z","caller":"traceutil/trace.go:171","msg":"trace[543618151] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"127.977756ms","start":"2026-02-12T08:58:27.123716Z","end":"2026-02-12T08:58:27.251694Z","steps":["trace[543618151] 'process raft request' (duration: 127.589148ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:27.251714Z","caller":"traceutil/trace.go:171","msg":"trace[595186659] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"137.516342ms","start":"2026-02-12T08:58:27.114191Z","end":"2026-02-12T08:58:27.251708Z","steps":["trace[595186659] 'process raft request' (duration: 137.014272ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:38.992381Z","caller":"traceutil/trace.go:171","msg":"trace[1600239307] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"249.211714ms","start":"2026-02-12T08:58:38.743153Z","end":"2026-02-12T08:58:38.992365Z","steps":["trace[1600239307] 'process raft request' (duration: 249.107112ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:41.281931Z","caller":"traceutil/trace.go:171","msg":"trace[410173422] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"106.805206ms","start":"2026-02-12T08:58:41.175109Z","end":"2026-02-12T08:58:41.281914Z","steps":["trace[410173422] 'read index received' (duration: 106.727014ms)","trace[410173422] 'applied index is now lower than readState.Index' (duration: 77.512µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:58:41.282005Z","caller":"traceutil/trace.go:171","msg":"trace[1637195218] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"257.706459ms","start":"2026-02-12T08:58:41.024294Z","end":"2026-02-12T08:58:41.282001Z","steps":["trace[1637195218] 'process raft request' (duration: 257.536965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:41.282152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.01192ms","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-02-12T08:58:41.282169Z","caller":"traceutil/trace.go:171","msg":"trace[1956078974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"107.080602ms","start":"2026-02-12T08:58:41.175083Z","end":"2026-02-12T08:58:41.282163Z","steps":["trace[1956078974] 'agreement among raft nodes before linearized reading' (duration: 106.98574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:41.282238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.414743ms","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-02-12T08:58:41.282252Z","caller":"traceutil/trace.go:171","msg":"trace[913577968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"103.435733ms","start":"2026-02-12T08:58:41.17881Z","end":"2026-02-12T08:58:41.282246Z","steps":["trace[913577968] 'agreement among raft nodes before linearized reading' (duration: 103.394272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:41.695009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.46023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:58:41.695067Z","caller":"traceutil/trace.go:171","msg":"trace[1407454638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"136.554202ms","start":"2026-02-12T08:58:41.558498Z","end":"2026-02-12T08:58:41.695052Z","steps":["trace[1407454638] 'range keys from in-memory index tree' (duration: 136.421439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:47.06012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058030960608624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T08:58:47.560261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058030960608624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T08:58:47.59092Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070260521s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T08:58:47.591366Z","caller":"traceutil/trace.go:171","msg":"trace[550095803] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.070766702s","start":"2026-02-12T08:58:46.520558Z","end":"2026-02-12T08:58:47.591325Z","steps":["trace[550095803] 'process raft request' (duration: 1.070588238s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:47.591477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:46.520539Z","time spent":"1.070867344s","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:58:47.59703Z","caller":"traceutil/trace.go:171","msg":"trace[1659470964] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:544; }","duration":"1.037893299s","start":"2026-02-12T08:58:46.559121Z","end":"2026-02-12T08:58:47.597015Z","steps":["trace[1659470964] 'read index received' (duration: 1.032093852s)","trace[1659470964] 'applied index is now lower than readState.Index' (duration: 5.798727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:58:47.597205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038068192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:58:47.59723Z","caller":"traceutil/trace.go:171","msg":"trace[493609796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"1.038126704s","start":"2026-02-12T08:58:46.559094Z","end":"2026-02-12T08:58:47.597221Z","steps":["trace[493609796] 'agreement among raft nodes before linearized reading' (duration: 1.038072423s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:47.597252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:58:46.559067Z","time spent":"1.038179965s","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T08:58:47.597412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.678479ms","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-02-12T08:58:47.597431Z","caller":"traceutil/trace.go:171","msg":"trace[1223136782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"275.739381ms","start":"2026-02-12T08:58:47.321685Z","end":"2026-02-12T08:58:47.597425Z","steps":["trace[1223136782] 'agreement among raft nodes before linearized reading' (duration: 275.69754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:47.597585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.70347ms","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-02-12T08:58:47.597603Z","caller":"traceutil/trace.go:171","msg":"trace[1934284056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"234.739981ms","start":"2026-02-12T08:58:47.362857Z","end":"2026-02-12T08:58:47.597597Z","steps":["trace[1934284056] 'agreement among raft nodes before linearized reading' (duration: 234.67097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:47.597683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.985838ms","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-02-12T08:58:47.5977Z","caller":"traceutil/trace.go:171","msg":"trace[1912680387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"236.027688ms","start":"2026-02-12T08:58:47.361666Z","end":"2026-02-12T08:58:47.597694Z","steps":["trace[1912680387] 'agreement among raft nodes before linearized reading' (duration: 235.998298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:47.597778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.008906ms","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-02-12T08:58:47.597798Z","caller":"traceutil/trace.go:171","msg":"trace[682415173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"276.042686ms","start":"2026-02-12T08:58:47.321749Z","end":"2026-02-12T08:58:47.597792Z","steps":["trace[682415173] 'agreement among raft nodes before linearized reading' (duration: 276.010946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.607759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.048005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:58:49.60782Z","caller":"traceutil/trace.go:171","msg":"trace[410423037] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:548; }","duration":"115.144737ms","start":"2026-02-12T08:58:49.492661Z","end":"2026-02-12T08:58:49.607806Z","steps":["trace[410423037] 'range keys from in-memory index tree' (duration: 114.934712ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:49.608273Z","caller":"traceutil/trace.go:171","msg":"trace[911383014] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"115.305301ms","start":"2026-02-12T08:58:49.492959Z","end":"2026-02-12T08:58:49.608264Z","steps":["trace[911383014] 'process raft request' (duration: 47.00648ms)","trace[911383014] 'compare' (duration: 68.066676ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:58:49.749954Z","caller":"traceutil/trace.go:171","msg":"trace[1492557340] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"246.402893ms","start":"2026-02-12T08:58:49.503533Z","end":"2026-02-12T08:58:49.749936Z","steps":["trace[1492557340] 'process raft request' (duration: 245.957024ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:49.750224Z","caller":"traceutil/trace.go:171","msg":"trace[207134859] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"217.22861ms","start":"2026-02-12T08:58:49.532988Z","end":"2026-02-12T08:58:49.750216Z","steps":["trace[207134859] 'process raft request' (duration: 216.648278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.753225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.786413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-hz4ng\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-12T08:58:49.753262Z","caller":"traceutil/trace.go:171","msg":"trace[46624004] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-hz4ng; range_end:; response_count:1; response_revision:551; }","duration":"229.855735ms","start":"2026-02-12T08:58:49.523398Z","end":"2026-02-12T08:58:49.753254Z","steps":["trace[46624004] 'agreement among raft nodes before linearized reading' (duration: 229.669311ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:49.749897Z","caller":"traceutil/trace.go:171","msg":"trace[922630478] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:564; }","duration":"226.451831ms","start":"2026-02-12T08:58:49.523424Z","end":"2026-02-12T08:58:49.749875Z","steps":["trace[922630478] 'read index received' (duration: 16.548019ms)","trace[922630478] 'applied index is now lower than readState.Index' (duration: 209.902782ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:58:49.763188Z","caller":"traceutil/trace.go:171","msg":"trace[945556642] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"126.939164ms","start":"2026-02-12T08:58:49.636237Z","end":"2026-02-12T08:58:49.763177Z","steps":["trace[945556642] 'process raft request' (duration: 126.920233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.763376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.313126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:58:49.763446Z","caller":"traceutil/trace.go:171","msg":"trace[2063160859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"201.414947ms","start":"2026-02-12T08:58:49.562024Z","end":"2026-02-12T08:58:49.763438Z","steps":["trace[2063160859] 'agreement among raft nodes before linearized reading' (duration: 201.312255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.763652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.259939ms","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-02-12T08:58:49.763713Z","caller":"traceutil/trace.go:171","msg":"trace[1529711735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"144.33024ms","start":"2026-02-12T08:58:49.619378Z","end":"2026-02-12T08:58:49.763708Z","steps":["trace[1529711735] 'agreement among raft nodes before linearized reading' (duration: 144.216848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.763916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.546264ms","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-02-12T08:58:49.763977Z","caller":"traceutil/trace.go:171","msg":"trace[745273571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"144.606336ms","start":"2026-02-12T08:58:49.619365Z","end":"2026-02-12T08:58:49.763971Z","steps":["trace[745273571] 'agreement among raft nodes before linearized reading' (duration: 144.515084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.764173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.224451ms","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-02-12T08:58:49.764234Z","caller":"traceutil/trace.go:171","msg":"trace[752832823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"146.286382ms","start":"2026-02-12T08:58:49.617943Z","end":"2026-02-12T08:58:49.764229Z","steps":["trace[752832823] 'agreement among raft nodes before linearized reading' (duration: 146.19828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:58:49.764437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.342476ms","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-02-12T08:58:49.764524Z","caller":"traceutil/trace.go:171","msg":"trace[8710089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"153.449497ms","start":"2026-02-12T08:58:49.611069Z","end":"2026-02-12T08:58:49.764518Z","steps":["trace[8710089] 'agreement among raft nodes before linearized reading' (duration: 153.334945ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:58:49.765117Z","caller":"traceutil/trace.go:171","msg":"trace[2052079797] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"215.560664ms","start":"2026-02-12T08:58:49.549549Z","end":"2026-02-12T08:58:49.765109Z","steps":["trace[2052079797] 'process raft request' (duration: 213.527201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:09.561855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000545755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T08:59:09.561945Z","caller":"traceutil/trace.go:171","msg":"trace[2117037846] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000696298s","start":"2026-02-12T08:59:07.561231Z","end":"2026-02-12T08:59:09.561927Z","steps":["trace[2117037846] 'agreement among raft nodes before linearized reading' (duration: 2.000590185s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:09.561994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:07.561211Z","time spent":"2.000772509s","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T08:59:11.563258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000691348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T08:59:11.563337Z","caller":"traceutil/trace.go:171","msg":"trace[2002816981] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0008326s","start":"2026-02-12T08:59:09.562486Z","end":"2026-02-12T08:59:11.563319Z","steps":["trace[2002816981] 'agreement among raft nodes before linearized reading' (duration: 2.000710738s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:11.563382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:09.562471Z","time spent":"2.000901942s","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T08:59:12.529187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.842804983s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T08:59:12.529258Z","caller":"traceutil/trace.go:171","msg":"trace[1557688052] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"4.967956118s","start":"2026-02-12T08:59:07.561292Z","end":"2026-02-12T08:59:12.529248Z","steps":["trace[1557688052] 'read index received' (duration: 125.238457ms)","trace[1557688052] 'applied index is now lower than readState.Index' (duration: 4.842716791s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:12.594183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999698155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T08:59:12.594264Z","caller":"traceutil/trace.go:171","msg":"trace[1225522616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999815439s","start":"2026-02-12T08:59:07.59443Z","end":"2026-02-12T08:59:12.594246Z","steps":["trace[1225522616] 'agreement among raft nodes before linearized reading' (duration: 4.999714466s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:12.594357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:07.594416Z","time spent":"4.999931351s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T08:59:12.877915Z","caller":"traceutil/trace.go:171","msg":"trace[162790754] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.30961519s","start":"2026-02-12T08:59:11.568277Z","end":"2026-02-12T08:59:12.877892Z","steps":["trace[162790754] 'process raft request' (duration: 1.309416166s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:12.878098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:11.568257Z","time spent":"1.309724302s","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T08:59:12.908072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999611884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T08:59:12.908121Z","caller":"traceutil/trace.go:171","msg":"trace[1183830979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999720766s","start":"2026-02-12T08:59:07.908388Z","end":"2026-02-12T08:59:12.908109Z","steps":["trace[1183830979] 'agreement among raft nodes before linearized reading' (duration: 4.999652464s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:12.908143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:07.90837Z","time spent":"4.999768827s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T08:59:12.91771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999802808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-12T08:59:12.917779Z","caller":"traceutil/trace.go:171","msg":"trace[542424894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.99990843s","start":"2026-02-12T08:59:07.917858Z","end":"2026-02-12T08:59:12.917767Z","steps":["trace[542424894] 'agreement among raft nodes before linearized reading' (duration: 4.999813638s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:12.917806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:07.917851Z","time spent":"4.999949222s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/12 08:59:12 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T08:59:13.572252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00030558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T08:59:13.572444Z","caller":"traceutil/trace.go:171","msg":"trace[1476753680] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000551495s","start":"2026-02-12T08:59:11.571876Z","end":"2026-02-12T08:59:13.572427Z","steps":["trace[1476753680] 'agreement among raft nodes before linearized reading' (duration: 2.00032723s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:13.572512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:11.571863Z","time spent":"2.000637477s","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/12 08:59:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T08:59:14.966164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.727432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T08:59:14.966234Z","caller":"traceutil/trace.go:171","msg":"trace[899732319] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:738; }","duration":"2.436950687s","start":"2026-02-12T08:59:12.529271Z","end":"2026-02-12T08:59:14.966222Z","steps":["trace[899732319] 'read index received' (duration: 348.560058ms)","trace[899732319] 'applied index is now lower than readState.Index' (duration: 2.088389829s)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:59:14.966365Z","caller":"traceutil/trace.go:171","msg":"trace[121256300] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"3.041684143s","start":"2026-02-12T08:59:11.924671Z","end":"2026-02-12T08:59:14.966355Z","steps":["trace[121256300] 'process raft request' (duration: 2.425714515s)","trace[121256300] 'compare' (duration: 615.383454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:14.966413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:11.924652Z","time spent":"3.041735755s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:14.966577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.110827143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T08:59:14.966596Z","caller":"traceutil/trace.go:171","msg":"trace[1934205750] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:717; }","duration":"3.110875824s","start":"2026-02-12T08:59:11.855714Z","end":"2026-02-12T08:59:14.96659Z","steps":["trace[1934205750] 'agreement among raft nodes before linearized reading' (duration: 3.110799062s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:14.966613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:11.855695Z","time spent":"3.110914754s","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"warn","ts":"2026-02-12T08:59:14.966715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.746216083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T08:59:14.966732Z","caller":"traceutil/trace.go:171","msg":"trace[1759916630] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:717; }","duration":"3.746263764s","start":"2026-02-12T08:59:11.220462Z","end":"2026-02-12T08:59:14.966726Z","steps":["trace[1759916630] 'agreement among raft nodes before linearized reading' (duration: 3.746229923s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:14.966773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:11.220435Z","time spent":"3.746311024s","remote":"127.0.0.1:47020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:14.966862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.943119429s","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-02-12T08:59:14.966878Z","caller":"traceutil/trace.go:171","msg":"trace[2078231636] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:717; }","duration":"4.94317152s","start":"2026-02-12T08:59:10.023702Z","end":"2026-02-12T08:59:14.966873Z","steps":["trace[2078231636] 'agreement among raft nodes before linearized reading' (duration: 4.943139649s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:14.966895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:10.023643Z","time spent":"4.943247561s","remote":"127.0.0.1:47270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:14.966987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.953216697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T08:59:14.967004Z","caller":"traceutil/trace.go:171","msg":"trace[896637920] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:717; }","duration":"4.953269969s","start":"2026-02-12T08:59:10.013728Z","end":"2026-02-12T08:59:14.966998Z","steps":["trace[896637920] 'agreement among raft nodes before linearized reading' (duration: 4.953236048s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:14.96702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:10.013705Z","time spent":"4.953310549s","remote":"127.0.0.1:47382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:14.96712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.043036408s","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-02-12T08:59:14.967137Z","caller":"traceutil/trace.go:171","msg":"trace[1933013356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"7.043065009s","start":"2026-02-12T08:59:07.924067Z","end":"2026-02-12T08:59:14.967132Z","steps":["trace[1933013356] 'agreement among raft nodes before linearized reading' (duration: 7.043016188s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:14.967155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:07.92406Z","time spent":"7.043090829s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T08:59:14.967264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.045270326s","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-02-12T08:59:14.96728Z","caller":"traceutil/trace.go:171","msg":"trace[771971546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"7.045302928s","start":"2026-02-12T08:59:07.921972Z","end":"2026-02-12T08:59:14.967275Z","steps":["trace[771971546] 'agreement among raft nodes before linearized reading' (duration: 7.045246556s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:14.967295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:07.92195Z","time spent":"7.045341958s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T08:59:15.572536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000106905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-12T08:59:15.573282Z","caller":"traceutil/trace.go:171","msg":"trace[578640030] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000840381s","start":"2026-02-12T08:59:13.572365Z","end":"2026-02-12T08:59:15.573205Z","steps":["trace[578640030] 'agreement among raft nodes before linearized reading' (duration: 2.000132946s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:15.57343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:13.572349Z","time spent":"2.001064685s","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/12 08:59:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T08:59:16.825859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.471216466s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T08:59:16.825933Z","caller":"traceutil/trace.go:171","msg":"trace[1862689250] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"2.428215199s","start":"2026-02-12T08:59:14.397704Z","end":"2026-02-12T08:59:16.825919Z","steps":["trace[1862689250] 'process raft request' (duration: 955.633134ms)","trace[1862689250] 'compare' (duration: 1.470551351s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:16.825981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.397684Z","time spent":"2.428276181s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:17.301771Z","caller":"traceutil/trace.go:171","msg":"trace[723253792] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"2.335505019s","start":"2026-02-12T08:59:14.96625Z","end":"2026-02-12T08:59:17.301755Z","steps":["trace[723253792] 'read index received' (duration: 387.094435ms)","trace[723253792] 'applied index is now lower than readState.Index' (duration: 1.948409894s)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:59:17.301918Z","caller":"traceutil/trace.go:171","msg":"trace[18639375] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"2.573895719s","start":"2026-02-12T08:59:14.728014Z","end":"2026-02-12T08:59:17.30191Z","steps":["trace[18639375] 'process raft request' (duration: 2.560894318s)","trace[18639375] 'compare' (duration: 12.46345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:17.302006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.727997Z","time spent":"2.57393867s","remote":"127.0.0.1:47014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:17.302164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.969354Z","time spent":"2.33280824s","remote":"127.0.0.1:46870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T08:59:17.302371Z","caller":"traceutil/trace.go:171","msg":"trace[144569481] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"2.328056377s","start":"2026-02-12T08:59:14.974304Z","end":"2026-02-12T08:59:17.30236Z","steps":["trace[144569481] 'process raft request' (duration: 2.327389493s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.302425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.974292Z","time spent":"2.328102438s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:17.302545Z","caller":"traceutil/trace.go:171","msg":"trace[593006733] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"2.3268111s","start":"2026-02-12T08:59:14.975726Z","end":"2026-02-12T08:59:17.302537Z","steps":["trace[593006733] 'process raft request' (duration: 2.326008872s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.30259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.975718Z","time spent":"2.32684797s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:17.302773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.383116121s","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-02-12T08:59:17.302805Z","caller":"traceutil/trace.go:171","msg":"trace[1501007986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"2.383157282s","start":"2026-02-12T08:59:14.919639Z","end":"2026-02-12T08:59:17.302796Z","steps":["trace[1501007986] 'agreement among raft nodes before linearized reading' (duration: 2.38305358s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.302832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.919633Z","time spent":"2.383193474s","remote":"127.0.0.1:47078","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-02-12T08:59:17.302994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.393507747s","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-02-12T08:59:17.30302Z","caller":"traceutil/trace.go:171","msg":"trace[1150322716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:721; }","duration":"2.393554649s","start":"2026-02-12T08:59:14.909458Z","end":"2026-02-12T08:59:17.303013Z","steps":["trace[1150322716] 'agreement among raft nodes before linearized reading' (duration: 2.393475607s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.303075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.909446Z","time spent":"2.39362268s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T08:59:17.30323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.533307699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-5dd5756b68-mfbw9.1893741c9f6edd0a\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-02-12T08:59:17.303255Z","caller":"traceutil/trace.go:171","msg":"trace[791754349] range","detail":"{range_begin:/registry/events/kube-system/coredns-5dd5756b68-mfbw9.1893741c9f6edd0a; range_end:; response_count:1; response_revision:721; }","duration":"2.533371461s","start":"2026-02-12T08:59:14.769876Z","end":"2026-02-12T08:59:17.303247Z","steps":["trace[791754349] 'agreement among raft nodes before linearized reading' (duration: 2.533301719s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.30328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.76986Z","time spent":"2.533414541s","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":855,"request content":"key:\"/registry/events/kube-system/coredns-5dd5756b68-mfbw9.1893741c9f6edd0a\" "} {"level":"warn","ts":"2026-02-12T08:59:17.303434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.706865642s","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-02-12T08:59:17.30346Z","caller":"traceutil/trace.go:171","msg":"trace[672295446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"2.706925804s","start":"2026-02-12T08:59:14.596527Z","end":"2026-02-12T08:59:17.303453Z","steps":["trace[672295446] 'agreement among raft nodes before linearized reading' (duration: 2.706855713s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.303487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:14.596506Z","time spent":"2.706974876s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T08:59:17.304423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.603710727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T08:59:17.304473Z","caller":"traceutil/trace.go:171","msg":"trace[396136085] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:721; }","duration":"4.60381899s","start":"2026-02-12T08:59:12.700641Z","end":"2026-02-12T08:59:17.30446Z","steps":["trace[396136085] 'agreement among raft nodes before linearized reading' (duration: 4.603689717s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.304499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:12.700619Z","time spent":"4.603875431s","remote":"127.0.0.1:47162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:17.308197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.348043418s","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-02-12T08:59:17.308232Z","caller":"traceutil/trace.go:171","msg":"trace[1952247433] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:721; }","duration":"3.348083439s","start":"2026-02-12T08:59:13.96014Z","end":"2026-02-12T08:59:17.308223Z","steps":["trace[1952247433] 'agreement among raft nodes before linearized reading' (duration: 3.343445779s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.308257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:13.960078Z","time spent":"3.34817146s","remote":"127.0.0.1:47380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":96,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:17.579931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000742409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T08:59:17.580027Z","caller":"traceutil/trace.go:171","msg":"trace[1929898229] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000924154s","start":"2026-02-12T08:59:15.579085Z","end":"2026-02-12T08:59:17.58001Z","steps":["trace[1929898229] 'agreement among raft nodes before linearized reading' (duration: 2.00080944s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:17.580074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:15.579071Z","time spent":"2.000993815s","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/12 08:59:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T08:59:17.90892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.315901Z","time spent":"593.01293ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/12 08:59:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T08:59:17.919816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.316951Z","time spent":"602.858924ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/12 08:59:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T08:59:18.501633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137510639s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T08:59:18.501763Z","caller":"traceutil/trace.go:171","msg":"trace[76641359] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"1.199932153s","start":"2026-02-12T08:59:17.301813Z","end":"2026-02-12T08:59:18.501745Z","steps":["trace[76641359] 'read index received' (duration: 62.473526ms)","trace[76641359] 'applied index is now lower than readState.Index' (duration: 1.137457247s)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:59:18.502703Z","caller":"traceutil/trace.go:171","msg":"trace[19065584] transaction","detail":"{read_only:false; number_of_response:1; response_revision:722; }","duration":"1.187771468s","start":"2026-02-12T08:59:17.31492Z","end":"2026-02-12T08:59:18.502691Z","steps":["trace[19065584] 'process raft request' (duration: 1.187671806s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:18.502812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.314907Z","time spent":"1.187841921s","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":41,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:18.503077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.113620243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:18.503108Z","caller":"traceutil/trace.go:171","msg":"trace[795932784] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:722; }","duration":"3.113678725s","start":"2026-02-12T08:59:15.38942Z","end":"2026-02-12T08:59:18.503099Z","steps":["trace[795932784] 'agreement among raft nodes before linearized reading' (duration: 3.113604234s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:18.503137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:15.389408Z","time spent":"3.113721376s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:18.503287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.148070241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T08:59:18.503323Z","caller":"traceutil/trace.go:171","msg":"trace[134861203] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:722; }","duration":"3.148138493s","start":"2026-02-12T08:59:15.355174Z","end":"2026-02-12T08:59:18.503313Z","steps":["trace[134861203] 'agreement among raft nodes before linearized reading' (duration: 3.148080151s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:18.503359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:15.355157Z","time spent":"3.148191213s","remote":"127.0.0.1:47336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:18.50354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.314438Z","time spent":"1.189099677s","remote":"127.0.0.1:46914","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T08:59:19.025632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.442749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T08:59:19.025895Z","caller":"traceutil/trace.go:171","msg":"trace[1414362328] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"516.829879ms","start":"2026-02-12T08:59:18.509051Z","end":"2026-02-12T08:59:19.025881Z","steps":["trace[1414362328] 'process raft request' (duration: 516.791038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:19.02595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:18.509041Z","time spent":"516.87877ms","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:19.025941Z","caller":"traceutil/trace.go:171","msg":"trace[1679950633] transaction","detail":"{read_only:false; number_of_response:1; response_revision:725; }","duration":"1.42810707s","start":"2026-02-12T08:59:17.597804Z","end":"2026-02-12T08:59:19.025911Z","steps":["trace[1679950633] 'process raft request' (duration: 1.427942367s)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:59:19.026086Z","caller":"traceutil/trace.go:171","msg":"trace[43253120] transaction","detail":"{read_only:false; number_of_response:1; response_revision:725; }","duration":"1.112919026s","start":"2026-02-12T08:59:17.913157Z","end":"2026-02-12T08:59:19.026076Z","steps":["trace[43253120] 'process raft request' (duration: 1.112611949s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:19.026081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.597786Z","time spent":"1.428216793s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":62,"response count":0,"response size":526,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:19.026126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.913135Z","time spent":"1.112969897s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":513,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:19.02619Z","caller":"traceutil/trace.go:171","msg":"trace[357404832] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:749; }","duration":"524.388212ms","start":"2026-02-12T08:59:18.501796Z","end":"2026-02-12T08:59:19.026184Z","steps":["trace[357404832] 'read index received' (duration: 89.152453ms)","trace[357404832] 'applied index is now lower than readState.Index' (duration: 435.235069ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:19.026237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445684052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:19.026274Z","caller":"traceutil/trace.go:171","msg":"trace[1738483037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"1.445740462s","start":"2026-02-12T08:59:17.580507Z","end":"2026-02-12T08:59:19.026248Z","steps":["trace[1738483037] 'agreement among raft nodes before linearized reading' (duration: 1.445699601s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:19.026293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.580489Z","time spent":"1.445799844s","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T08:59:19.026401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:18.50893Z","time spent":"517.470583ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T08:59:19.026522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.174813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T08:59:19.026542Z","caller":"traceutil/trace.go:171","msg":"trace[1296073491] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:726; }","duration":"812.234294ms","start":"2026-02-12T08:59:18.214301Z","end":"2026-02-12T08:59:19.026535Z","steps":["trace[1296073491] 'agreement among raft nodes before linearized reading' (duration: 812.198314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:19.026567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:18.21428Z","time spent":"812.273975ms","remote":"127.0.0.1:47004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:19.026668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135956455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:19.026685Z","caller":"traceutil/trace.go:171","msg":"trace[981597927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"1.136054928s","start":"2026-02-12T08:59:17.890624Z","end":"2026-02-12T08:59:19.026679Z","steps":["trace[981597927] 'agreement among raft nodes before linearized reading' (duration: 1.136029917s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:19.0267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:17.890579Z","time spent":"1.136117459s","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T08:59:27.556447Z","caller":"traceutil/trace.go:171","msg":"trace[1399179098] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"172.494659ms","start":"2026-02-12T08:59:27.383934Z","end":"2026-02-12T08:59:27.556429Z","steps":["trace[1399179098] 'process raft request' (duration: 172.373746ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:59:39.911526Z","caller":"traceutil/trace.go:171","msg":"trace[1469765707] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"362.378423ms","start":"2026-02-12T08:59:39.549131Z","end":"2026-02-12T08:59:39.911509Z","steps":["trace[1469765707] 'read index received' (duration: 362.185819ms)","trace[1469765707] 'applied index is now lower than readState.Index' (duration: 191.954µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:59:39.911718Z","caller":"traceutil/trace.go:171","msg":"trace[907844522] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"364.590432ms","start":"2026-02-12T08:59:39.54712Z","end":"2026-02-12T08:59:39.91171Z","steps":["trace[907844522] 'process raft request' (duration: 364.279456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.911805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.547068Z","time spent":"364.676004ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:39.911932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.796162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.911954Z","caller":"traceutil/trace.go:171","msg":"trace[905913909] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:789; }","duration":"362.832994ms","start":"2026-02-12T08:59:39.549113Z","end":"2026-02-12T08:59:39.911946Z","steps":["trace[905913909] 'agreement among raft nodes before linearized reading' (duration: 362.795163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.911972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.549106Z","time spent":"362.861954ms","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-12T08:59:39.912183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.67808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.912215Z","caller":"traceutil/trace.go:171","msg":"trace[291923438] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:789; }","duration":"362.71131ms","start":"2026-02-12T08:59:39.549487Z","end":"2026-02-12T08:59:39.912198Z","steps":["trace[291923438] 'agreement among raft nodes before linearized reading' (duration: 362.676639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.912234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.549482Z","time spent":"362.747212ms","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-12T08:59:39.912614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.865193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-12T08:59:39.912636Z","caller":"traceutil/trace.go:171","msg":"trace[1669732463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:789; }","duration":"211.906563ms","start":"2026-02-12T08:59:39.700722Z","end":"2026-02-12T08:59:39.912629Z","steps":["trace[1669732463] 'agreement among raft nodes before linearized reading' (duration: 211.870433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.912745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.837344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-12T08:59:39.912762Z","caller":"traceutil/trace.go:171","msg":"trace[1184645954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:789; }","duration":"248.871814ms","start":"2026-02-12T08:59:39.663884Z","end":"2026-02-12T08:59:39.912756Z","steps":["trace[1184645954] 'agreement among raft nodes before linearized reading' (duration: 248.838854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.912909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.071053ms","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-02-12T08:59:39.912926Z","caller":"traceutil/trace.go:171","msg":"trace[1066129309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"292.105295ms","start":"2026-02-12T08:59:39.620816Z","end":"2026-02-12T08:59:39.912921Z","steps":["trace[1066129309] 'agreement among raft nodes before linearized reading' (duration: 292.055794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.913008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.985677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.913026Z","caller":"traceutil/trace.go:171","msg":"trace[1624689485] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:789; }","duration":"322.010147ms","start":"2026-02-12T08:59:39.59101Z","end":"2026-02-12T08:59:39.91302Z","steps":["trace[1624689485] 'agreement among raft nodes before linearized reading' (duration: 321.982746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.913043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.591007Z","time spent":"322.030387ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:39.913135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.124999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.913151Z","caller":"traceutil/trace.go:171","msg":"trace[1829087904] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:789; }","duration":"322.155391ms","start":"2026-02-12T08:59:39.590991Z","end":"2026-02-12T08:59:39.913147Z","steps":["trace[1829087904] 'agreement among raft nodes before linearized reading' (duration: 322.1304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.913168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.590989Z","time spent":"322.174821ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-12T08:59:39.913264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.280093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.913281Z","caller":"traceutil/trace.go:171","msg":"trace[941309132] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:789; }","duration":"322.298434ms","start":"2026-02-12T08:59:39.590978Z","end":"2026-02-12T08:59:39.913276Z","steps":["trace[941309132] 'agreement among raft nodes before linearized reading' (duration: 322.259793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.913298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.590965Z","time spent":"322.328284ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-12T08:59:39.913374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.714441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.91339Z","caller":"traceutil/trace.go:171","msg":"trace[1988731502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"348.741031ms","start":"2026-02-12T08:59:39.564642Z","end":"2026-02-12T08:59:39.913383Z","steps":["trace[1988731502] 'agreement among raft nodes before linearized reading' (duration: 348.71741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.913405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.564633Z","time spent":"348.767652ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T08:59:39.913499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.895985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:39.913515Z","caller":"traceutil/trace.go:171","msg":"trace[630579186] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:789; }","duration":"362.922806ms","start":"2026-02-12T08:59:39.550588Z","end":"2026-02-12T08:59:39.91351Z","steps":["trace[630579186] 'agreement among raft nodes before linearized reading' (duration: 362.895935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:39.913532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:39.550583Z","time spent":"362.944267ms","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-02-12T08:59:41.444061Z","caller":"traceutil/trace.go:171","msg":"trace[706269659] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"100.651994ms","start":"2026-02-12T08:59:41.343392Z","end":"2026-02-12T08:59:41.444044Z","steps":["trace[706269659] 'process raft request' (duration: 100.546481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:41.67453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.303843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:41.67462Z","caller":"traceutil/trace.go:171","msg":"trace[2019160580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"117.462507ms","start":"2026-02-12T08:59:41.557139Z","end":"2026-02-12T08:59:41.674601Z","steps":["trace[2019160580] 'range keys from in-memory index tree' (duration: 117.242972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:44.016399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.975504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:44.016467Z","caller":"traceutil/trace.go:171","msg":"trace[1588698143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"457.076215ms","start":"2026-02-12T08:59:43.559374Z","end":"2026-02-12T08:59:44.01645Z","steps":["trace[1588698143] 'range keys from in-memory index tree' (duration: 456.877512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:44.016526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:43.559359Z","time spent":"457.157478ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T08:59:44.625376Z","caller":"traceutil/trace.go:171","msg":"trace[823533923] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:839; }","duration":"349.309454ms","start":"2026-02-12T08:59:44.276022Z","end":"2026-02-12T08:59:44.625331Z","steps":["trace[823533923] 'read index received' (duration: 349.001968ms)","trace[823533923] 'applied index is now lower than readState.Index' (duration: 306.206µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:59:44.625749Z","caller":"traceutil/trace.go:171","msg":"trace[752280080] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"603.836363ms","start":"2026-02-12T08:59:44.021901Z","end":"2026-02-12T08:59:44.625737Z","steps":["trace[752280080] 'process raft request' (duration: 603.180009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:44.625846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:44.021891Z","time spent":"603.889364ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:44.626013Z","caller":"traceutil/trace.go:171","msg":"trace[104036181] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"603.672658ms","start":"2026-02-12T08:59:44.022329Z","end":"2026-02-12T08:59:44.626002Z","steps":["trace[104036181] 'process raft request' (duration: 602.924742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:44.626064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:44.022318Z","time spent":"603.718ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T08:59:44.626238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.208894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T08:59:44.62628Z","caller":"traceutil/trace.go:171","msg":"trace[1478928720] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:806; }","duration":"350.286196ms","start":"2026-02-12T08:59:44.275981Z","end":"2026-02-12T08:59:44.626267Z","steps":["trace[1478928720] 'agreement among raft nodes before linearized reading' (duration: 350.222114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:44.626317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:44.275963Z","time spent":"350.344807ms","remote":"127.0.0.1:47314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-12T08:59:44.626475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.033584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T08:59:44.626504Z","caller":"traceutil/trace.go:171","msg":"trace[72069582] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:806; }","duration":"293.081165ms","start":"2026-02-12T08:59:44.333412Z","end":"2026-02-12T08:59:44.626494Z","steps":["trace[72069582] 'agreement among raft nodes before linearized reading' (duration: 293.033164ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T08:59:49.001746Z","caller":"traceutil/trace.go:171","msg":"trace[434569911] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"308.62012ms","start":"2026-02-12T08:59:48.693103Z","end":"2026-02-12T08:59:49.001723Z","steps":["trace[434569911] 'process raft request' (duration: 308.472367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:49.001919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:48.693091Z","time spent":"308.720313ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:49.190021Z","caller":"traceutil/trace.go:171","msg":"trace[672604365] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"457.77468ms","start":"2026-02-12T08:59:48.73223Z","end":"2026-02-12T08:59:49.190004Z","steps":["trace[672604365] 'read index received' (duration: 270.125446ms)","trace[672604365] 'applied index is now lower than readState.Index' (duration: 187.648374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:49.190142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.903163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T08:59:49.190161Z","caller":"traceutil/trace.go:171","msg":"trace[1726977356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"457.930984ms","start":"2026-02-12T08:59:48.732222Z","end":"2026-02-12T08:59:49.190153Z","steps":["trace[1726977356] 'agreement among raft nodes before linearized reading' (duration: 457.837442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:49.190177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:48.732173Z","time spent":"457.999525ms","remote":"127.0.0.1:47078","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":"info","ts":"2026-02-12T08:59:49.190171Z","caller":"traceutil/trace.go:171","msg":"trace[1353598587] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"495.55142ms","start":"2026-02-12T08:59:48.694598Z","end":"2026-02-12T08:59:49.190149Z","steps":["trace[1353598587] 'process raft request' (duration: 409.377097ms)","trace[1353598587] 'compare' (duration: 85.907677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T08:59:49.190269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:48.694588Z","time spent":"495.627341ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T08:59:57.549853Z","caller":"traceutil/trace.go:171","msg":"trace[23889455] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"247.776239ms","start":"2026-02-12T08:59:57.302061Z","end":"2026-02-12T08:59:57.549837Z","steps":["trace[23889455] 'read index received' (duration: 247.588335ms)","trace[23889455] 'applied index is now lower than readState.Index' (duration: 187.324µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T08:59:57.550101Z","caller":"traceutil/trace.go:171","msg":"trace[461939004] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"249.839297ms","start":"2026-02-12T08:59:57.300254Z","end":"2026-02-12T08:59:57.550093Z","steps":["trace[461939004] 'process raft request' (duration: 249.452467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:57.550289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.21249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T08:59:57.550321Z","caller":"traceutil/trace.go:171","msg":"trace[2060111083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"248.272602ms","start":"2026-02-12T08:59:57.302042Z","end":"2026-02-12T08:59:57.550314Z","steps":["trace[2060111083] 'agreement among raft nodes before linearized reading' (duration: 248.168299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:57.55043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.894548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T08:59:57.550443Z","caller":"traceutil/trace.go:171","msg":"trace[1590047816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"204.908068ms","start":"2026-02-12T08:59:57.345531Z","end":"2026-02-12T08:59:57.550439Z","steps":["trace[1590047816] 'agreement among raft nodes before linearized reading' (duration: 204.868627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T08:59:59.803839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058030960609525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T09:00:00.120669Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005682263s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T09:00:00.120935Z","caller":"traceutil/trace.go:171","msg":"trace[1840247580] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"817.960946ms","start":"2026-02-12T08:59:59.302956Z","end":"2026-02-12T09:00:00.120917Z","steps":["trace[1840247580] 'read index received' (duration: 817.815203ms)","trace[1840247580] 'applied index is now lower than readState.Index' (duration: 145.053µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:00.120994Z","caller":"traceutil/trace.go:171","msg":"trace[1756373693] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.006057571s","start":"2026-02-12T08:59:59.114927Z","end":"2026-02-12T09:00:00.120985Z","steps":["trace[1756373693] 'process raft request' (duration: 1.005881927s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:00.121074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:59.114918Z","time spent":"1.006093702s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:00.121218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.118347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T09:00:00.121268Z","caller":"traceutil/trace.go:171","msg":"trace[1143292221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"562.189508ms","start":"2026-02-12T08:59:59.559066Z","end":"2026-02-12T09:00:00.121256Z","steps":["trace[1143292221] 'agreement among raft nodes before linearized reading' (duration: 562.028145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:00.121295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:59.559031Z","time spent":"562.2593ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:00.121408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.936201ms","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-02-12T09:00:00.121432Z","caller":"traceutil/trace.go:171","msg":"trace[107798043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"551.978382ms","start":"2026-02-12T08:59:59.569448Z","end":"2026-02-12T09:00:00.121426Z","steps":["trace[107798043] 'agreement among raft nodes before linearized reading' (duration: 551.89086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:00.121447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.618269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-12T09:00:00.121453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:59.569439Z","time spent":"552.006542ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T09:00:00.121466Z","caller":"traceutil/trace.go:171","msg":"trace[773461780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"558.66721ms","start":"2026-02-12T08:59:59.562794Z","end":"2026-02-12T09:00:00.121461Z","steps":["trace[773461780] 'agreement among raft nodes before linearized reading' (duration: 558.615439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:00.121487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:59.562785Z","time spent":"558.695681ms","remote":"127.0.0.1:47078","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-02-12T09:00:00.121546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.587869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T09:00:00.121577Z","caller":"traceutil/trace.go:171","msg":"trace[1333589099] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:896; }","duration":"818.626151ms","start":"2026-02-12T08:59:59.30293Z","end":"2026-02-12T09:00:00.121556Z","steps":["trace[1333589099] 'agreement among raft nodes before linearized reading' (duration: 818.59948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:00.121594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T08:59:59.302916Z","time spent":"818.672791ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-12T09:00:02.298868Z","caller":"traceutil/trace.go:171","msg":"trace[1678766757] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"116.277052ms","start":"2026-02-12T09:00:02.182579Z","end":"2026-02-12T09:00:02.298856Z","steps":["trace[1678766757] 'read index received' (duration: 6.850233ms)","trace[1678766757] 'applied index is now lower than readState.Index' (duration: 109.424879ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:02.299103Z","caller":"traceutil/trace.go:171","msg":"trace[1266509491] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"133.075685ms","start":"2026-02-12T09:00:02.166021Z","end":"2026-02-12T09:00:02.299096Z","steps":["trace[1266509491] 'process raft request' (duration: 81.248684ms)","trace[1266509491] 'compare' (duration: 51.477853ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:02.2992Z","caller":"traceutil/trace.go:171","msg":"trace[354134349] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"131.861227ms","start":"2026-02-12T09:00:02.167334Z","end":"2026-02-12T09:00:02.299196Z","steps":["trace[354134349] 'process raft request' (duration: 131.488389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:02.299321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.731861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T09:00:02.299337Z","caller":"traceutil/trace.go:171","msg":"trace[2056576189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:905; }","duration":"116.780122ms","start":"2026-02-12T09:00:02.182552Z","end":"2026-02-12T09:00:02.299333Z","steps":["trace[2056576189] 'agreement among raft nodes before linearized reading' (duration: 116.71085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.258319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.471903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:05.258368Z","caller":"traceutil/trace.go:171","msg":"trace[1685532324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"701.552756ms","start":"2026-02-12T09:00:04.556803Z","end":"2026-02-12T09:00:05.258356Z","steps":["trace[1685532324] 'range keys from in-memory index tree' (duration: 701.432313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.258388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:04.556789Z","time spent":"701.595056ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:05.258444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.067123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-12T09:00:05.25849Z","caller":"traceutil/trace.go:171","msg":"trace[1709177941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"955.158725ms","start":"2026-02-12T09:00:04.30332Z","end":"2026-02-12T09:00:05.258479Z","steps":["trace[1709177941] 'range keys from in-memory index tree' (duration: 954.959801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.258512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:04.303306Z","time spent":"955.201096ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:05.258659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.666421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T09:00:05.258674Z","caller":"traceutil/trace.go:171","msg":"trace[43815054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:908; }","duration":"953.702872ms","start":"2026-02-12T09:00:04.304965Z","end":"2026-02-12T09:00:05.258668Z","steps":["trace[43815054] 'range keys from in-memory index tree' (duration: 953.604191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.258686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:04.304952Z","time spent":"953.731003ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:05.258835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.463952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T09:00:05.258854Z","caller":"traceutil/trace.go:171","msg":"trace[1359723968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"937.491552ms","start":"2026-02-12T09:00:04.321356Z","end":"2026-02-12T09:00:05.258848Z","steps":["trace[1359723968] 'range keys from in-memory index tree' (duration: 937.3928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.258868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:04.321352Z","time spent":"937.513913ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-12T09:00:05.740472Z","caller":"traceutil/trace.go:171","msg":"trace[2095383439] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"477.945686ms","start":"2026-02-12T09:00:05.262507Z","end":"2026-02-12T09:00:05.740452Z","steps":["trace[2095383439] 'process raft request' (duration: 477.836774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.740586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:05.262494Z","time spent":"478.034628ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T09:00:05.743264Z","caller":"traceutil/trace.go:171","msg":"trace[421217461] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"480.563974ms","start":"2026-02-12T09:00:05.262688Z","end":"2026-02-12T09:00:05.743252Z","steps":["trace[421217461] 'process raft request' (duration: 480.36724ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:05.74327Z","caller":"traceutil/trace.go:171","msg":"trace[102565994] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"186.218138ms","start":"2026-02-12T09:00:05.557029Z","end":"2026-02-12T09:00:05.743247Z","steps":["trace[102565994] 'read index received' (duration: 183.924297ms)","trace[102565994] 'applied index is now lower than readState.Index' (duration: 2.292871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:05.743335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:05.26268Z","time spent":"480.610325ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:05.743409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.356501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:05.743442Z","caller":"traceutil/trace.go:171","msg":"trace[1432761354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"186.428243ms","start":"2026-02-12T09:00:05.557004Z","end":"2026-02-12T09:00:05.743432Z","steps":["trace[1432761354] 'agreement among raft nodes before linearized reading' (duration: 186.332301ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:05.743574Z","caller":"traceutil/trace.go:171","msg":"trace[1888900540] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"478.850167ms","start":"2026-02-12T09:00:05.264717Z","end":"2026-02-12T09:00:05.743567Z","steps":["trace[1888900540] 'process raft request' (duration: 478.489389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:05.743614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:05.264708Z","time spent":"478.884658ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:10.717872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058030960609604,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T09:00:10.800544Z","caller":"traceutil/trace.go:171","msg":"trace[1190309153] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"582.852948ms","start":"2026-02-12T09:00:10.217675Z","end":"2026-02-12T09:00:10.800528Z","steps":["trace[1190309153] 'read index received' (duration: 582.729705ms)","trace[1190309153] 'applied index is now lower than readState.Index' (duration: 122.663µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:10.800686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.997061ms","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-02-12T09:00:10.800703Z","caller":"traceutil/trace.go:171","msg":"trace[570697458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"583.050632ms","start":"2026-02-12T09:00:10.217647Z","end":"2026-02-12T09:00:10.800698Z","steps":["trace[570697458] 'agreement among raft nodes before linearized reading' (duration: 582.94079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:10.800728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:10.217634Z","time spent":"583.089434ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T09:00:10.800756Z","caller":"traceutil/trace.go:171","msg":"trace[1504758667] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"616.558906ms","start":"2026-02-12T09:00:10.184176Z","end":"2026-02-12T09:00:10.800735Z","steps":["trace[1504758667] 'process raft request' (duration: 616.245708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:10.80086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:10.184167Z","time spent":"616.640857ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:12.292319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058030960609611,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T09:00:12.292555Z","caller":"traceutil/trace.go:171","msg":"trace[851948412] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"529.387539ms","start":"2026-02-12T09:00:11.763152Z","end":"2026-02-12T09:00:12.29254Z","steps":["trace[851948412] 'read index received' (duration: 529.267467ms)","trace[851948412] 'applied index is now lower than readState.Index' (duration: 119.602µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:12.292713Z","caller":"traceutil/trace.go:171","msg":"trace[201950523] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"530.898563ms","start":"2026-02-12T09:00:11.761808Z","end":"2026-02-12T09:00:12.292707Z","steps":["trace[201950523] 'process raft request' (duration: 530.649107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.292773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:11.761791Z","time spent":"530.935793ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:12.292902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.749197ms","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-02-12T09:00:12.292916Z","caller":"traceutil/trace.go:171","msg":"trace[688339190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"529.788718ms","start":"2026-02-12T09:00:11.763123Z","end":"2026-02-12T09:00:12.292912Z","steps":["trace[688339190] 'agreement among raft nodes before linearized reading' (duration: 529.730407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.29293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:11.763112Z","time spent":"529.814159ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:12.293001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.924215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T09:00:12.293013Z","caller":"traceutil/trace.go:171","msg":"trace[1801065776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"526.956866ms","start":"2026-02-12T09:00:11.766052Z","end":"2026-02-12T09:00:12.293009Z","steps":["trace[1801065776] 'agreement among raft nodes before linearized reading' (duration: 526.923105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.293025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:11.766042Z","time spent":"526.979546ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:12.293114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.071101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T09:00:12.293127Z","caller":"traceutil/trace.go:171","msg":"trace[1327548720] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:926; }","duration":"433.106192ms","start":"2026-02-12T09:00:11.860017Z","end":"2026-02-12T09:00:12.293124Z","steps":["trace[1327548720] 'agreement among raft nodes before linearized reading' (duration: 433.067781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.293138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:11.860004Z","time spent":"433.131202ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"warn","ts":"2026-02-12T09:00:12.917509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.173791ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-12T09:00:12.917697Z","caller":"traceutil/trace.go:171","msg":"trace[288582212] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:968; }","duration":"356.756728ms","start":"2026-02-12T09:00:12.560929Z","end":"2026-02-12T09:00:12.917686Z","steps":["trace[288582212] 'read index received' (duration: 71.420024ms)","trace[288582212] 'applied index is now lower than readState.Index' (duration: 285.336194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:12.917751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:12.302114Z","time spent":"615.633264ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T09:00:12.917959Z","caller":"traceutil/trace.go:171","msg":"trace[594341202] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"605.160483ms","start":"2026-02-12T09:00:12.312792Z","end":"2026-02-12T09:00:12.917953Z","steps":["trace[594341202] 'process raft request' (duration: 604.782614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.918011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:12.312775Z","time spent":"605.198023ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T09:00:12.918071Z","caller":"traceutil/trace.go:171","msg":"trace[1435642388] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"605.157682ms","start":"2026-02-12T09:00:12.312909Z","end":"2026-02-12T09:00:12.918067Z","steps":["trace[1435642388] 'process raft request' (duration: 604.757163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.918095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:12.312842Z","time spent":"605.238634ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:12.918172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.236899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:12.918215Z","caller":"traceutil/trace.go:171","msg":"trace[520699594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"357.330721ms","start":"2026-02-12T09:00:12.560874Z","end":"2026-02-12T09:00:12.918205Z","steps":["trace[520699594] 'agreement among raft nodes before linearized reading' (duration: 357.252069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:12.918239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:12.560854Z","time spent":"357.380352ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:12.918348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.653418ms","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-02-12T09:00:12.918366Z","caller":"traceutil/trace.go:171","msg":"trace[1523931390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"111.69317ms","start":"2026-02-12T09:00:12.806668Z","end":"2026-02-12T09:00:12.918361Z","steps":["trace[1523931390] 'agreement among raft nodes before linearized reading' (duration: 111.624178ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:14.437275Z","caller":"traceutil/trace.go:171","msg":"trace[207714718] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"127.741245ms","start":"2026-02-12T09:00:14.309517Z","end":"2026-02-12T09:00:14.437259Z","steps":["trace[207714718] 'process raft request' (duration: 127.639523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:14.835615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.983398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:14.835665Z","caller":"traceutil/trace.go:171","msg":"trace[1426236545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"277.06117ms","start":"2026-02-12T09:00:14.558592Z","end":"2026-02-12T09:00:14.835653Z","steps":["trace[1426236545] 'range keys from in-memory index tree' (duration: 276.936248ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:15.145786Z","caller":"traceutil/trace.go:171","msg":"trace[418513242] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"217.840575ms","start":"2026-02-12T09:00:14.92793Z","end":"2026-02-12T09:00:15.14577Z","steps":["trace[418513242] 'read index received' (duration: 217.709332ms)","trace[418513242] 'applied index is now lower than readState.Index' (duration: 130.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:15.145861Z","caller":"traceutil/trace.go:171","msg":"trace[930081936] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"222.918148ms","start":"2026-02-12T09:00:14.922938Z","end":"2026-02-12T09:00:15.145856Z","steps":["trace[930081936] 'process raft request' (duration: 222.741964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:15.146013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.75585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T09:00:15.146059Z","caller":"traceutil/trace.go:171","msg":"trace[244338689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"211.818672ms","start":"2026-02-12T09:00:14.934228Z","end":"2026-02-12T09:00:15.146047Z","steps":["trace[244338689] 'agreement among raft nodes before linearized reading' (duration: 211.70426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:15.146157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.214163ms","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-02-12T09:00:15.146178Z","caller":"traceutil/trace.go:171","msg":"trace[1856367827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"218.260605ms","start":"2026-02-12T09:00:14.927912Z","end":"2026-02-12T09:00:15.146172Z","steps":["trace[1856367827] 'agreement among raft nodes before linearized reading' (duration: 218.161633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:15.146194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.762806ms","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-02-12T09:00:15.14621Z","caller":"traceutil/trace.go:171","msg":"trace[1938916476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"209.788187ms","start":"2026-02-12T09:00:14.936417Z","end":"2026-02-12T09:00:15.146205Z","steps":["trace[1938916476] 'agreement among raft nodes before linearized reading' (duration: 209.746756ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:15.65855Z","caller":"traceutil/trace.go:171","msg":"trace[1870424879] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"506.492843ms","start":"2026-02-12T09:00:15.152044Z","end":"2026-02-12T09:00:15.658537Z","steps":["trace[1870424879] 'process raft request' (duration: 474.541503ms)","trace[1870424879] 'compare' (duration: 31.605732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:15.658644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:15.152027Z","time spent":"506.563464ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T09:00:15.658785Z","caller":"traceutil/trace.go:171","msg":"trace[1726685157] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"506.624936ms","start":"2026-02-12T09:00:15.152142Z","end":"2026-02-12T09:00:15.658767Z","steps":["trace[1726685157] 'process raft request' (duration: 506.36615ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:15.658851Z","caller":"traceutil/trace.go:171","msg":"trace[283079930] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"101.703449ms","start":"2026-02-12T09:00:15.557142Z","end":"2026-02-12T09:00:15.658845Z","steps":["trace[283079930] 'read index received' (duration: 69.453282ms)","trace[283079930] 'applied index is now lower than readState.Index' (duration: 32.249617ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:15.65889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.75184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T09:00:15.658892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:15.152137Z","time spent":"506.692657ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T09:00:15.658904Z","caller":"traceutil/trace.go:171","msg":"trace[456012159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"101.793841ms","start":"2026-02-12T09:00:15.557106Z","end":"2026-02-12T09:00:15.6589Z","steps":["trace[456012159] 'agreement among raft nodes before linearized reading' (duration: 101.75979ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:15.659001Z","caller":"traceutil/trace.go:171","msg":"trace[1301649213] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"506.82203ms","start":"2026-02-12T09:00:15.152173Z","end":"2026-02-12T09:00:15.658995Z","steps":["trace[1301649213] 'process raft request' (duration: 506.529684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:15.659027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:15.152169Z","time spent":"506.84287ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:17.784781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.682645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T09:00:17.784845Z","caller":"traceutil/trace.go:171","msg":"trace[507477453] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"117.377615ms","start":"2026-02-12T09:00:17.667456Z","end":"2026-02-12T09:00:17.784834Z","steps":["trace[507477453] 'read index received' (duration: 34.63µs)","trace[507477453] 'applied index is now lower than readState.Index' (duration: 117.342295ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:17.784888Z","caller":"traceutil/trace.go:171","msg":"trace[1256658780] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"138.451814ms","start":"2026-02-12T09:00:17.64643Z","end":"2026-02-12T09:00:17.784882Z","steps":["trace[1256658780] 'process raft request' (duration: 14.618276ms)","trace[1256658780] 'compare' (duration: 123.48087ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:17.785087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.626451ms","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-02-12T09:00:17.785105Z","caller":"traceutil/trace.go:171","msg":"trace[805446994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"117.675942ms","start":"2026-02-12T09:00:17.667423Z","end":"2026-02-12T09:00:17.785099Z","steps":["trace[805446994] 'agreement among raft nodes before linearized reading' (duration: 117.57473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:17.78778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.513912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T09:00:17.787855Z","caller":"traceutil/trace.go:171","msg":"trace[1410516675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"118.620484ms","start":"2026-02-12T09:00:17.669218Z","end":"2026-02-12T09:00:17.787839Z","steps":["trace[1410516675] 'agreement among raft nodes before linearized reading' (duration: 118.410539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:17.788229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.775994ms","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-02-12T09:00:17.788266Z","caller":"traceutil/trace.go:171","msg":"trace[1850357265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"117.823576ms","start":"2026-02-12T09:00:17.670431Z","end":"2026-02-12T09:00:17.788254Z","steps":["trace[1850357265] 'agreement among raft nodes before linearized reading' (duration: 117.727824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:18.017958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.806377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T09:00:18.018055Z","caller":"traceutil/trace.go:171","msg":"trace[971129407] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:987; }","duration":"131.899187ms","start":"2026-02-12T09:00:17.886146Z","end":"2026-02-12T09:00:18.018045Z","steps":["trace[971129407] 'read index received' (duration: 12.988078ms)","trace[971129407] 'applied index is now lower than readState.Index' (duration: 118.907909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:18.018098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.951858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:18.018119Z","caller":"traceutil/trace.go:171","msg":"trace[872232413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"131.972469ms","start":"2026-02-12T09:00:17.886142Z","end":"2026-02-12T09:00:18.018115Z","steps":["trace[872232413] 'agreement among raft nodes before linearized reading' (duration: 131.928518ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:18.018341Z","caller":"traceutil/trace.go:171","msg":"trace[22778773] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"226.470926ms","start":"2026-02-12T09:00:17.791863Z","end":"2026-02-12T09:00:18.018334Z","steps":["trace[22778773] 'process raft request' (duration: 107.24209ms)","trace[22778773] 'compare' (duration: 118.742356ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:18.018419Z","caller":"traceutil/trace.go:171","msg":"trace[1671824723] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"218.96539ms","start":"2026-02-12T09:00:17.799449Z","end":"2026-02-12T09:00:18.018415Z","steps":["trace[1671824723] 'process raft request' (duration: 218.566232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.060158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058030960609672,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T09:00:20.343741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126482714s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T09:00:20.343982Z","caller":"traceutil/trace.go:171","msg":"trace[591150822] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"784.611895ms","start":"2026-02-12T09:00:19.559354Z","end":"2026-02-12T09:00:20.343966Z","steps":["trace[591150822] 'read index received' (duration: 784.483622ms)","trace[591150822] 'applied index is now lower than readState.Index' (duration: 127.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:20.34425Z","caller":"traceutil/trace.go:171","msg":"trace[1706060914] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.166351478s","start":"2026-02-12T09:00:19.177891Z","end":"2026-02-12T09:00:20.344242Z","steps":["trace[1706060914] 'process raft request' (duration: 1.16596001s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.344449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:19.177866Z","time spent":"1.166531073s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:20.344443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.321544ms","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-02-12T09:00:20.344591Z","caller":"traceutil/trace.go:171","msg":"trace[289115234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"518.454177ms","start":"2026-02-12T09:00:19.826107Z","end":"2026-02-12T09:00:20.344561Z","steps":["trace[289115234] 'agreement among raft nodes before linearized reading' (duration: 518.228632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.344654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:19.826048Z","time spent":"518.597171ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T09:00:20.345356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.180917ms","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-02-12T09:00:20.345384Z","caller":"traceutil/trace.go:171","msg":"trace[2072372560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:949; }","duration":"243.243189ms","start":"2026-02-12T09:00:20.102134Z","end":"2026-02-12T09:00:20.345378Z","steps":["trace[2072372560] 'agreement among raft nodes before linearized reading' (duration: 243.174808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.344761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.404243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:20.345504Z","caller":"traceutil/trace.go:171","msg":"trace[2137088438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"786.168061ms","start":"2026-02-12T09:00:19.559328Z","end":"2026-02-12T09:00:20.345496Z","steps":["trace[2137088438] 'agreement among raft nodes before linearized reading' (duration: 785.413454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.345528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:19.559316Z","time spent":"786.203861ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:20.345626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.211911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T09:00:20.345649Z","caller":"traceutil/trace.go:171","msg":"trace[330150466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"322.269623ms","start":"2026-02-12T09:00:20.023373Z","end":"2026-02-12T09:00:20.345643Z","steps":["trace[330150466] 'agreement among raft nodes before linearized reading' (duration: 322.210022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.345667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:20.023356Z","time spent":"322.306963ms","remote":"127.0.0.1:47078","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-02-12T09:00:20.639698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.059438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T09:00:20.639835Z","caller":"traceutil/trace.go:171","msg":"trace[434581757] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"286.193723ms","start":"2026-02-12T09:00:20.353629Z","end":"2026-02-12T09:00:20.639823Z","steps":["trace[434581757] 'process raft request' (duration: 286.136762ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:20.639876Z","caller":"traceutil/trace.go:171","msg":"trace[989836124] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:994; }","duration":"146.564353ms","start":"2026-02-12T09:00:20.493295Z","end":"2026-02-12T09:00:20.63986Z","steps":["trace[989836124] 'read index received' (duration: 9.762806ms)","trace[989836124] 'applied index is now lower than readState.Index' (duration: 136.800867ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:20.639978Z","caller":"traceutil/trace.go:171","msg":"trace[1446414162] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"288.419052ms","start":"2026-02-12T09:00:20.35155Z","end":"2026-02-12T09:00:20.63997Z","steps":["trace[1446414162] 'process raft request' (duration: 89.011296ms)","trace[1446414162] 'compare' (duration: 198.968046ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:20.640049Z","caller":"traceutil/trace.go:171","msg":"trace[406791299] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"285.565419ms","start":"2026-02-12T09:00:20.354478Z","end":"2026-02-12T09:00:20.640043Z","steps":["trace[406791299] 'process raft request' (duration: 285.323113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:20.639994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.687006ms","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-02-12T09:00:20.640131Z","caller":"traceutil/trace.go:171","msg":"trace[723075320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"146.856969ms","start":"2026-02-12T09:00:20.493266Z","end":"2026-02-12T09:00:20.640123Z","steps":["trace[723075320] 'agreement among raft nodes before linearized reading' (duration: 146.645745ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:20.954574Z","caller":"traceutil/trace.go:171","msg":"trace[1124269366] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"159.716205ms","start":"2026-02-12T09:00:20.794829Z","end":"2026-02-12T09:00:20.954546Z","steps":["trace[1124269366] 'process raft request' (duration: 159.47263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.250478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.588037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:22.250537Z","caller":"traceutil/trace.go:171","msg":"trace[1649433660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"693.678048ms","start":"2026-02-12T09:00:21.556845Z","end":"2026-02-12T09:00:22.250523Z","steps":["trace[1649433660] 'range keys from in-memory index tree' (duration: 693.534705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.250565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:21.556831Z","time spent":"693.728459ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:22.250758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092541461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:22.250775Z","caller":"traceutil/trace.go:171","msg":"trace[1405473436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"1.092585592s","start":"2026-02-12T09:00:21.158184Z","end":"2026-02-12T09:00:22.25077Z","steps":["trace[1405473436] 'range keys from in-memory index tree' (duration: 1.09249221s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.250794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:21.158169Z","time spent":"1.092618083s","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:22.251307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.953737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T09:00:22.251327Z","caller":"traceutil/trace.go:171","msg":"trace[826652017] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:953; }","duration":"390.996068ms","start":"2026-02-12T09:00:21.860325Z","end":"2026-02-12T09:00:22.251321Z","steps":["trace[826652017] 'range keys from in-memory index tree' (duration: 390.868036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.251344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:21.860312Z","time spent":"391.028319ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"info","ts":"2026-02-12T09:00:22.548288Z","caller":"traceutil/trace.go:171","msg":"trace[242256171] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"196.976601ms","start":"2026-02-12T09:00:22.351296Z","end":"2026-02-12T09:00:22.548273Z","steps":["trace[242256171] 'read index received' (duration: 196.882109ms)","trace[242256171] 'applied index is now lower than readState.Index' (duration: 93.822µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:22.548419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.105954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-12T09:00:22.548436Z","caller":"traceutil/trace.go:171","msg":"trace[1470167334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"197.159726ms","start":"2026-02-12T09:00:22.35127Z","end":"2026-02-12T09:00:22.54843Z","steps":["trace[1470167334] 'agreement among raft nodes before linearized reading' (duration: 197.059834ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:22.943885Z","caller":"traceutil/trace.go:171","msg":"trace[789612199] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"394.116938ms","start":"2026-02-12T09:00:22.549737Z","end":"2026-02-12T09:00:22.943854Z","steps":["trace[789612199] 'process raft request' (duration: 393.993405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.94403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.549713Z","time spent":"394.24955ms","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T09:00:22.946476Z","caller":"traceutil/trace.go:171","msg":"trace[1859846581] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"388.260608ms","start":"2026-02-12T09:00:22.558207Z","end":"2026-02-12T09:00:22.946467Z","steps":["trace[1859846581] 'read index received' (duration: 386.10891ms)","trace[1859846581] 'applied index is now lower than readState.Index' (duration: 2.151328ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:22.946639Z","caller":"traceutil/trace.go:171","msg":"trace[1158845144] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"394.444356ms","start":"2026-02-12T09:00:22.552189Z","end":"2026-02-12T09:00:22.946633Z","steps":["trace[1158845144] 'process raft request' (duration: 394.2096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.946686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.552176Z","time spent":"394.486056ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T09:00:22.946785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.580205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:22.946809Z","caller":"traceutil/trace.go:171","msg":"trace[672366868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"388.604055ms","start":"2026-02-12T09:00:22.558199Z","end":"2026-02-12T09:00:22.946803Z","steps":["trace[672366868] 'agreement among raft nodes before linearized reading' (duration: 388.566964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.946831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.558175Z","time spent":"388.652046ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:22.946956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.925018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T09:00:22.946979Z","caller":"traceutil/trace.go:171","msg":"trace[1996702556] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:955; }","duration":"350.9728ms","start":"2026-02-12T09:00:22.596001Z","end":"2026-02-12T09:00:22.946973Z","steps":["trace[1996702556] 'agreement among raft nodes before linearized reading' (duration: 350.930189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.595988Z","time spent":"351.0076ms","remote":"127.0.0.1:47162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-12T09:00:22.947177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.007431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T09:00:22.949965Z","caller":"traceutil/trace.go:171","msg":"trace[1649891937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"303.806724ms","start":"2026-02-12T09:00:22.646128Z","end":"2026-02-12T09:00:22.949934Z","steps":["trace[1649891937] 'agreement among raft nodes before linearized reading' (duration: 300.993861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.950001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.646108Z","time spent":"303.884236ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:22.948721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.257401ms","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-02-12T09:00:22.948796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.286085ms","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-02-12T09:00:22.951977Z","caller":"traceutil/trace.go:171","msg":"trace[357581720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"307.485365ms","start":"2026-02-12T09:00:22.644483Z","end":"2026-02-12T09:00:22.951968Z","steps":["trace[357581720] 'agreement among raft nodes before linearized reading' (duration: 304.254853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.952007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.644469Z","time spent":"307.528437ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T09:00:22.952056Z","caller":"traceutil/trace.go:171","msg":"trace[1669365760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"306.606565ms","start":"2026-02-12T09:00:22.645444Z","end":"2026-02-12T09:00:22.952051Z","steps":["trace[1669365760] 'agreement among raft nodes before linearized reading' (duration: 303.126328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:22.952072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:22.64544Z","time spent":"306.627806ms","remote":"127.0.0.1:47078","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-02-12T09:00:23.07928Z","caller":"traceutil/trace.go:171","msg":"trace[1151499449] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"122.997911ms","start":"2026-02-12T09:00:22.956255Z","end":"2026-02-12T09:00:23.079253Z","steps":["trace[1151499449] 'process raft request' (duration: 64.505162ms)","trace[1151499449] 'compare' (duration: 58.315695ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:23.086786Z","caller":"traceutil/trace.go:171","msg":"trace[82266635] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"130.412194ms","start":"2026-02-12T09:00:22.956304Z","end":"2026-02-12T09:00:23.086716Z","steps":["trace[82266635] 'process raft request' (duration: 130.315312ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:23.086854Z","caller":"traceutil/trace.go:171","msg":"trace[1867486715] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"100.535592ms","start":"2026-02-12T09:00:22.986308Z","end":"2026-02-12T09:00:23.086844Z","steps":["trace[1867486715] 'read index received' (duration: 34.461625ms)","trace[1867486715] 'applied index is now lower than readState.Index' (duration: 66.073317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:00:23.087256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.898129ms","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-02-12T09:00:23.087294Z","caller":"traceutil/trace.go:171","msg":"trace[455589343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"101.008973ms","start":"2026-02-12T09:00:22.986276Z","end":"2026-02-12T09:00:23.087285Z","steps":["trace[455589343] 'agreement among raft nodes before linearized reading' (duration: 100.841289ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:27.172998Z","caller":"traceutil/trace.go:171","msg":"trace[191770503] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"191.885457ms","start":"2026-02-12T09:00:26.981098Z","end":"2026-02-12T09:00:27.172984Z","steps":["trace[191770503] 'process raft request' (duration: 191.54217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:28.999564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.750256ms","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":"warn","ts":"2026-02-12T09:00:28.999871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.437256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:29.000377Z","caller":"traceutil/trace.go:171","msg":"trace[1210335066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"434.199023ms","start":"2026-02-12T09:00:28.566144Z","end":"2026-02-12T09:00:29.000343Z","steps":["trace[1210335066] 'range keys from in-memory index tree' (duration: 433.401385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:29.000598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:28.566137Z","time spent":"434.443878ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T09:00:29.001688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.14343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-02-12T09:00:29.001881Z","caller":"traceutil/trace.go:171","msg":"trace[34301438] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:980; }","duration":"415.340184ms","start":"2026-02-12T09:00:28.586527Z","end":"2026-02-12T09:00:29.001868Z","steps":["trace[34301438] 'range keys from in-memory index tree' (duration: 414.995936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:29.002002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:28.58649Z","time spent":"415.498547ms","remote":"127.0.0.1:47014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4763,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-12T09:00:29.002379Z","caller":"traceutil/trace.go:171","msg":"trace[1004627618] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:980; }","duration":"169.893449ms","start":"2026-02-12T09:00:28.829748Z","end":"2026-02-12T09:00:28.999641Z","steps":["trace[1004627618] 'count revisions from in-memory index tree' (duration: 169.627484ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.24322Z","caller":"traceutil/trace.go:171","msg":"trace[187875569] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"233.153862ms","start":"2026-02-12T09:00:29.010051Z","end":"2026-02-12T09:00:29.243205Z","steps":["trace[187875569] 'process raft request' (duration: 233.06063ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.444781Z","caller":"traceutil/trace.go:171","msg":"trace[1575097010] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"175.196867ms","start":"2026-02-12T09:00:29.269524Z","end":"2026-02-12T09:00:29.444721Z","steps":["trace[1575097010] 'process raft request' (duration: 174.771168ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.446852Z","caller":"traceutil/trace.go:171","msg":"trace[2045514415] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"172.100058ms","start":"2026-02-12T09:00:29.274739Z","end":"2026-02-12T09:00:29.446839Z","steps":["trace[2045514415] 'process raft request' (duration: 171.591536ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.447907Z","caller":"traceutil/trace.go:171","msg":"trace[2115508950] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"177.084768ms","start":"2026-02-12T09:00:29.270812Z","end":"2026-02-12T09:00:29.447897Z","steps":["trace[2115508950] 'process raft request' (duration: 173.820746ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.448647Z","caller":"traceutil/trace.go:171","msg":"trace[120464350] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"175.986404ms","start":"2026-02-12T09:00:29.272638Z","end":"2026-02-12T09:00:29.448625Z","steps":["trace[120464350] 'process raft request' (duration: 173.638952ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.449996Z","caller":"traceutil/trace.go:171","msg":"trace[269828503] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"177.013446ms","start":"2026-02-12T09:00:29.27295Z","end":"2026-02-12T09:00:29.449963Z","steps":["trace[269828503] 'process raft request' (duration: 173.350564ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.455123Z","caller":"traceutil/trace.go:171","msg":"trace[757324782] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"173.452688ms","start":"2026-02-12T09:00:29.281659Z","end":"2026-02-12T09:00:29.455112Z","steps":["trace[757324782] 'process raft request' (duration: 173.373396ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.598433Z","caller":"traceutil/trace.go:171","msg":"trace[1149091285] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"130.538276ms","start":"2026-02-12T09:00:29.467876Z","end":"2026-02-12T09:00:29.598414Z","steps":["trace[1149091285] 'process raft request' (duration: 130.391293ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:29.599397Z","caller":"traceutil/trace.go:171","msg":"trace[719364472] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"131.094498ms","start":"2026-02-12T09:00:29.46828Z","end":"2026-02-12T09:00:29.599374Z","steps":["trace[719364472] 'process raft request' (duration: 130.085196ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:31.403352Z","caller":"traceutil/trace.go:171","msg":"trace[1772093909] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"117.687731ms","start":"2026-02-12T09:00:31.285648Z","end":"2026-02-12T09:00:31.403335Z","steps":["trace[1772093909] 'process raft request' (duration: 117.594159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:31.850715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.224517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T09:00:31.850784Z","caller":"traceutil/trace.go:171","msg":"trace[1691064465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"381.33181ms","start":"2026-02-12T09:00:31.469437Z","end":"2026-02-12T09:00:31.850769Z","steps":["trace[1691064465] 'range keys from in-memory index tree' (duration: 381.113854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:31.850817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:31.469428Z","time spent":"381.381271ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:31.850995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.928483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-12T09:00:31.850989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.430878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:31.851043Z","caller":"traceutil/trace.go:171","msg":"trace[376712233] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:993; }","duration":"384.519089ms","start":"2026-02-12T09:00:31.466511Z","end":"2026-02-12T09:00:31.85103Z","steps":["trace[376712233] 'count revisions from in-memory index tree' (duration: 384.377807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:31.851072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:31.4665Z","time spent":"384.564571ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-12T09:00:31.851141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.08373ms","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-02-12T09:00:31.85116Z","caller":"traceutil/trace.go:171","msg":"trace[1158656703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"388.12731ms","start":"2026-02-12T09:00:31.463028Z","end":"2026-02-12T09:00:31.851155Z","steps":["trace[1158656703] 'range keys from in-memory index tree' (duration: 388.015818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:31.851177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:31.463016Z","time spent":"388.156382ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T09:00:31.851013Z","caller":"traceutil/trace.go:171","msg":"trace[1210921313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"381.964983ms","start":"2026-02-12T09:00:31.469042Z","end":"2026-02-12T09:00:31.851007Z","steps":["trace[1210921313] 'range keys from in-memory index tree' (duration: 381.865711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:31.851204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:31.469033Z","time spent":"382.165137ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T09:00:31.851272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.859307ms","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-02-12T09:00:31.85129Z","caller":"traceutil/trace.go:171","msg":"trace[1433595062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"402.899238ms","start":"2026-02-12T09:00:31.448384Z","end":"2026-02-12T09:00:31.851283Z","steps":["trace[1433595062] 'range keys from in-memory index tree' (duration: 402.786435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:31.851305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:31.448372Z","time spent":"402.930388ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T09:00:32.04074Z","caller":"traceutil/trace.go:171","msg":"trace[1609830018] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"111.29003ms","start":"2026-02-12T09:00:31.929432Z","end":"2026-02-12T09:00:32.040722Z","steps":["trace[1609830018] 'process raft request' (duration: 105.976272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:33.373433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.307052ms","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":"info","ts":"2026-02-12T09:00:33.373532Z","caller":"traceutil/trace.go:171","msg":"trace[1170843139] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:999; }","duration":"474.411814ms","start":"2026-02-12T09:00:32.899066Z","end":"2026-02-12T09:00:33.373478Z","steps":["trace[1170843139] 'count revisions from in-memory index tree' (duration: 474.182819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:33.373588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:32.899048Z","time spent":"474.523786ms","remote":"127.0.0.1:49286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-12T09:00:33.740656Z","caller":"traceutil/trace.go:171","msg":"trace[1855439667] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"181.044036ms","start":"2026-02-12T09:00:33.559591Z","end":"2026-02-12T09:00:33.740635Z","steps":["trace[1855439667] 'read index received' (duration: 180.842712ms)","trace[1855439667] 'applied index is now lower than readState.Index' (duration: 200.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:00:33.740845Z","caller":"traceutil/trace.go:171","msg":"trace[1953827734] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"330.258045ms","start":"2026-02-12T09:00:33.410571Z","end":"2026-02-12T09:00:33.740829Z","steps":["trace[1953827734] 'process raft request' (duration: 329.898918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:33.740932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.250041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T09:00:33.740947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:33.410553Z","time spent":"330.337688ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T09:00:33.741056Z","caller":"traceutil/trace.go:171","msg":"trace[1341153676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"181.416516ms","start":"2026-02-12T09:00:33.559562Z","end":"2026-02-12T09:00:33.740978Z","steps":["trace[1341153676] 'agreement among raft nodes before linearized reading' (duration: 181.253291ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:00:39.204042Z","caller":"traceutil/trace.go:171","msg":"trace[1081046069] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"286.956186ms","start":"2026-02-12T09:00:38.917063Z","end":"2026-02-12T09:00:39.204019Z","steps":["trace[1081046069] 'process raft request' (duration: 284.004871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:45.203268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.750848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:00:45.232915Z","caller":"traceutil/trace.go:171","msg":"trace[47350495] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1066; }","duration":"585.423407ms","start":"2026-02-12T09:00:44.647471Z","end":"2026-02-12T09:00:45.232894Z","steps":["trace[47350495] 'range keys from in-memory index tree' (duration: 555.710667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:00:45.232999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T09:00:44.647455Z","time spent":"585.519369ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-12T09:00:49.934195Z","caller":"traceutil/trace.go:171","msg":"trace[921214321] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"117.747592ms","start":"2026-02-12T09:00:49.816431Z","end":"2026-02-12T09:00:49.934178Z","steps":["trace[921214321] 'process raft request' (duration: 117.64866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:01:06.436364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.872829ms","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-02-12T09:01:06.43643Z","caller":"traceutil/trace.go:171","msg":"trace[2069679391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"119.991812ms","start":"2026-02-12T09:01:06.316419Z","end":"2026-02-12T09:01:06.436411Z","steps":["trace[2069679391] 'range keys from in-memory index tree' (duration: 109.363256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:01:06.436672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.449043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T09:01:06.436761Z","caller":"traceutil/trace.go:171","msg":"trace[100536912] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"120.193736ms","start":"2026-02-12T09:01:06.316549Z","end":"2026-02-12T09:01:06.436743Z","steps":["trace[100536912] 'process raft request' (duration: 17.59955ms)","trace[100536912] 'compare' (duration: 102.271879ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:01:12.61451Z","caller":"traceutil/trace.go:171","msg":"trace[915528539] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"148.723069ms","start":"2026-02-12T09:01:12.465764Z","end":"2026-02-12T09:01:12.614487Z","steps":["trace[915528539] 'process raft request' (duration: 144.166208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:01:44.533561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.952336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-12T09:01:44.533646Z","caller":"traceutil/trace.go:171","msg":"trace[1171733215] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1315; }","duration":"131.053118ms","start":"2026-02-12T09:01:44.402575Z","end":"2026-02-12T09:01:44.533628Z","steps":["trace[1171733215] 'range keys from in-memory index tree' (duration: 130.798353ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:01:44.654099Z","caller":"traceutil/trace.go:171","msg":"trace[846170437] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"115.488624ms","start":"2026-02-12T09:01:44.538589Z","end":"2026-02-12T09:01:44.654077Z","steps":["trace[846170437] 'process raft request' (duration: 115.334421ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:01:44.662047Z","caller":"traceutil/trace.go:171","msg":"trace[1871624005] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1377; }","duration":"100.272978ms","start":"2026-02-12T09:01:44.561754Z","end":"2026-02-12T09:01:44.662027Z","steps":["trace[1871624005] 'read index received' (duration: 92.289801ms)","trace[1871624005] 'applied index is now lower than readState.Index' (duration: 7.982477ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:01:44.662277Z","caller":"traceutil/trace.go:171","msg":"trace[2011176511] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"123.573102ms","start":"2026-02-12T09:01:44.538695Z","end":"2026-02-12T09:01:44.662268Z","steps":["trace[2011176511] 'process raft request' (duration: 123.185824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:01:44.662431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.665506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T09:01:44.662459Z","caller":"traceutil/trace.go:171","msg":"trace[1552537582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"100.705317ms","start":"2026-02-12T09:01:44.561747Z","end":"2026-02-12T09:01:44.662453Z","steps":["trace[1552537582] 'agreement among raft nodes before linearized reading' (duration: 100.650556ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T09:03:45.727535Z","caller":"traceutil/trace.go:171","msg":"trace[98248968] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"100.100645ms","start":"2026-02-12T09:03:45.627412Z","end":"2026-02-12T09:03:45.727513Z","steps":["trace[98248968] 'process raft request' (duration: 99.688855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:03:46.009602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.27127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T09:03:46.009693Z","caller":"traceutil/trace.go:171","msg":"trace[630650911] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"204.552406ms","start":"2026-02-12T09:03:45.805126Z","end":"2026-02-12T09:03:46.009678Z","steps":["trace[630650911] 'read index received' (duration: 69.067432ms)","trace[630650911] 'applied index is now lower than readState.Index' (duration: 135.483984ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T09:03:46.009777Z","caller":"traceutil/trace.go:171","msg":"trace[1150074017] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"277.35041ms","start":"2026-02-12T09:03:45.732412Z","end":"2026-02-12T09:03:46.009762Z","steps":["trace[1150074017] 'process raft request' (duration: 141.849715ms)","trace[1150074017] 'compare' (duration: 135.192558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T09:03:46.01004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.919235ms","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-02-12T09:03:46.010069Z","caller":"traceutil/trace.go:171","msg":"trace[268113438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1837; }","duration":"205.116788ms","start":"2026-02-12T09:03:45.804943Z","end":"2026-02-12T09:03:46.01006Z","steps":["trace[268113438] 'agreement among raft nodes before linearized reading' (duration: 204.984455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:04:04.295555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.064188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:coredns\" ","response":"range_response_count:1 size:415"} {"level":"info","ts":"2026-02-12T09:04:04.296283Z","caller":"traceutil/trace.go:171","msg":"trace[1068678250] range","detail":"{range_begin:/registry/clusterrolebindings/system:coredns; range_end:; response_count:1; response_revision:1925; }","duration":"136.821054ms","start":"2026-02-12T09:04:04.15944Z","end":"2026-02-12T09:04:04.296261Z","steps":["trace[1068678250] 'range keys from in-memory index tree' (duration: 135.975956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T09:04:04.295707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.879792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-02-12T09:04:04.296711Z","caller":"traceutil/trace.go:171","msg":"trace[2132344271] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1925; }","duration":"140.897243ms","start":"2026-02-12T09:04:04.155789Z","end":"2026-02-12T09:04:04.296686Z","steps":["trace[2132344271] 'range keys from in-memory index tree' (duration: 139.790239ms)"],"step_count":1}