{"level":"warn","ts":"2026-03-20T05:32:49.661635Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:32:49.661721Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-20T05:32:49.661783Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:32:49.661802Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-20T05:32:49.661824Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T05:32:49.662436Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-03-20T05:32:49.662522Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-20T05:32:49.667127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.372418ms"} {"level":"info","ts":"2026-03-20T05:32:49.67751Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-03-20T05:32:49.677614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:32:49.677649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-03-20T05:32:49.677673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:32:49.677691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-03-20T05:32:49.677749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-03-20T05:32:49.686711Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:32:49.688009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:32:49.689941Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-20T05:32:49.693505Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:32:49.693816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:32:49.693807Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:32:49.693892Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:32:49.693906Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:32:49.697244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-03-20T05:32:49.697363Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-20T05:32:49.704513Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T05:32:49.704627Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-20T05:32:49.704694Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-20T05:32:49.705446Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:32:49.705501Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:32:50.478498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:32:50.478579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:32:50.478602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-03-20T05:32:50.478622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:32:50.478634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-20T05:32:50.47865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-03-20T05:32:50.478692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-20T05:32:50.47953Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:50.480307Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:32:50.480347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:32:50.480363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:32:50.480623Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:32:50.480674Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:32:50.480826Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:50.480919Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:50.480951Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:50.482813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T05:32:50.483933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"warn","ts":"2026-03-20T05:32:55.220344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.831185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:32:55.220434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.400557963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:32:55.220474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017366522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:32:55.220397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.503519303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:32:55.220559Z","caller":"traceutil/trace.go:171","msg":"trace[712555064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:71; }","duration":"1.017470786s","start":"2026-03-20T05:32:54.203053Z","end":"2026-03-20T05:32:55.220524Z","steps":["trace[712555064] 'range keys from in-memory index tree' (duration: 1.0172784s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:55.220559Z","caller":"traceutil/trace.go:171","msg":"trace[243823480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"2.400743889s","start":"2026-03-20T05:32:52.819769Z","end":"2026-03-20T05:32:55.220513Z","steps":["trace[243823480] 'range keys from in-memory index tree' (duration: 2.400420129s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:55.220578Z","caller":"traceutil/trace.go:171","msg":"trace[895270638] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"2.503769481s","start":"2026-03-20T05:32:52.716793Z","end":"2026-03-20T05:32:55.220562Z","steps":["trace[895270638] 'range keys from in-memory index tree' (duration: 2.503445511s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:55.220627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:52.716774Z","time spent":"2.503842082s","remote":"127.0.0.1:39302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-20T05:32:55.220627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:54.203032Z","time spent":"1.017579688s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:32:55.220629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:52.819745Z","time spent":"2.400872434s","remote":"127.0.0.1:38960","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-03-20T05:32:55.220419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.503184272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:32:55.220674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321269731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:32:55.22075Z","caller":"traceutil/trace.go:171","msg":"trace[1404168285] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"2.503578175s","start":"2026-03-20T05:32:52.717138Z","end":"2026-03-20T05:32:55.220717Z","steps":["trace[1404168285] 'range keys from in-memory index tree' (duration: 2.50311663s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:55.220792Z","caller":"traceutil/trace.go:171","msg":"trace[2083808262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"1.321585471s","start":"2026-03-20T05:32:53.899173Z","end":"2026-03-20T05:32:55.220758Z","steps":["trace[2083808262] 'range keys from in-memory index tree' (duration: 1.321192639s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:55.220814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:52.717128Z","time spent":"2.503661887s","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-20T05:32:55.220849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:53.899154Z","time spent":"1.321682134s","remote":"127.0.0.1:38960","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-03-20T05:32:55.220522Z","caller":"traceutil/trace.go:171","msg":"trace[1609590012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"400.074074ms","start":"2026-03-20T05:32:54.820421Z","end":"2026-03-20T05:32:55.220495Z","steps":["trace[1609590012] 'range keys from in-memory index tree' (duration: 399.753333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:55.220915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:54.820401Z","time spent":"400.504197ms","remote":"127.0.0.1:38944","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-03-20T05:32:55.372186Z","caller":"traceutil/trace.go:171","msg":"trace[608861975] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"147.095486ms","start":"2026-03-20T05:32:55.225065Z","end":"2026-03-20T05:32:55.37216Z","steps":["trace[608861975] 'read index received' (duration: 146.955952ms)","trace[608861975] 'applied index is now lower than readState.Index' (duration: 138.244µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:55.372234Z","caller":"traceutil/trace.go:171","msg":"trace[2092904553] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"147.187569ms","start":"2026-03-20T05:32:55.225016Z","end":"2026-03-20T05:32:55.372203Z","steps":["trace[2092904553] 'process raft request' (duration: 146.994313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:55.372508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.410896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:32:55.372599Z","caller":"traceutil/trace.go:171","msg":"trace[1025302310] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"147.57388ms","start":"2026-03-20T05:32:55.225005Z","end":"2026-03-20T05:32:55.372579Z","steps":["trace[1025302310] 'agreement among raft nodes before linearized reading' (duration: 147.303612ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:55.374618Z","caller":"traceutil/trace.go:171","msg":"trace[1188546674] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"149.425519ms","start":"2026-03-20T05:32:55.225164Z","end":"2026-03-20T05:32:55.37459Z","steps":["trace[1188546674] 'process raft request' (duration: 149.282565ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:21.796168Z","caller":"traceutil/trace.go:171","msg":"trace[705791005] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"212.182316ms","start":"2026-03-20T05:33:21.583963Z","end":"2026-03-20T05:33:21.796145Z","steps":["trace[705791005] 'read index received' (duration: 212.043742ms)","trace[705791005] 'applied index is now lower than readState.Index' (duration: 137.564µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:21.796216Z","caller":"traceutil/trace.go:171","msg":"trace[64244637] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"282.102549ms","start":"2026-03-20T05:33:21.514094Z","end":"2026-03-20T05:33:21.796197Z","steps":["trace[64244637] 'process raft request' (duration: 281.897793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:21.796371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.361782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:33:21.796409Z","caller":"traceutil/trace.go:171","msg":"trace[536931576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"212.472745ms","start":"2026-03-20T05:33:21.583925Z","end":"2026-03-20T05:33:21.796398Z","steps":["trace[536931576] 'agreement among raft nodes before linearized reading' (duration: 212.363762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:21.796415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.628169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:33:21.796502Z","caller":"traceutil/trace.go:171","msg":"trace[233288178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:461; }","duration":"133.787465ms","start":"2026-03-20T05:33:21.662693Z","end":"2026-03-20T05:33:21.79648Z","steps":["trace[233288178] 'agreement among raft nodes before linearized reading' (duration: 133.608959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.056359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.784037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:22.056564Z","caller":"traceutil/trace.go:171","msg":"trace[971204383] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"231.653912ms","start":"2026-03-20T05:33:21.824894Z","end":"2026-03-20T05:33:22.056548Z","steps":["trace[971204383] 'read index received' (duration: 35.055069ms)","trace[971204383] 'applied index is now lower than readState.Index' (duration: 196.597983ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:22.056658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.754636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-20T05:33:22.056646Z","caller":"traceutil/trace.go:171","msg":"trace[1468848328] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"255.950481ms","start":"2026-03-20T05:33:21.800669Z","end":"2026-03-20T05:33:22.056619Z","steps":["trace[1468848328] 'process raft request' (duration: 255.821367ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:22.056693Z","caller":"traceutil/trace.go:171","msg":"trace[247840843] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:463; }","duration":"231.794257ms","start":"2026-03-20T05:33:21.82489Z","end":"2026-03-20T05:33:22.056684Z","steps":["trace[247840843] 'agreement among raft nodes before linearized reading' (duration: 231.718715ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:22.056709Z","caller":"traceutil/trace.go:171","msg":"trace[39223427] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"256.159218ms","start":"2026-03-20T05:33:21.800521Z","end":"2026-03-20T05:33:22.05668Z","steps":["trace[39223427] 'process raft request' (duration: 59.462962ms)","trace[39223427] 'compare' (duration: 195.630462ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:23.303157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.263843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-lc2wt\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-03-20T05:33:23.303254Z","caller":"traceutil/trace.go:171","msg":"trace[351776423] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-lc2wt; range_end:; response_count:1; response_revision:488; }","duration":"275.416007ms","start":"2026-03-20T05:33:23.027823Z","end":"2026-03-20T05:33:23.30324Z","steps":["trace[351776423] 'range keys from in-memory index tree' (duration: 275.104658ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.391469Z","caller":"traceutil/trace.go:171","msg":"trace[61147853] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"213.749316ms","start":"2026-03-20T05:33:23.177691Z","end":"2026-03-20T05:33:23.39144Z","steps":["trace[61147853] 'process raft request' (duration: 213.602431ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.478755Z","caller":"traceutil/trace.go:171","msg":"trace[1882688799] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"104.505138ms","start":"2026-03-20T05:33:23.374227Z","end":"2026-03-20T05:33:23.478732Z","steps":["trace[1882688799] 'process raft request' (duration: 104.398275ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.478867Z","caller":"traceutil/trace.go:171","msg":"trace[488999998] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"167.529163ms","start":"2026-03-20T05:33:23.311285Z","end":"2026-03-20T05:33:23.478814Z","steps":["trace[488999998] 'process raft request' (duration: 167.171911ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.653243Z","caller":"traceutil/trace.go:171","msg":"trace[947228491] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"166.226062ms","start":"2026-03-20T05:33:23.486931Z","end":"2026-03-20T05:33:23.653157Z","steps":["trace[947228491] 'read index received' (duration: 143.928076ms)","trace[947228491] 'applied index is now lower than readState.Index' (duration: 22.296416ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:23.653443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.48981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-lc2wt\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-03-20T05:33:23.65353Z","caller":"traceutil/trace.go:171","msg":"trace[796131446] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-lc2wt; range_end:; response_count:1; response_revision:492; }","duration":"166.626064ms","start":"2026-03-20T05:33:23.486885Z","end":"2026-03-20T05:33:23.653511Z","steps":["trace[796131446] 'agreement among raft nodes before linearized reading' (duration: 166.452788ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.653563Z","caller":"traceutil/trace.go:171","msg":"trace[1479395935] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"255.368713ms","start":"2026-03-20T05:33:23.398182Z","end":"2026-03-20T05:33:23.653551Z","steps":["trace[1479395935] 'process raft request' (duration: 232.702806ms)","trace[1479395935] 'compare' (duration: 22.025967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:24.253209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680549689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:24.674779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.770329Z","time spent":"904.442337ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-20T05:33:24.676394Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016788153s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:33:24.676807Z","caller":"traceutil/trace.go:171","msg":"trace[2021025805] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.017275128s","start":"2026-03-20T05:33:23.65951Z","end":"2026-03-20T05:33:24.676785Z","steps":["trace[2021025805] 'process raft request' (duration: 1.017129314s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.676934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.659488Z","time spent":"1.017371552s","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4679,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:24.681317Z","caller":"traceutil/trace.go:171","msg":"trace[246568454] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.016199384s","start":"2026-03-20T05:33:23.665098Z","end":"2026-03-20T05:33:24.681297Z","steps":["trace[246568454] 'process raft request' (duration: 1.016013578s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.681469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.665054Z","time spent":"1.016327388s","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:24.681537Z","caller":"traceutil/trace.go:171","msg":"trace[1705276896] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:509; }","duration":"929.21044ms","start":"2026-03-20T05:33:23.752247Z","end":"2026-03-20T05:33:24.681458Z","steps":["trace[1705276896] 'read index received' (duration: 924.381027ms)","trace[1705276896] 'applied index is now lower than readState.Index' (duration: 4.827563ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:24.681625Z","caller":"traceutil/trace.go:171","msg":"trace[1665711966] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.01540348s","start":"2026-03-20T05:33:23.666192Z","end":"2026-03-20T05:33:24.681595Z","steps":["trace[1665711966] 'process raft request' (duration: 1.015072679s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.681723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.666169Z","time spent":"1.015507622s","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:24.681826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.93867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:24.681761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.49103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:24.681854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.179635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-20T05:33:24.681861Z","caller":"traceutil/trace.go:171","msg":"trace[1087701219] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:495; }","duration":"908.997461ms","start":"2026-03-20T05:33:23.772855Z","end":"2026-03-20T05:33:24.681852Z","steps":["trace[1087701219] 'agreement among raft nodes before linearized reading' (duration: 908.928829ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:24.681885Z","caller":"traceutil/trace.go:171","msg":"trace[1268366904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"929.657075ms","start":"2026-03-20T05:33:23.752211Z","end":"2026-03-20T05:33:24.681868Z","steps":["trace[1268366904] 'agreement among raft nodes before linearized reading' (duration: 929.49249ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:24.681897Z","caller":"traceutil/trace.go:171","msg":"trace[2135728836] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:495; }","duration":"508.293558ms","start":"2026-03-20T05:33:24.173592Z","end":"2026-03-20T05:33:24.681886Z","steps":["trace[2135728836] 'agreement among raft nodes before linearized reading' (duration: 508.221336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.681906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.772838Z","time spent":"909.061023ms","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:24.681934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:24.173573Z","time spent":"508.3511ms","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:24.681993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.257187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:24.68202Z","caller":"traceutil/trace.go:171","msg":"trace[1341630937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:495; }","duration":"708.315029ms","start":"2026-03-20T05:33:23.973697Z","end":"2026-03-20T05:33:24.682012Z","steps":["trace[1341630937] 'agreement among raft nodes before linearized reading' (duration: 708.275938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.682038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.703537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T05:33:24.682063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.973679Z","time spent":"708.378251ms","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-20T05:33:24.682096Z","caller":"traceutil/trace.go:171","msg":"trace[1006587319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"621.791461ms","start":"2026-03-20T05:33:24.060272Z","end":"2026-03-20T05:33:24.682063Z","steps":["trace[1006587319] 'agreement among raft nodes before linearized reading' (duration: 621.691427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.682135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:24.060252Z","time spent":"621.875074ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:33:24.682219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.557093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:33:24.682244Z","caller":"traceutil/trace.go:171","msg":"trace[2033503876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"621.598924ms","start":"2026-03-20T05:33:24.060639Z","end":"2026-03-20T05:33:24.682238Z","steps":["trace[2033503876] 'agreement among raft nodes before linearized reading' (duration: 621.540342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.682262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:24.060631Z","time spent":"621.626926ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:24.682155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.007237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-20T05:33:24.682341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.400843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-03-20T05:33:24.682352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.599905ms","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":"warn","ts":"2026-03-20T05:33:24.681932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.752193Z","time spent":"929.725507ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:33:24.682397Z","caller":"traceutil/trace.go:171","msg":"trace[668085424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:495; }","duration":"294.663827ms","start":"2026-03-20T05:33:24.387724Z","end":"2026-03-20T05:33:24.682388Z","steps":["trace[668085424] 'agreement among raft nodes before linearized reading' (duration: 294.605605ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:24.682483Z","caller":"traceutil/trace.go:171","msg":"trace[1500129326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"882.267915ms","start":"2026-03-20T05:33:23.800097Z","end":"2026-03-20T05:33:24.682365Z","steps":["trace[1500129326] 'agreement among raft nodes before linearized reading' (duration: 882.014117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.682574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:23.800077Z","time spent":"882.469802ms","remote":"127.0.0.1:39216","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":"info","ts":"2026-03-20T05:33:24.682385Z","caller":"traceutil/trace.go:171","msg":"trace[1177273475] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:495; }","duration":"309.474795ms","start":"2026-03-20T05:33:24.372897Z","end":"2026-03-20T05:33:24.682372Z","steps":["trace[1177273475] 'agreement among raft nodes before linearized reading' (duration: 309.390313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.683314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:24.372883Z","time spent":"310.362834ms","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:24.691835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.359526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-20T05:33:24.691875Z","caller":"traceutil/trace.go:171","msg":"trace[1656429862] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:495; }","duration":"118.46165ms","start":"2026-03-20T05:33:24.573405Z","end":"2026-03-20T05:33:24.691867Z","steps":["trace[1656429862] 'agreement among raft nodes before linearized reading' (duration: 108.743572ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.607282Z","caller":"traceutil/trace.go:171","msg":"trace[677325332] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"243.441476ms","start":"2026-03-20T05:33:27.363813Z","end":"2026-03-20T05:33:27.607255Z","steps":["trace[677325332] 'read index received' (duration: 243.25768ms)","trace[677325332] 'applied index is now lower than readState.Index' (duration: 182.226µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:27.607332Z","caller":"traceutil/trace.go:171","msg":"trace[1675368804] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"264.239614ms","start":"2026-03-20T05:33:27.34306Z","end":"2026-03-20T05:33:27.607299Z","steps":["trace[1675368804] 'process raft request' (duration: 264.002626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.607484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.619911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.607555Z","caller":"traceutil/trace.go:171","msg":"trace[1693243783] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:522; }","duration":"243.757846ms","start":"2026-03-20T05:33:27.363782Z","end":"2026-03-20T05:33:27.607539Z","steps":["trace[1693243783] 'agreement among raft nodes before linearized reading' (duration: 243.603671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.607564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.456246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.607597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.715245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.607615Z","caller":"traceutil/trace.go:171","msg":"trace[812722744] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:522; }","duration":"194.541889ms","start":"2026-03-20T05:33:27.413063Z","end":"2026-03-20T05:33:27.607605Z","steps":["trace[812722744] 'agreement among raft nodes before linearized reading' (duration: 194.464726ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.607653Z","caller":"traceutil/trace.go:171","msg":"trace[593401366] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:522; }","duration":"225.778707ms","start":"2026-03-20T05:33:27.381847Z","end":"2026-03-20T05:33:27.607626Z","steps":["trace[593401366] 'agreement among raft nodes before linearized reading' (duration: 225.727956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.607764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.94113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.60782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.125955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.607486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.518619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.607844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.725084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.60785Z","caller":"traceutil/trace.go:171","msg":"trace[399248959] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:522; }","duration":"194.205597ms","start":"2026-03-20T05:33:27.413636Z","end":"2026-03-20T05:33:27.607842Z","steps":["trace[399248959] 'agreement among raft nodes before linearized reading' (duration: 194.150505ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.607904Z","caller":"traceutil/trace.go:171","msg":"trace[1572482966] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:522; }","duration":"194.799806ms","start":"2026-03-20T05:33:27.413086Z","end":"2026-03-20T05:33:27.607886Z","steps":["trace[1572482966] 'agreement among raft nodes before linearized reading' (duration: 194.733814ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.607884Z","caller":"traceutil/trace.go:171","msg":"trace[140451956] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:522; }","duration":"243.985534ms","start":"2026-03-20T05:33:27.363869Z","end":"2026-03-20T05:33:27.607855Z","steps":["trace[140451956] 'agreement among raft nodes before linearized reading' (duration: 243.55226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.607743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.843789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.607556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.275281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.608025Z","caller":"traceutil/trace.go:171","msg":"trace[978345454] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:522; }","duration":"226.189839ms","start":"2026-03-20T05:33:27.381823Z","end":"2026-03-20T05:33:27.608012Z","steps":["trace[978345454] 'agreement among raft nodes before linearized reading' (duration: 225.813548ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.608038Z","caller":"traceutil/trace.go:171","msg":"trace[175149020] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:522; }","duration":"225.767187ms","start":"2026-03-20T05:33:27.382259Z","end":"2026-03-20T05:33:27.608026Z","steps":["trace[175149020] 'agreement among raft nodes before linearized reading' (duration: 225.272211ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.607846Z","caller":"traceutil/trace.go:171","msg":"trace[1263362251] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:522; }","duration":"243.099685ms","start":"2026-03-20T05:33:27.364724Z","end":"2026-03-20T05:33:27.607824Z","steps":["trace[1263362251] 'agreement among raft nodes before linearized reading' (duration: 242.922619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:28.357662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.832088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:28.357751Z","caller":"traceutil/trace.go:171","msg":"trace[492116781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"605.985022ms","start":"2026-03-20T05:33:27.751746Z","end":"2026-03-20T05:33:28.357731Z","steps":["trace[492116781] 'range keys from in-memory index tree' (duration: 605.767295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:28.357731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.39827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:28.357796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.817594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:28.357806Z","caller":"traceutil/trace.go:171","msg":"trace[578907521] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:522; }","duration":"603.488604ms","start":"2026-03-20T05:33:27.7543Z","end":"2026-03-20T05:33:28.357788Z","steps":["trace[578907521] 'range keys from in-memory index tree' (duration: 603.342189ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:28.357849Z","caller":"traceutil/trace.go:171","msg":"trace[1490671257] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:522; }","duration":"603.873616ms","start":"2026-03-20T05:33:27.753954Z","end":"2026-03-20T05:33:28.357828Z","steps":["trace[1490671257] 'range keys from in-memory index tree' (duration: 603.794542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:28.357852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:27.754296Z","time spent":"603.544705ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-03-20T05:33:28.357887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:27.75395Z","time spent":"603.925117ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-03-20T05:33:28.357715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.587186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:28.357936Z","caller":"traceutil/trace.go:171","msg":"trace[1098651853] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:522; }","duration":"603.853725ms","start":"2026-03-20T05:33:27.754063Z","end":"2026-03-20T05:33:28.357917Z","steps":["trace[1098651853] 'count revisions from in-memory index tree' (duration: 603.494343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:28.357798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:27.751734Z","time spent":"606.054595ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:28.357998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:27.754019Z","time spent":"603.956249ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:33:29.097921Z","caller":"traceutil/trace.go:171","msg":"trace[942398383] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"473.56817ms","start":"2026-03-20T05:33:28.624327Z","end":"2026-03-20T05:33:29.097895Z","steps":["trace[942398383] 'process raft request' (duration: 473.314442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.098078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.624302Z","time spent":"473.697214ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:29.207726Z","caller":"traceutil/trace.go:171","msg":"trace[775386879] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:540; }","duration":"503.57139ms","start":"2026-03-20T05:33:28.704129Z","end":"2026-03-20T05:33:29.2077Z","steps":["trace[775386879] 'read index received' (duration: 393.609969ms)","trace[775386879] 'applied index is now lower than readState.Index' (duration: 109.960411ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:29.208026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.825258ms","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-03-20T05:33:29.208035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.873629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:33:29.208096Z","caller":"traceutil/trace.go:171","msg":"trace[908764257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"497.88855ms","start":"2026-03-20T05:33:28.710185Z","end":"2026-03-20T05:33:29.208074Z","steps":["trace[908764257] 'agreement among raft nodes before linearized reading' (duration: 497.739385ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.208096Z","caller":"traceutil/trace.go:171","msg":"trace[658294995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"503.994043ms","start":"2026-03-20T05:33:28.704088Z","end":"2026-03-20T05:33:29.208082Z","steps":["trace[658294995] 'agreement among raft nodes before linearized reading' (duration: 503.762126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.208135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.704072Z","time spent":"504.054675ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:33:29.208216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.694122ms","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-03-20T05:33:29.208111Z","caller":"traceutil/trace.go:171","msg":"trace[1385225087] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"539.110905ms","start":"2026-03-20T05:33:28.668908Z","end":"2026-03-20T05:33:29.208019Z","steps":["trace[1385225087] 'process raft request' (duration: 492.319794ms)","trace[1385225087] 'compare' (duration: 46.354447ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:29.208227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.347898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:33:29.20835Z","caller":"traceutil/trace.go:171","msg":"trace[1751151058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"502.828996ms","start":"2026-03-20T05:33:28.705429Z","end":"2026-03-20T05:33:29.208258Z","steps":["trace[1751151058] 'agreement among raft nodes before linearized reading' (duration: 502.423614ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.208392Z","caller":"traceutil/trace.go:171","msg":"trace[427176637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"500.540154ms","start":"2026-03-20T05:33:28.707833Z","end":"2026-03-20T05:33:29.208373Z","steps":["trace[427176637] 'agreement among raft nodes before linearized reading' (duration: 500.214444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.208135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.710155Z","time spent":"497.971382ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:33:29.208445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.494656ms","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":"warn","ts":"2026-03-20T05:33:29.208481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.668887Z","time spent":"539.437176ms","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4878,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:29.208462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.707824Z","time spent":"500.608896ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:33:29.208559Z","caller":"traceutil/trace.go:171","msg":"trace[179970024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:524; }","duration":"502.64254ms","start":"2026-03-20T05:33:28.705884Z","end":"2026-03-20T05:33:29.208527Z","steps":["trace[179970024] 'agreement among raft nodes before linearized reading' (duration: 502.215316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.208509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.705415Z","time spent":"503.079804ms","remote":"127.0.0.1:39216","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-03-20T05:33:29.208665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:28.705874Z","time spent":"502.757194ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:33:29.328882Z","caller":"traceutil/trace.go:171","msg":"trace[166361992] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"116.683694ms","start":"2026-03-20T05:33:29.212171Z","end":"2026-03-20T05:33:29.328855Z","steps":["trace[166361992] 'process raft request' (duration: 116.485237ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.329146Z","caller":"traceutil/trace.go:171","msg":"trace[1850261774] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"115.960462ms","start":"2026-03-20T05:33:29.213175Z","end":"2026-03-20T05:33:29.329135Z","steps":["trace[1850261774] 'process raft request' (duration: 115.638671ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.329551Z","caller":"traceutil/trace.go:171","msg":"trace[1483152154] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"114.796745ms","start":"2026-03-20T05:33:29.214728Z","end":"2026-03-20T05:33:29.329525Z","steps":["trace[1483152154] 'process raft request' (duration: 114.706142ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.329615Z","caller":"traceutil/trace.go:171","msg":"trace[441801876] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"114.727872ms","start":"2026-03-20T05:33:29.214874Z","end":"2026-03-20T05:33:29.329602Z","steps":["trace[441801876] 'process raft request' (duration: 114.226986ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:33.193876Z","caller":"traceutil/trace.go:171","msg":"trace[864940812] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"203.524912ms","start":"2026-03-20T05:33:32.990332Z","end":"2026-03-20T05:33:33.193857Z","steps":["trace[864940812] 'process raft request' (duration: 203.346946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.194073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.809306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gttt8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-20T05:33:33.194163Z","caller":"traceutil/trace.go:171","msg":"trace[856149603] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gttt8; range_end:; response_count:1; response_revision:538; }","duration":"139.94943ms","start":"2026-03-20T05:33:33.054195Z","end":"2026-03-20T05:33:33.194144Z","steps":["trace[856149603] 'agreement among raft nodes before linearized reading' (duration: 139.747434ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:33.194242Z","caller":"traceutil/trace.go:171","msg":"trace[1602479977] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"139.573788ms","start":"2026-03-20T05:33:33.054241Z","end":"2026-03-20T05:33:33.193815Z","steps":["trace[1602479977] 'read index received' (duration: 139.389542ms)","trace[1602479977] 'applied index is now lower than readState.Index' (duration: 183.366µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:33.374596Z","caller":"traceutil/trace.go:171","msg":"trace[617177218] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"162.453042ms","start":"2026-03-20T05:33:33.212106Z","end":"2026-03-20T05:33:33.374559Z","steps":["trace[617177218] 'process raft request' (duration: 162.176323ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:33.374611Z","caller":"traceutil/trace.go:171","msg":"trace[981305438] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"142.435379ms","start":"2026-03-20T05:33:33.232126Z","end":"2026-03-20T05:33:33.374562Z","steps":["trace[981305438] 'read index received' (duration: 142.019026ms)","trace[981305438] 'applied index is now lower than readState.Index' (duration: 414.803µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:33.375013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.830282ms","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-03-20T05:33:33.375075Z","caller":"traceutil/trace.go:171","msg":"trace[1035095840] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:539; }","duration":"142.961716ms","start":"2026-03-20T05:33:33.232091Z","end":"2026-03-20T05:33:33.375053Z","steps":["trace[1035095840] 'agreement among raft nodes before linearized reading' (duration: 142.691827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.375173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.983992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:33.375279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.064442ms","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-03-20T05:33:33.375385Z","caller":"traceutil/trace.go:171","msg":"trace[925374588] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:539; }","duration":"127.215318ms","start":"2026-03-20T05:33:33.248145Z","end":"2026-03-20T05:33:33.375361Z","steps":["trace[925374588] 'agreement among raft nodes before linearized reading' (duration: 126.851346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.375454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.067525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:33.375523Z","caller":"traceutil/trace.go:171","msg":"trace[36280661] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:539; }","duration":"110.217879ms","start":"2026-03-20T05:33:33.265291Z","end":"2026-03-20T05:33:33.375509Z","steps":["trace[36280661] 'agreement among raft nodes before linearized reading' (duration: 110.090725ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:33.375316Z","caller":"traceutil/trace.go:171","msg":"trace[511317380] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:539; }","duration":"110.23468ms","start":"2026-03-20T05:33:33.265047Z","end":"2026-03-20T05:33:33.375282Z","steps":["trace[511317380] 'agreement among raft nodes before linearized reading' (duration: 109.958011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.375753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.995702ms","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-03-20T05:33:33.375826Z","caller":"traceutil/trace.go:171","msg":"trace[878760543] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:539; }","duration":"128.080985ms","start":"2026-03-20T05:33:33.247732Z","end":"2026-03-20T05:33:33.375813Z","steps":["trace[878760543] 'agreement among raft nodes before linearized reading' (duration: 127.987982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.37596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.096225ms","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":"warn","ts":"2026-03-20T05:33:33.375772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.751016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:33.376028Z","caller":"traceutil/trace.go:171","msg":"trace[828278182] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:539; }","duration":"128.187068ms","start":"2026-03-20T05:33:33.247831Z","end":"2026-03-20T05:33:33.376018Z","steps":["trace[828278182] 'agreement among raft nodes before linearized reading' (duration: 128.100096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.376014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.246875ms","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-03-20T05:33:33.376187Z","caller":"traceutil/trace.go:171","msg":"trace[1909010199] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:539; }","duration":"111.232332ms","start":"2026-03-20T05:33:33.264931Z","end":"2026-03-20T05:33:33.376163Z","steps":["trace[1909010199] 'agreement among raft nodes before linearized reading' (duration: 110.599422ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:33.376243Z","caller":"traceutil/trace.go:171","msg":"trace[1827370904] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:539; }","duration":"143.481482ms","start":"2026-03-20T05:33:33.23274Z","end":"2026-03-20T05:33:33.376221Z","steps":["trace[1827370904] 'agreement among raft nodes before linearized reading' (duration: 143.228314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:33.376289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.804601ms","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-03-20T05:33:33.376441Z","caller":"traceutil/trace.go:171","msg":"trace[1856660878] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:539; }","duration":"144.333449ms","start":"2026-03-20T05:33:33.232087Z","end":"2026-03-20T05:33:33.376421Z","steps":["trace[1856660878] 'agreement among raft nodes before linearized reading' (duration: 142.755399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:35.278704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526486406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:35.278778Z","caller":"traceutil/trace.go:171","msg":"trace[1017294483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"1.52660891s","start":"2026-03-20T05:33:33.752153Z","end":"2026-03-20T05:33:35.278762Z","steps":["trace[1017294483] 'range keys from in-memory index tree' (duration: 1.526357972s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:35.278816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:33.752136Z","time spent":"1.526670482s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:46.356813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.204159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gttt8\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-03-20T05:33:46.356474Z","caller":"traceutil/trace.go:171","msg":"trace[61328844] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"181.874309ms","start":"2026-03-20T05:33:46.174578Z","end":"2026-03-20T05:33:46.356452Z","steps":["trace[61328844] 'read index received' (duration: 181.638822ms)","trace[61328844] 'applied index is now lower than readState.Index' (duration: 234.287µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:46.356899Z","caller":"traceutil/trace.go:171","msg":"trace[612204498] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gttt8; range_end:; response_count:1; response_revision:611; }","duration":"182.357513ms","start":"2026-03-20T05:33:46.174524Z","end":"2026-03-20T05:33:46.356882Z","steps":["trace[612204498] 'agreement among raft nodes before linearized reading' (duration: 182.073155ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:49.637001Z","caller":"traceutil/trace.go:171","msg":"trace[1894464490] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"118.766105ms","start":"2026-03-20T05:33:49.51822Z","end":"2026-03-20T05:33:49.636986Z","steps":["trace[1894464490] 'read index received' (duration: 118.668662ms)","trace[1894464490] 'applied index is now lower than readState.Index' (duration: 96.703µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:49.637163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.92825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:33:49.637226Z","caller":"traceutil/trace.go:171","msg":"trace[736946017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"119.035763ms","start":"2026-03-20T05:33:49.518181Z","end":"2026-03-20T05:33:49.637217Z","steps":["trace[736946017] 'agreement among raft nodes before linearized reading' (duration: 118.904389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:49.637273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.860969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:33:49.637331Z","caller":"traceutil/trace.go:171","msg":"trace[743934365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"111.934822ms","start":"2026-03-20T05:33:49.525382Z","end":"2026-03-20T05:33:49.637317Z","steps":["trace[743934365] 'agreement among raft nodes before linearized reading' (duration: 111.760936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:49.637355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.039814ms","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-03-20T05:33:49.637447Z","caller":"traceutil/trace.go:171","msg":"trace[1666908696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:672; }","duration":"119.171858ms","start":"2026-03-20T05:33:49.518256Z","end":"2026-03-20T05:33:49.637427Z","steps":["trace[1666908696] 'agreement among raft nodes before linearized reading' (duration: 118.876509ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:49.637113Z","caller":"traceutil/trace.go:171","msg":"trace[202959708] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"161.680888ms","start":"2026-03-20T05:33:49.47539Z","end":"2026-03-20T05:33:49.637071Z","steps":["trace[202959708] 'process raft request' (duration: 161.503002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:49.637362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.017621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:33:49.637747Z","caller":"traceutil/trace.go:171","msg":"trace[1299531641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:672; }","duration":"117.433983ms","start":"2026-03-20T05:33:49.520293Z","end":"2026-03-20T05:33:49.637727Z","steps":["trace[1299531641] 'agreement among raft nodes before linearized reading' (duration: 116.855755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:49.6375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.982402ms","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-03-20T05:33:49.637892Z","caller":"traceutil/trace.go:171","msg":"trace[1617565524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"119.425386ms","start":"2026-03-20T05:33:49.518447Z","end":"2026-03-20T05:33:49.637873Z","steps":["trace[1617565524] 'agreement among raft nodes before linearized reading' (duration: 118.856108ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:49.742304Z","caller":"traceutil/trace.go:171","msg":"trace[1310981043] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"100.038079ms","start":"2026-03-20T05:33:49.642243Z","end":"2026-03-20T05:33:49.742281Z","steps":["trace[1310981043] 'process raft request' (duration: 100.001798ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:49.7424Z","caller":"traceutil/trace.go:171","msg":"trace[806943822] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"100.305457ms","start":"2026-03-20T05:33:49.642057Z","end":"2026-03-20T05:33:49.742363Z","steps":["trace[806943822] 'process raft request' (duration: 100.023768ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:49.742483Z","caller":"traceutil/trace.go:171","msg":"trace[1996320667] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"100.307337ms","start":"2026-03-20T05:33:49.642146Z","end":"2026-03-20T05:33:49.742453Z","steps":["trace[1996320667] 'process raft request' (duration: 100.027818ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:49.742503Z","caller":"traceutil/trace.go:171","msg":"trace[755286595] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"100.266556ms","start":"2026-03-20T05:33:49.642197Z","end":"2026-03-20T05:33:49.742464Z","steps":["trace[755286595] 'process raft request' (duration: 100.017389ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:51.956919Z","caller":"traceutil/trace.go:171","msg":"trace[1880523885] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"207.492731ms","start":"2026-03-20T05:33:51.749407Z","end":"2026-03-20T05:33:51.9569Z","steps":["trace[1880523885] 'read index received' (duration: 207.376477ms)","trace[1880523885] 'applied index is now lower than readState.Index' (duration: 115.484µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:51.956998Z","caller":"traceutil/trace.go:171","msg":"trace[1508587677] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"207.863342ms","start":"2026-03-20T05:33:51.749105Z","end":"2026-03-20T05:33:51.956968Z","steps":["trace[1508587677] 'process raft request' (duration: 207.675706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.957075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.659365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:33:51.957099Z","caller":"traceutil/trace.go:171","msg":"trace[414527594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"207.730508ms","start":"2026-03-20T05:33:51.74936Z","end":"2026-03-20T05:33:51.957091Z","steps":["trace[414527594] 'agreement among raft nodes before linearized reading' (duration: 207.623894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:52.457958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:52.958122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:53.071024Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114332015s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:33:53.071709Z","caller":"traceutil/trace.go:171","msg":"trace[1196894571] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.319749501s","start":"2026-03-20T05:33:51.751929Z","end":"2026-03-20T05:33:53.071678Z","steps":["trace[1196894571] 'process raft request' (duration: 1.319371199s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:53.07184Z","caller":"traceutil/trace.go:171","msg":"trace[1541478961] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.319844574s","start":"2026-03-20T05:33:51.751973Z","end":"2026-03-20T05:33:53.071818Z","steps":["trace[1541478961] 'process raft request' (duration: 1.319543585s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:53.071942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.751911Z","time spent":"1.319900896s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:53.071976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.751952Z","time spent":"1.319956937s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:53.072035Z","caller":"traceutil/trace.go:171","msg":"trace[1982340799] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.317868962s","start":"2026-03-20T05:33:51.754117Z","end":"2026-03-20T05:33:53.071986Z","steps":["trace[1982340799] 'process raft request' (duration: 1.317604594s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:53.072193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.7541Z","time spent":"1.318001906s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:53.754536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000422832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-20T05:33:53.754603Z","caller":"traceutil/trace.go:171","msg":"trace[1330169080] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000534176s","start":"2026-03-20T05:33:51.754053Z","end":"2026-03-20T05:33:53.754588Z","steps":["trace[1330169080] 'agreement among raft nodes before linearized reading' (duration: 2.000450313s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:53.754639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.754038Z","time spent":"2.000594607s","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/20 05:33:53 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-20T05:33:53.870825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.126436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:53.870937Z","caller":"traceutil/trace.go:171","msg":"trace[1838281969] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:704; }","duration":"1.913954757s","start":"2026-03-20T05:33:51.956966Z","end":"2026-03-20T05:33:53.870921Z","steps":["trace[1838281969] 'read index received' (duration: 1.114342325s)","trace[1838281969] 'applied index is now lower than readState.Index' (duration: 799.611382ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:53.870974Z","caller":"traceutil/trace.go:171","msg":"trace[1354186894] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.909482757s","start":"2026-03-20T05:33:51.961457Z","end":"2026-03-20T05:33:53.87094Z","steps":["trace[1354186894] 'process raft request' (duration: 1.165173346s)","trace[1354186894] 'compare' (duration: 743.920599ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:53.871172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.96144Z","time spent":"1.909623232s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:54.371344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550052,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:55.763699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000057541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-20T05:33:55.763783Z","caller":"traceutil/trace.go:171","msg":"trace[1466600891] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000173774s","start":"2026-03-20T05:33:53.76359Z","end":"2026-03-20T05:33:55.763764Z","steps":["trace[1466600891] 'agreement among raft nodes before linearized reading' (duration: 2.000070301s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:55.763852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:53.763568Z","time spent":"2.000273138s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/20 05:33:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-20T05:33:56.172869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748044698s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:56.173064Z","caller":"traceutil/trace.go:171","msg":"trace[1359338248] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"2.302085609s","start":"2026-03-20T05:33:53.870961Z","end":"2026-03-20T05:33:56.173047Z","steps":["trace[1359338248] 'read index received' (duration: 553.811643ms)","trace[1359338248] 'applied index is now lower than readState.Index' (duration: 1.748272966s)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:56.173154Z","caller":"traceutil/trace.go:171","msg":"trace[126184446] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"2.745908882s","start":"2026-03-20T05:33:53.427234Z","end":"2026-03-20T05:33:56.173142Z","steps":["trace[126184446] 'process raft request' (duration: 997.506432ms)","trace[126184446] 'compare' (duration: 1.747851102s)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:56.173236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.559979916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:56.173257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:53.427215Z","time spent":"2.745999184s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:56.173272Z","caller":"traceutil/trace.go:171","msg":"trace[262312716] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"2.416024803s","start":"2026-03-20T05:33:53.757222Z","end":"2026-03-20T05:33:56.173247Z","steps":["trace[262312716] 'process raft request' (duration: 2.415742114s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:56.173299Z","caller":"traceutil/trace.go:171","msg":"trace[944944472] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:685; }","duration":"2.560086479s","start":"2026-03-20T05:33:53.613195Z","end":"2026-03-20T05:33:56.173282Z","steps":["trace[944944472] 'agreement among raft nodes before linearized reading' (duration: 2.559951595s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.173332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:53.61314Z","time spent":"2.560185981s","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:33:56.173395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:53.757206Z","time spent":"2.416127636s","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:33:56.173623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.919433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:56.173644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.212229688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:33:56.173684Z","caller":"traceutil/trace.go:171","msg":"trace[705875115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"2.21230718s","start":"2026-03-20T05:33:53.961364Z","end":"2026-03-20T05:33:56.173671Z","steps":["trace[705875115] 'agreement among raft nodes before linearized reading' (duration: 2.212175366s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.173723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:53.961347Z","time spent":"2.212368953s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:56.173725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.413904ms","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-03-20T05:33:56.173773Z","caller":"traceutil/trace.go:171","msg":"trace[97239917] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:685; }","duration":"618.506677ms","start":"2026-03-20T05:33:55.555254Z","end":"2026-03-20T05:33:56.17376Z","steps":["trace[97239917] 'agreement among raft nodes before linearized reading' (duration: 618.404474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.173784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.287413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T05:33:56.173778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09719291s","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-03-20T05:33:56.173811Z","caller":"traceutil/trace.go:171","msg":"trace[194810915] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:685; }","duration":"949.352455ms","start":"2026-03-20T05:33:55.224452Z","end":"2026-03-20T05:33:56.173805Z","steps":["trace[194810915] 'agreement among raft nodes before linearized reading' (duration: 949.285703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.173809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:55.555231Z","time spent":"618.569309ms","remote":"127.0.0.1:39224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:33:56.173835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:55.224435Z","time spent":"949.395717ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:33:56.173841Z","caller":"traceutil/trace.go:171","msg":"trace[1175526844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"1.097302293s","start":"2026-03-20T05:33:55.076522Z","end":"2026-03-20T05:33:56.173824Z","steps":["trace[1175526844] 'agreement among raft nodes before linearized reading' (duration: 1.097144888s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.173882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:55.0765Z","time spent":"1.097374436s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:33:56.173876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09722258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-20T05:33:56.173926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097382956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:33:56.173961Z","caller":"traceutil/trace.go:171","msg":"trace[1934895833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"1.097450528s","start":"2026-03-20T05:33:55.076499Z","end":"2026-03-20T05:33:56.17395Z","steps":["trace[1934895833] 'agreement among raft nodes before linearized reading' (duration: 1.097358255s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:56.173958Z","caller":"traceutil/trace.go:171","msg":"trace[522286936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.097327954s","start":"2026-03-20T05:33:55.076612Z","end":"2026-03-20T05:33:56.17394Z","steps":["trace[522286936] 'agreement among raft nodes before linearized reading' (duration: 1.097044235s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.173851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.241531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:33:56.173655Z","caller":"traceutil/trace.go:171","msg":"trace[1977645813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"409.977775ms","start":"2026-03-20T05:33:55.76367Z","end":"2026-03-20T05:33:56.173648Z","steps":["trace[1977645813] 'agreement among raft nodes before linearized reading' (duration: 409.933393ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:56.174Z","caller":"traceutil/trace.go:171","msg":"trace[1524253155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"299.413106ms","start":"2026-03-20T05:33:55.874579Z","end":"2026-03-20T05:33:56.173992Z","steps":["trace[1524253155] 'agreement among raft nodes before linearized reading' (duration: 299.231401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:56.174006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:55.076588Z","time spent":"1.097410786s","remote":"127.0.0.1:39216","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-03-20T05:33:56.174033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:55.763656Z","time spent":"410.360226ms","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:56.173989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:55.076479Z","time spent":"1.09750428s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:33:57.362468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.837726ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-20T05:33:57.362588Z","caller":"traceutil/trace.go:171","msg":"trace[308049051] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"1.182531079s","start":"2026-03-20T05:33:56.180043Z","end":"2026-03-20T05:33:57.362574Z","steps":["trace[308049051] 'read index received' (duration: 235.589979ms)","trace[308049051] 'applied index is now lower than readState.Index' (duration: 946.93951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:57.362599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.174767Z","time spent":"1.187825844s","remote":"127.0.0.1:39010","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-20T05:33:57.36273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182678153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189e75ceacec28ab\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-20T05:33:57.362762Z","caller":"traceutil/trace.go:171","msg":"trace[285392079] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189e75ceacec28ab; range_end:; response_count:1; response_revision:685; }","duration":"1.182741715s","start":"2026-03-20T05:33:56.180013Z","end":"2026-03-20T05:33:57.362755Z","steps":["trace[285392079] 'agreement among raft nodes before linearized reading' (duration: 1.182620132s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:57.362787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.179996Z","time spent":"1.182785836s","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189e75ceacec28ab\" "} {"level":"warn","ts":"2026-03-20T05:33:57.638837Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.223199941s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T05:33:58.180906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000292808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-20T05:33:58.180988Z","caller":"traceutil/trace.go:171","msg":"trace[1153809958] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000413372s","start":"2026-03-20T05:33:56.180556Z","end":"2026-03-20T05:33:58.180969Z","steps":["trace[1153809958] 'agreement among raft nodes before linearized reading' (duration: 2.000309829s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:58.181038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.180546Z","time spent":"2.000480714s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/20 05:33:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-20T05:33:58.704296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065138047s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:58.704698Z","caller":"traceutil/trace.go:171","msg":"trace[1714194854] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"2.524501695s","start":"2026-03-20T05:33:56.180139Z","end":"2026-03-20T05:33:58.704641Z","steps":["trace[1714194854] 'process raft request' (duration: 1.458890493s)","trace[1714194854] 'compare' (duration: 1.064934291s)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:58.704905Z","caller":"traceutil/trace.go:171","msg":"trace[1111876509] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:711; }","duration":"1.342275135s","start":"2026-03-20T05:33:57.362609Z","end":"2026-03-20T05:33:58.704884Z","steps":["trace[1111876509] 'read index received' (duration: 276.476108ms)","trace[1111876509] 'applied index is now lower than readState.Index' (duration: 1.065797467s)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:58.705Z","caller":"traceutil/trace.go:171","msg":"trace[1653443386] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"2.524627639s","start":"2026-03-20T05:33:56.18036Z","end":"2026-03-20T05:33:58.704988Z","steps":["trace[1653443386] 'process raft request' (duration: 2.524246217s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:58.705106Z","caller":"traceutil/trace.go:171","msg":"trace[1039082159] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"2.524461814s","start":"2026-03-20T05:33:56.180626Z","end":"2026-03-20T05:33:58.705088Z","steps":["trace[1039082159] 'process raft request' (duration: 2.524154184s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:58.705091Z","caller":"traceutil/trace.go:171","msg":"trace[908522960] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"2.524862476s","start":"2026-03-20T05:33:56.1802Z","end":"2026-03-20T05:33:58.705062Z","steps":["trace[908522960] 'process raft request' (duration: 2.524243767s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:58.705116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.180344Z","time spent":"2.52468423s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:58.705228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.180617Z","time spent":"2.524527776s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:58.705296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.180177Z","time spent":"2.52498679s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:58.704673Z","caller":"traceutil/trace.go:171","msg":"trace[920007357] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"2.52435349s","start":"2026-03-20T05:33:56.180298Z","end":"2026-03-20T05:33:58.704651Z","steps":["trace[920007357] 'process raft request' (duration: 2.524269088s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:58.705493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.180271Z","time spent":"2.525175726s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:58.70584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:56.180123Z","time spent":"2.5246773s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:59.159584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.520199171s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:33:59.160063Z","caller":"traceutil/trace.go:171","msg":"trace[883784200] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.796251397s","start":"2026-03-20T05:33:57.363784Z","end":"2026-03-20T05:33:59.160036Z","steps":["trace[883784200] 'process raft request' (duration: 1.796008019s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:59.160086Z","caller":"traceutil/trace.go:171","msg":"trace[21067490] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:716; }","duration":"455.103285ms","start":"2026-03-20T05:33:58.70495Z","end":"2026-03-20T05:33:59.160054Z","steps":["trace[21067490] 'read index received' (duration: 454.733814ms)","trace[21067490] 'applied index is now lower than readState.Index' (duration: 367.921µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:59.160194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:57.363746Z","time spent":"1.79637435s","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:59.160268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.88646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:59.160308Z","caller":"traceutil/trace.go:171","msg":"trace[311599095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"978.992533ms","start":"2026-03-20T05:33:58.181301Z","end":"2026-03-20T05:33:59.160294Z","steps":["trace[311599095] 'agreement among raft nodes before linearized reading' (duration: 978.867119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:59.160343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:58.181268Z","time spent":"979.067065ms","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:33:59.160462Z","caller":"traceutil/trace.go:171","msg":"trace[1932101962] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.795510903s","start":"2026-03-20T05:33:57.364943Z","end":"2026-03-20T05:33:59.160453Z","steps":["trace[1932101962] 'process raft request' (duration: 1.795047388s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:59.160516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:57.364933Z","time spent":"1.795554645s","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:59.305349Z","caller":"traceutil/trace.go:171","msg":"trace[146044510] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"122.651857ms","start":"2026-03-20T05:33:59.182658Z","end":"2026-03-20T05:33:59.30531Z","steps":["trace[146044510] 'process raft request' (duration: 110.714533ms)","trace[146044510] 'compare' (duration: 11.681816ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:59.447917Z","caller":"traceutil/trace.go:171","msg":"trace[1935785356] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"132.460524ms","start":"2026-03-20T05:33:59.315426Z","end":"2026-03-20T05:33:59.447887Z","steps":["trace[1935785356] 'process raft request' (duration: 122.303706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:13.063517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.890787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:13.063619Z","caller":"traceutil/trace.go:171","msg":"trace[193481614] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"241.206895ms","start":"2026-03-20T05:34:12.822397Z","end":"2026-03-20T05:34:13.063603Z","steps":["trace[193481614] 'process raft request' (duration: 69.157543ms)","trace[193481614] 'compare' (duration: 171.68199ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:13.067992Z","caller":"traceutil/trace.go:171","msg":"trace[570093376] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"245.353564ms","start":"2026-03-20T05:34:12.822611Z","end":"2026-03-20T05:34:13.067965Z","steps":["trace[570093376] 'process raft request' (duration: 245.305213ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:13.068021Z","caller":"traceutil/trace.go:171","msg":"trace[464669386] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"245.402686ms","start":"2026-03-20T05:34:12.82259Z","end":"2026-03-20T05:34:13.067992Z","steps":["trace[464669386] 'process raft request' (duration: 245.21056ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:16.811535Z","caller":"traceutil/trace.go:171","msg":"trace[312879846] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"510.051825ms","start":"2026-03-20T05:34:16.301442Z","end":"2026-03-20T05:34:16.811494Z","steps":["trace[312879846] 'process raft request' (duration: 432.993104ms)","trace[312879846] 'compare' (duration: 76.69974ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:16.811654Z","caller":"traceutil/trace.go:171","msg":"trace[655249720] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"410.598953ms","start":"2026-03-20T05:34:16.401041Z","end":"2026-03-20T05:34:16.81164Z","steps":["trace[655249720] 'process raft request' (duration: 410.367236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:16.811724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:16.401012Z","time spent":"410.669356ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:16.811739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:16.301423Z","time spent":"510.200279ms","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:19.331698Z","caller":"traceutil/trace.go:171","msg":"trace[110235620] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"113.036066ms","start":"2026-03-20T05:34:19.218632Z","end":"2026-03-20T05:34:19.331668Z","steps":["trace[110235620] 'process raft request' (duration: 75.247154ms)","trace[110235620] 'compare' (duration: 37.669058ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:21.481235Z","caller":"traceutil/trace.go:171","msg":"trace[398470111] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"142.315402ms","start":"2026-03-20T05:34:21.338901Z","end":"2026-03-20T05:34:21.481216Z","steps":["trace[398470111] 'process raft request' (duration: 142.180778ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:22.96911Z","caller":"traceutil/trace.go:171","msg":"trace[1313290545] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"104.119257ms","start":"2026-03-20T05:34:22.864966Z","end":"2026-03-20T05:34:22.969085Z","steps":["trace[1313290545] 'process raft request' (duration: 103.949122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:23.385127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.404124ms","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-03-20T05:34:23.385246Z","caller":"traceutil/trace.go:171","msg":"trace[10308926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"110.554619ms","start":"2026-03-20T05:34:23.274668Z","end":"2026-03-20T05:34:23.385223Z","steps":["trace[10308926] 'range keys from in-memory index tree' (duration: 110.216728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:33.709904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.145452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:33.710051Z","caller":"traceutil/trace.go:171","msg":"trace[1558592951] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"604.715975ms","start":"2026-03-20T05:34:33.105306Z","end":"2026-03-20T05:34:33.710021Z","steps":["trace[1558592951] 'process raft request' (duration: 246.16439ms)","trace[1558592951] 'compare' (duration: 357.698568ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:33.710112Z","caller":"traceutil/trace.go:171","msg":"trace[148083972] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"438.96258ms","start":"2026-03-20T05:34:33.271034Z","end":"2026-03-20T05:34:33.709997Z","steps":["trace[148083972] 'read index received' (duration: 80.378664ms)","trace[148083972] 'applied index is now lower than readState.Index' (duration: 358.582676ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:33.710173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:33.105293Z","time spent":"604.825419ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:33.71029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.178546ms","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":"warn","ts":"2026-03-20T05:34:33.710275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.218869ms","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-03-20T05:34:33.710339Z","caller":"traceutil/trace.go:171","msg":"trace[1165408111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:845; }","duration":"243.257639ms","start":"2026-03-20T05:34:33.467069Z","end":"2026-03-20T05:34:33.710327Z","steps":["trace[1165408111] 'agreement among raft nodes before linearized reading' (duration: 243.116374ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:33.710373Z","caller":"traceutil/trace.go:171","msg":"trace[969640632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:845; }","duration":"439.357373ms","start":"2026-03-20T05:34:33.270991Z","end":"2026-03-20T05:34:33.710348Z","steps":["trace[969640632] 'agreement among raft nodes before linearized reading' (duration: 439.112776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:33.710456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:33.270975Z","time spent":"439.469046ms","remote":"127.0.0.1:39216","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-03-20T05:34:33.710438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.334319ms","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-03-20T05:34:33.710531Z","caller":"traceutil/trace.go:171","msg":"trace[1587472432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:845; }","duration":"116.476823ms","start":"2026-03-20T05:34:33.594034Z","end":"2026-03-20T05:34:33.710511Z","steps":["trace[1587472432] 'agreement among raft nodes before linearized reading' (duration: 116.219115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:35.633563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:36.134653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:36.635165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:37.135756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:37.269527Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.189791315s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:34:37.269989Z","caller":"traceutil/trace.go:171","msg":"trace[268589311] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"2.137581652s","start":"2026-03-20T05:34:35.132382Z","end":"2026-03-20T05:34:37.269964Z","steps":["trace[268589311] 'read index received' (duration: 2.137501039s)","trace[268589311] 'applied index is now lower than readState.Index' (duration: 79.443µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:37.270151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.137748667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:37.270123Z","caller":"traceutil/trace.go:171","msg":"trace[775250723] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"2.190448425s","start":"2026-03-20T05:34:35.079644Z","end":"2026-03-20T05:34:37.270092Z","steps":["trace[775250723] 'process raft request' (duration: 2.190146476s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:37.270198Z","caller":"traceutil/trace.go:171","msg":"trace[85519438] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:849; }","duration":"2.137855211s","start":"2026-03-20T05:34:35.132328Z","end":"2026-03-20T05:34:37.270184Z","steps":["trace[85519438] 'agreement among raft nodes before linearized reading' (duration: 2.137763808s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.270235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.132304Z","time spent":"2.137921942s","remote":"127.0.0.1:39346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:34:37.270375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.079624Z","time spent":"2.19059431s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:37.752553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000161733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-20T05:34:37.75265Z","caller":"traceutil/trace.go:171","msg":"trace[699932852] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000319868s","start":"2026-03-20T05:34:35.752307Z","end":"2026-03-20T05:34:37.752626Z","steps":["trace[699932852] 'agreement among raft nodes before linearized reading' (duration: 2.000178414s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.752704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.752295Z","time spent":"2.00039853s","remote":"127.0.0.1:38960","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-03-20T05:34:37.858105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.969976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:37.858267Z","caller":"traceutil/trace.go:171","msg":"trace[1258946549] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"588.170048ms","start":"2026-03-20T05:34:37.270061Z","end":"2026-03-20T05:34:37.858231Z","steps":["trace[1258946549] 'read index received' (duration: 120.83636ms)","trace[1258946549] 'applied index is now lower than readState.Index' (duration: 467.331208ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:37.858579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058749536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:34:37.858622Z","caller":"traceutil/trace.go:171","msg":"trace[323043934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"2.058836448s","start":"2026-03-20T05:34:35.799774Z","end":"2026-03-20T05:34:37.858611Z","steps":["trace[323043934] 'agreement among raft nodes before linearized reading' (duration: 2.058608131s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.85866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.79975Z","time spent":"2.058902291s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:34:37.858639Z","caller":"traceutil/trace.go:171","msg":"trace[443312947] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"641.204676ms","start":"2026-03-20T05:34:37.217402Z","end":"2026-03-20T05:34:37.858607Z","steps":["trace[443312947] 'process raft request' (duration: 173.60884ms)","trace[443312947] 'compare' (duration: 466.692278ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:37.858678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144846079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T05:34:37.858751Z","caller":"traceutil/trace.go:171","msg":"trace[1283415888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:850; }","duration":"2.144958592s","start":"2026-03-20T05:34:35.713775Z","end":"2026-03-20T05:34:37.858734Z","steps":["trace[1283415888] 'agreement among raft nodes before linearized reading' (duration: 2.144702514s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.858795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.713762Z","time spent":"2.145024735s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:34:37.858802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:37.217378Z","time spent":"641.333611ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:37.858852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.101556144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-20T05:34:37.858925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.127434794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:37.85892Z","caller":"traceutil/trace.go:171","msg":"trace[1193854417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"2.101642818s","start":"2026-03-20T05:34:35.757259Z","end":"2026-03-20T05:34:37.858902Z","steps":["trace[1193854417] 'agreement among raft nodes before linearized reading' (duration: 2.101451202s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:37.858958Z","caller":"traceutil/trace.go:171","msg":"trace[1763141050] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:850; }","duration":"2.127489236s","start":"2026-03-20T05:34:35.73146Z","end":"2026-03-20T05:34:37.858949Z","steps":["trace[1763141050] 'agreement among raft nodes before linearized reading' (duration: 2.127439304s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.858989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.73145Z","time spent":"2.127531647s","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:34:37.858982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.75725Z","time spent":"2.10172256s","remote":"127.0.0.1:39216","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-03-20T05:34:37.85902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732295355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T05:34:37.859054Z","caller":"traceutil/trace.go:171","msg":"trace[1155484066] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:850; }","duration":"1.732363438s","start":"2026-03-20T05:34:36.126681Z","end":"2026-03-20T05:34:37.859044Z","steps":["trace[1155484066] 'agreement among raft nodes before linearized reading' (duration: 1.732270925s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.859089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:36.126662Z","time spent":"1.732420069s","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.221\" "} {"level":"warn","ts":"2026-03-20T05:34:37.859266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.309449958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:37.85931Z","caller":"traceutil/trace.go:171","msg":"trace[1500164310] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:850; }","duration":"2.309494849s","start":"2026-03-20T05:34:35.549805Z","end":"2026-03-20T05:34:37.8593Z","steps":["trace[1500164310] 'agreement among raft nodes before linearized reading' (duration: 2.309425027s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.859346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.549749Z","time spent":"2.309589202s","remote":"127.0.0.1:39356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:34:37.859367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.985805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:37.859405Z","caller":"traceutil/trace.go:171","msg":"trace[1397946241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"106.045127ms","start":"2026-03-20T05:34:37.753351Z","end":"2026-03-20T05:34:37.859396Z","steps":["trace[1397946241] 'agreement among raft nodes before linearized reading' (duration: 105.991405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.859494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.883012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:37.859991Z","caller":"traceutil/trace.go:171","msg":"trace[2034743786] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:850; }","duration":"185.006517ms","start":"2026-03-20T05:34:37.674967Z","end":"2026-03-20T05:34:37.859973Z","steps":["trace[2034743786] 'agreement among raft nodes before linearized reading' (duration: 183.839341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.858573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05825531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:34:37.862611Z","caller":"traceutil/trace.go:171","msg":"trace[132935275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"2.062336258s","start":"2026-03-20T05:34:35.800259Z","end":"2026-03-20T05:34:37.862595Z","steps":["trace[132935275] 'agreement among raft nodes before linearized reading' (duration: 2.058069835s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:37.862688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:35.800248Z","time spent":"2.06242516s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:34:46.142733Z","caller":"traceutil/trace.go:171","msg":"trace[1309604656] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"155.914708ms","start":"2026-03-20T05:34:45.986799Z","end":"2026-03-20T05:34:46.142714Z","steps":["trace[1309604656] 'process raft request' (duration: 139.476873ms)","trace[1309604656] 'compare' (duration: 15.985891ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:50.24741Z","caller":"traceutil/trace.go:171","msg":"trace[343870379] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"253.213637ms","start":"2026-03-20T05:34:49.99418Z","end":"2026-03-20T05:34:50.247394Z","steps":["trace[343870379] 'read index received' (duration: 253.089603ms)","trace[343870379] 'applied index is now lower than readState.Index' (duration: 123.514µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:50.247575Z","caller":"traceutil/trace.go:171","msg":"trace[72038555] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"282.955393ms","start":"2026-03-20T05:34:49.964613Z","end":"2026-03-20T05:34:50.247569Z","steps":["trace[72038555] 'process raft request' (duration: 282.697075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:50.247764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.568248ms","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-03-20T05:34:50.247791Z","caller":"traceutil/trace.go:171","msg":"trace[2018123473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"253.627051ms","start":"2026-03-20T05:34:49.994156Z","end":"2026-03-20T05:34:50.247783Z","steps":["trace[2018123473] 'agreement among raft nodes before linearized reading' (duration: 253.532078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:50.247953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.979461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-20T05:34:50.247997Z","caller":"traceutil/trace.go:171","msg":"trace[1296258273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"131.078574ms","start":"2026-03-20T05:34:50.116907Z","end":"2026-03-20T05:34:50.247985Z","steps":["trace[1296258273] 'agreement among raft nodes before linearized reading' (duration: 130.974721ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:51.256075Z","caller":"traceutil/trace.go:171","msg":"trace[947348170] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"316.005564ms","start":"2026-03-20T05:34:50.940045Z","end":"2026-03-20T05:34:51.25605Z","steps":["trace[947348170] 'process raft request' (duration: 315.86437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:51.256271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:50.940026Z","time spent":"316.127818ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:52.757652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550593,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:34:52.88876Z","caller":"traceutil/trace.go:171","msg":"trace[2128660917] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"631.368748ms","start":"2026-03-20T05:34:52.257373Z","end":"2026-03-20T05:34:52.888742Z","steps":["trace[2128660917] 'read index received' (duration: 631.209473ms)","trace[2128660917] 'applied index is now lower than readState.Index' (duration: 158.675µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:52.888858Z","caller":"traceutil/trace.go:171","msg":"trace[29004450] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"633.461743ms","start":"2026-03-20T05:34:52.255365Z","end":"2026-03-20T05:34:52.888827Z","steps":["trace[29004450] 'process raft request' (duration: 633.262377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:52.888998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.625166ms","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-03-20T05:34:52.889024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.255344Z","time spent":"633.594218ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:52.889034Z","caller":"traceutil/trace.go:171","msg":"trace[1538468930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"631.698569ms","start":"2026-03-20T05:34:52.257327Z","end":"2026-03-20T05:34:52.889026Z","steps":["trace[1538468930] 'agreement among raft nodes before linearized reading' (duration: 631.515563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:52.889072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.257316Z","time spent":"631.74989ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:34:52.889131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.733026ms","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-03-20T05:34:52.889222Z","caller":"traceutil/trace.go:171","msg":"trace[1964103377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"629.86013ms","start":"2026-03-20T05:34:52.259347Z","end":"2026-03-20T05:34:52.889207Z","steps":["trace[1964103377] 'agreement among raft nodes before linearized reading' (duration: 629.671844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:52.889255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.550611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-20T05:34:52.889283Z","caller":"traceutil/trace.go:171","msg":"trace[888957552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"629.596703ms","start":"2026-03-20T05:34:52.25968Z","end":"2026-03-20T05:34:52.889277Z","steps":["trace[888957552] 'agreement among raft nodes before linearized reading' (duration: 629.52452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:52.889302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.25967Z","time spent":"629.627603ms","remote":"127.0.0.1:39216","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-03-20T05:34:52.889254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.259335Z","time spent":"629.911812ms","remote":"127.0.0.1:39216","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-03-20T05:34:52.889163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.383113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:52.889397Z","caller":"traceutil/trace.go:171","msg":"trace[117143540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"137.653741ms","start":"2026-03-20T05:34:52.751734Z","end":"2026-03-20T05:34:52.889388Z","steps":["trace[117143540] 'agreement among raft nodes before linearized reading' (duration: 137.414554ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:55.12979Z","caller":"traceutil/trace.go:171","msg":"trace[1311890903] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"130.25517ms","start":"2026-03-20T05:34:54.99951Z","end":"2026-03-20T05:34:55.129765Z","steps":["trace[1311890903] 'process raft request' (duration: 130.208368ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:55.129817Z","caller":"traceutil/trace.go:171","msg":"trace[1485293155] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"130.484626ms","start":"2026-03-20T05:34:54.999301Z","end":"2026-03-20T05:34:55.129785Z","steps":["trace[1485293155] 'process raft request' (duration: 130.271729ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.138169Z","caller":"traceutil/trace.go:171","msg":"trace[1740128812] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"138.816388ms","start":"2026-03-20T05:34:56.999338Z","end":"2026-03-20T05:34:57.138154Z","steps":["trace[1740128812] 'read index received' (duration: 138.683394ms)","trace[1740128812] 'applied index is now lower than readState.Index' (duration: 132.364µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:57.138489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.029458ms","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":"warn","ts":"2026-03-20T05:34:57.138501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.135508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:34:57.138531Z","caller":"traceutil/trace.go:171","msg":"trace[775881114] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"744.006472ms","start":"2026-03-20T05:34:56.394489Z","end":"2026-03-20T05:34:57.138496Z","steps":["trace[775881114] 'process raft request' (duration: 743.573038ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.138573Z","caller":"traceutil/trace.go:171","msg":"trace[187681502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"114.127721ms","start":"2026-03-20T05:34:57.024405Z","end":"2026-03-20T05:34:57.138533Z","steps":["trace[187681502] 'agreement among raft nodes before linearized reading' (duration: 113.944855ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.138583Z","caller":"traceutil/trace.go:171","msg":"trace[717725645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"139.266292ms","start":"2026-03-20T05:34:56.999299Z","end":"2026-03-20T05:34:57.138565Z","steps":["trace[717725645] 'agreement among raft nodes before linearized reading' (duration: 139.009904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.138709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:56.394467Z","time spent":"744.153287ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:57.377685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.074114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:57.377772Z","caller":"traceutil/trace.go:171","msg":"trace[1789615350] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"235.064707ms","start":"2026-03-20T05:34:57.142693Z","end":"2026-03-20T05:34:57.377758Z","steps":["trace[1789615350] 'process raft request' (duration: 63.8655ms)","trace[1789615350] 'compare' (duration: 170.463264ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:57.62319Z","caller":"traceutil/trace.go:171","msg":"trace[1848460392] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"478.862498ms","start":"2026-03-20T05:34:57.144299Z","end":"2026-03-20T05:34:57.623162Z","steps":["trace[1848460392] 'process raft request' (duration: 478.757424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.623364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:57.144293Z","time spent":"478.983892ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:57.623549Z","caller":"traceutil/trace.go:171","msg":"trace[668801979] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"479.005922ms","start":"2026-03-20T05:34:57.144518Z","end":"2026-03-20T05:34:57.623524Z","steps":["trace[668801979] 'process raft request' (duration: 478.594969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.623696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:57.144507Z","time spent":"479.112786ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:57.623691Z","caller":"traceutil/trace.go:171","msg":"trace[615556560] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:960; }","duration":"166.728507ms","start":"2026-03-20T05:34:57.456921Z","end":"2026-03-20T05:34:57.623649Z","steps":["trace[615556560] 'read index received' (duration: 71.534602ms)","trace[615556560] 'applied index is now lower than readState.Index' (duration: 95.192465ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:57.623799Z","caller":"traceutil/trace.go:171","msg":"trace[982768311] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"478.377042ms","start":"2026-03-20T05:34:57.145414Z","end":"2026-03-20T05:34:57.623791Z","steps":["trace[982768311] 'process raft request' (duration: 478.083803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.623848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:57.145406Z","time spent":"478.414713ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:57.623851Z","caller":"traceutil/trace.go:171","msg":"trace[722270412] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"479.051734ms","start":"2026-03-20T05:34:57.144761Z","end":"2026-03-20T05:34:57.623813Z","steps":["trace[722270412] 'process raft request' (duration: 478.633781ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.623888Z","caller":"traceutil/trace.go:171","msg":"trace[1560947395] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"473.413467ms","start":"2026-03-20T05:34:57.150451Z","end":"2026-03-20T05:34:57.623865Z","steps":["trace[1560947395] 'process raft request' (duration: 473.118137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.623958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.007516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:34:57.624006Z","caller":"traceutil/trace.go:171","msg":"trace[1654415539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"167.133609ms","start":"2026-03-20T05:34:57.456861Z","end":"2026-03-20T05:34:57.623994Z","steps":["trace[1654415539] 'agreement among raft nodes before linearized reading' (duration: 166.927562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.624035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:57.150439Z","time spent":"473.51505ms","remote":"127.0.0.1:39404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:57.624043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:57.144741Z","time spent":"479.199068ms","remote":"127.0.0.1:39222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:57.624384Z","caller":"traceutil/trace.go:171","msg":"trace[1749179825] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"479.355514ms","start":"2026-03-20T05:34:57.144123Z","end":"2026-03-20T05:34:57.623478Z","steps":["trace[1749179825] 'process raft request' (duration: 384.386245ms)","trace[1749179825] 'compare' (duration: 94.36918ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:57.624464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:57.144107Z","time spent":"480.320173ms","remote":"127.0.0.1:39130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:58.983099Z","caller":"traceutil/trace.go:171","msg":"trace[1900101437] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"229.180862ms","start":"2026-03-20T05:34:58.753891Z","end":"2026-03-20T05:34:58.983072Z","steps":["trace[1900101437] 'read index received' (duration: 228.995306ms)","trace[1900101437] 'applied index is now lower than readState.Index' (duration: 184.085µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:58.983125Z","caller":"traceutil/trace.go:171","msg":"trace[13560858] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"578.627829ms","start":"2026-03-20T05:34:58.404479Z","end":"2026-03-20T05:34:58.983107Z","steps":["trace[13560858] 'process raft request' (duration: 578.431632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:58.983262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.361788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:58.983324Z","caller":"traceutil/trace.go:171","msg":"trace[614043435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"229.472181ms","start":"2026-03-20T05:34:58.753838Z","end":"2026-03-20T05:34:58.98331Z","steps":["trace[614043435] 'agreement among raft nodes before linearized reading' (duration: 229.334447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:58.983352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:58.404456Z","time spent":"578.801393ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:58.983264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.023111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:58.983454Z","caller":"traceutil/trace.go:171","msg":"trace[1798280654] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:928; }","duration":"191.262269ms","start":"2026-03-20T05:34:58.792175Z","end":"2026-03-20T05:34:58.983437Z","steps":["trace[1798280654] 'agreement among raft nodes before linearized reading' (duration: 191.041872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.144207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.181982ms","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":"warn","ts":"2026-03-20T05:35:00.144436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.131422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:35:00.144479Z","caller":"traceutil/trace.go:171","msg":"trace[236174230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"515.488311ms","start":"2026-03-20T05:34:59.628972Z","end":"2026-03-20T05:35:00.14446Z","steps":["trace[236174230] 'range keys from in-memory index tree' (duration: 515.066159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.144491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.883695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-03-20T05:35:00.144527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:59.628926Z","time spent":"515.591115ms","remote":"127.0.0.1:39216","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":"info","ts":"2026-03-20T05:35:00.14454Z","caller":"traceutil/trace.go:171","msg":"trace[982559508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"435.278278ms","start":"2026-03-20T05:34:59.709227Z","end":"2026-03-20T05:35:00.144505Z","steps":["trace[982559508] 'range keys from in-memory index tree' (duration: 434.934326ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:00.144558Z","caller":"traceutil/trace.go:171","msg":"trace[521667271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"515.996098ms","start":"2026-03-20T05:34:59.628548Z","end":"2026-03-20T05:35:00.144545Z","steps":["trace[521667271] 'range keys from in-memory index tree' (duration: 515.666357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.144588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:59.62853Z","time spent":"516.051819ms","remote":"127.0.0.1:39216","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-03-20T05:35:00.144591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:59.709178Z","time spent":"435.403681ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:35:00.144325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.627816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:35:00.144679Z","caller":"traceutil/trace.go:171","msg":"trace[234648378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"516.031239ms","start":"2026-03-20T05:34:59.628637Z","end":"2026-03-20T05:35:00.144668Z","steps":["trace[234648378] 'range keys from in-memory index tree' (duration: 515.467541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.144714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:59.628631Z","time spent":"516.07433ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:35:00.144378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.424332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T05:35:00.144785Z","caller":"traceutil/trace.go:171","msg":"trace[1112388552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"762.886197ms","start":"2026-03-20T05:34:59.381891Z","end":"2026-03-20T05:35:00.144777Z","steps":["trace[1112388552] 'range keys from in-memory index tree' (duration: 762.210295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.144808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:59.381872Z","time spent":"762.930418ms","remote":"127.0.0.1:39216","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-03-20T05:35:00.455034Z","caller":"traceutil/trace.go:171","msg":"trace[627408391] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"303.097598ms","start":"2026-03-20T05:35:00.151919Z","end":"2026-03-20T05:35:00.455016Z","steps":["trace[627408391] 'process raft request' (duration: 303.026495ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:00.455026Z","caller":"traceutil/trace.go:171","msg":"trace[304718994] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"305.235425ms","start":"2026-03-20T05:35:00.149763Z","end":"2026-03-20T05:35:00.454998Z","steps":["trace[304718994] 'process raft request' (duration: 304.926685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.455301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:00.15191Z","time spent":"303.320004ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:35:00.455393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:00.149743Z","time spent":"305.577795ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:00.455088Z","caller":"traceutil/trace.go:171","msg":"trace[1293431949] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"305.226595ms","start":"2026-03-20T05:35:00.149851Z","end":"2026-03-20T05:35:00.455077Z","steps":["trace[1293431949] 'process raft request' (duration: 304.975077ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:00.455117Z","caller":"traceutil/trace.go:171","msg":"trace[1271898922] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"305.225634ms","start":"2026-03-20T05:35:00.149873Z","end":"2026-03-20T05:35:00.455099Z","steps":["trace[1271898922] 'process raft request' (duration: 305.008187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:00.45559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:00.149823Z","time spent":"305.70919ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:35:00.455664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:00.149847Z","time spent":"305.777922ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:35:00.694768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.805907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:00.694862Z","caller":"traceutil/trace.go:171","msg":"trace[839687048] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:933; }","duration":"110.996243ms","start":"2026-03-20T05:35:00.583847Z","end":"2026-03-20T05:35:00.694843Z","steps":["trace[839687048] 'count revisions from in-memory index tree' (duration: 110.669352ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:01.285503Z","caller":"traceutil/trace.go:171","msg":"trace[1958016082] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"133.197582ms","start":"2026-03-20T05:35:01.152281Z","end":"2026-03-20T05:35:01.285479Z","steps":["trace[1958016082] 'process raft request' (duration: 133.037367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:02.252757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236882680550667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:35:02.581627Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150824344s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T05:35:03.09246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.959678ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:03.092584Z","caller":"traceutil/trace.go:171","msg":"trace[85281734] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:934; }","duration":"374.105492ms","start":"2026-03-20T05:35:02.718462Z","end":"2026-03-20T05:35:03.092568Z","steps":["trace[85281734] 'range keys from in-memory index tree' (duration: 373.942207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.09267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.800794ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-20T05:35:03.092971Z","caller":"traceutil/trace.go:171","msg":"trace[1630877557] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:974; }","duration":"1.340713329s","start":"2026-03-20T05:35:01.75224Z","end":"2026-03-20T05:35:03.092953Z","steps":["trace[1630877557] 'read index received' (duration: 829.612846ms)","trace[1630877557] 'applied index is now lower than readState.Index' (duration: 511.099283ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:03.093015Z","caller":"traceutil/trace.go:171","msg":"trace[1954461144] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.540458805s","start":"2026-03-20T05:35:01.552523Z","end":"2026-03-20T05:35:03.092982Z","steps":["trace[1954461144] 'process raft request' (duration: 1.540248069s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.09311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340856954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:35:03.093157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.552499Z","time spent":"1.540582469s","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:03.093249Z","caller":"traceutil/trace.go:171","msg":"trace[1489059283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"1.34104024s","start":"2026-03-20T05:35:01.752184Z","end":"2026-03-20T05:35:03.093225Z","steps":["trace[1489059283] 'agreement among raft nodes before linearized reading' (duration: 1.340848214s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.093299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.752165Z","time spent":"1.341124123s","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:35:03.093557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.676394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:03.093612Z","caller":"traceutil/trace.go:171","msg":"trace[73143461] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:935; }","duration":"928.763957ms","start":"2026-03-20T05:35:02.164831Z","end":"2026-03-20T05:35:03.093595Z","steps":["trace[73143461] 'agreement among raft nodes before linearized reading' (duration: 928.666034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.093648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:02.16481Z","time spent":"928.828339ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:35:03.093753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.078711ms","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-03-20T05:35:03.093795Z","caller":"traceutil/trace.go:171","msg":"trace[58571972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"633.189266ms","start":"2026-03-20T05:35:02.460594Z","end":"2026-03-20T05:35:03.093783Z","steps":["trace[58571972] 'agreement among raft nodes before linearized reading' (duration: 633.050931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.093804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.861197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T05:35:03.093827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:02.460577Z","time spent":"633.241687ms","remote":"127.0.0.1:39216","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-03-20T05:35:03.093835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.01723ms","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":"warn","ts":"2026-03-20T05:35:03.093844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.049363ms","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-03-20T05:35:03.093851Z","caller":"traceutil/trace.go:171","msg":"trace[168900686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"633.907088ms","start":"2026-03-20T05:35:02.45993Z","end":"2026-03-20T05:35:03.093837Z","steps":["trace[168900686] 'agreement among raft nodes before linearized reading' (duration: 633.741073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.093852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.067021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:35:03.093882Z","caller":"traceutil/trace.go:171","msg":"trace[1466658368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"633.090152ms","start":"2026-03-20T05:35:02.460778Z","end":"2026-03-20T05:35:03.093868Z","steps":["trace[1466658368] 'agreement among raft nodes before linearized reading' (duration: 632.928277ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:03.093889Z","caller":"traceutil/trace.go:171","msg":"trace[1701925358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"907.135945ms","start":"2026-03-20T05:35:02.18674Z","end":"2026-03-20T05:35:03.093876Z","steps":["trace[1701925358] 'agreement among raft nodes before linearized reading' (duration: 907.012402ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:03.093902Z","caller":"traceutil/trace.go:171","msg":"trace[1816818944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"633.138324ms","start":"2026-03-20T05:35:02.460748Z","end":"2026-03-20T05:35:03.093887Z","steps":["trace[1816818944] 'agreement among raft nodes before linearized reading' (duration: 632.965498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.093895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:02.459886Z","time spent":"634.000051ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:35:03.093923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:02.186718Z","time spent":"907.196898ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:35:03.093923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:02.460768Z","time spent":"633.146634ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:35:03.093942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:02.46074Z","time spent":"633.193885ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:35:03.702135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.854332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:35:03.702241Z","caller":"traceutil/trace.go:171","msg":"trace[1701032275] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"605.105762ms","start":"2026-03-20T05:35:03.097116Z","end":"2026-03-20T05:35:03.702221Z","steps":["trace[1701032275] 'process raft request' (duration: 470.906979ms)","trace[1701032275] 'compare' (duration: 133.313335ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:35:03.702308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:03.097095Z","time spent":"605.180924ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:03.733264Z","caller":"traceutil/trace.go:171","msg":"trace[662517386] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"635.640792ms","start":"2026-03-20T05:35:03.097598Z","end":"2026-03-20T05:35:03.733239Z","steps":["trace[662517386] 'process raft request' (duration: 635.455706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.733408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:03.097564Z","time spent":"635.769056ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:03.733685Z","caller":"traceutil/trace.go:171","msg":"trace[1679386633] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:976; }","duration":"632.821623ms","start":"2026-03-20T05:35:03.100845Z","end":"2026-03-20T05:35:03.733666Z","steps":["trace[1679386633] 'read index received' (duration: 467.174551ms)","trace[1679386633] 'applied index is now lower than readState.Index' (duration: 165.646202ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:03.733907Z","caller":"traceutil/trace.go:171","msg":"trace[820800797] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"635.665794ms","start":"2026-03-20T05:35:03.098227Z","end":"2026-03-20T05:35:03.733893Z","steps":["trace[820800797] 'process raft request' (duration: 635.318213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.733985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:03.098214Z","time spent":"635.733045ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:03.734219Z","caller":"traceutil/trace.go:171","msg":"trace[1822121939] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"636.035454ms","start":"2026-03-20T05:35:03.098171Z","end":"2026-03-20T05:35:03.734206Z","steps":["trace[1822121939] 'process raft request' (duration: 635.003142ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:03.734244Z","caller":"traceutil/trace.go:171","msg":"trace[898635231] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"635.744716ms","start":"2026-03-20T05:35:03.098465Z","end":"2026-03-20T05:35:03.73421Z","steps":["trace[898635231] 'process raft request' (duration: 635.164107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.734452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:03.098455Z","time spent":"635.869269ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:35:03.734475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:03.098151Z","time spent":"636.104587ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:35:03.733808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.951117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:03.735343Z","caller":"traceutil/trace.go:171","msg":"trace[243292310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"634.498006ms","start":"2026-03-20T05:35:03.100816Z","end":"2026-03-20T05:35:03.735314Z","steps":["trace[243292310] 'agreement among raft nodes before linearized reading' (duration: 632.929667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:03.736453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:03.100805Z","time spent":"634.566878ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:35:05.276313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.382466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:05.276443Z","caller":"traceutil/trace.go:171","msg":"trace[72931421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"520.551471ms","start":"2026-03-20T05:35:04.755866Z","end":"2026-03-20T05:35:05.276417Z","steps":["trace[72931421] 'range keys from in-memory index tree' (duration: 520.300703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:05.276511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:04.75582Z","time spent":"520.671125ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:35:05.978221Z","caller":"traceutil/trace.go:171","msg":"trace[1083701262] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"240.651212ms","start":"2026-03-20T05:35:05.73754Z","end":"2026-03-20T05:35:05.978191Z","steps":["trace[1083701262] 'read index received' (duration: 240.603601ms)","trace[1083701262] 'applied index is now lower than readState.Index' (duration: 46.541µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:35:05.978647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.080177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T05:35:05.978709Z","caller":"traceutil/trace.go:171","msg":"trace[1436897273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"241.19968ms","start":"2026-03-20T05:35:05.737494Z","end":"2026-03-20T05:35:05.978693Z","steps":["trace[1436897273] 'agreement among raft nodes before linearized reading' (duration: 240.990514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:05.978647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.323934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:35:05.978828Z","caller":"traceutil/trace.go:171","msg":"trace[1751884851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"227.54545ms","start":"2026-03-20T05:35:05.751266Z","end":"2026-03-20T05:35:05.978812Z","steps":["trace[1751884851] 'agreement among raft nodes before linearized reading' (duration: 227.2282ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:05.979067Z","caller":"traceutil/trace.go:171","msg":"trace[235680162] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"267.489748ms","start":"2026-03-20T05:35:05.71097Z","end":"2026-03-20T05:35:05.978459Z","steps":["trace[235680162] 'process raft request' (duration: 267.058954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:05.979227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.142897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:35:05.979289Z","caller":"traceutil/trace.go:171","msg":"trace[1753657362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"240.2342ms","start":"2026-03-20T05:35:05.739041Z","end":"2026-03-20T05:35:05.979276Z","steps":["trace[1753657362] 'agreement among raft nodes before linearized reading' (duration: 239.477016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:05.979061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.002533ms","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-03-20T05:35:05.979382Z","caller":"traceutil/trace.go:171","msg":"trace[1428729682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"240.381014ms","start":"2026-03-20T05:35:05.738982Z","end":"2026-03-20T05:35:05.979363Z","steps":["trace[1428729682] 'agreement among raft nodes before linearized reading' (duration: 239.871898ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:06.08746Z","caller":"traceutil/trace.go:171","msg":"trace[671075589] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"103.878559ms","start":"2026-03-20T05:35:05.983548Z","end":"2026-03-20T05:35:06.087426Z","steps":["trace[671075589] 'process raft request' (duration: 103.797616ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:06.087701Z","caller":"traceutil/trace.go:171","msg":"trace[1560334699] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"103.763765ms","start":"2026-03-20T05:35:05.983907Z","end":"2026-03-20T05:35:06.08767Z","steps":["trace[1560334699] 'process raft request' (duration: 103.486517ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:06.087928Z","caller":"traceutil/trace.go:171","msg":"trace[923843298] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"104.475167ms","start":"2026-03-20T05:35:05.98344Z","end":"2026-03-20T05:35:06.087915Z","steps":["trace[923843298] 'process raft request' (duration: 103.772535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:07.296928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.424705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:07.297024Z","caller":"traceutil/trace.go:171","msg":"trace[107843574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"546.57076ms","start":"2026-03-20T05:35:06.750424Z","end":"2026-03-20T05:35:07.296994Z","steps":["trace[107843574] 'range keys from in-memory index tree' (duration: 546.310152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:07.297064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:06.7504Z","time spent":"546.655493ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:35:07.297278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.987855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:35:07.297356Z","caller":"traceutil/trace.go:171","msg":"trace[560128331] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:953; }","duration":"440.165311ms","start":"2026-03-20T05:35:06.857171Z","end":"2026-03-20T05:35:07.297336Z","steps":["trace[560128331] 'count revisions from in-memory index tree' (duration: 439.878622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:07.2974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:06.85715Z","time spent":"440.239373ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:35:09.210595Z","caller":"traceutil/trace.go:171","msg":"trace[895148277] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"134.533834ms","start":"2026-03-20T05:35:09.076033Z","end":"2026-03-20T05:35:09.210567Z","steps":["trace[895148277] 'read index received' (duration: 133.920565ms)","trace[895148277] 'applied index is now lower than readState.Index' (duration: 612.199µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:09.210652Z","caller":"traceutil/trace.go:171","msg":"trace[65270737] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"270.007546ms","start":"2026-03-20T05:35:08.940605Z","end":"2026-03-20T05:35:09.210613Z","steps":["trace[65270737] 'process raft request' (duration: 269.277123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:09.210751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.72253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:09.210793Z","caller":"traceutil/trace.go:171","msg":"trace[210082721] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:961; }","duration":"134.831713ms","start":"2026-03-20T05:35:09.075949Z","end":"2026-03-20T05:35:09.210781Z","steps":["trace[210082721] 'agreement among raft nodes before linearized reading' (duration: 134.717709ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:09.364674Z","caller":"traceutil/trace.go:171","msg":"trace[501184009] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"224.437012ms","start":"2026-03-20T05:35:09.140208Z","end":"2026-03-20T05:35:09.364645Z","steps":["trace[501184009] 'process raft request' (duration: 218.818106ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:09.367721Z","caller":"traceutil/trace.go:171","msg":"trace[997805762] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"152.54617ms","start":"2026-03-20T05:35:09.215141Z","end":"2026-03-20T05:35:09.367687Z","steps":["trace[997805762] 'process raft request' (duration: 152.315143ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:10.285671Z","caller":"traceutil/trace.go:171","msg":"trace[701027904] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"220.666164ms","start":"2026-03-20T05:35:10.06498Z","end":"2026-03-20T05:35:10.285646Z","steps":["trace[701027904] 'read index received' (duration: 220.504909ms)","trace[701027904] 'applied index is now lower than readState.Index' (duration: 160.265µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:10.285877Z","caller":"traceutil/trace.go:171","msg":"trace[2146457275] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"287.152656ms","start":"2026-03-20T05:35:09.998688Z","end":"2026-03-20T05:35:10.285841Z","steps":["trace[2146457275] 'process raft request' (duration: 286.758873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:10.286351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.240522ms","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":"warn","ts":"2026-03-20T05:35:10.286396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.297257ms","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-03-20T05:35:10.28644Z","caller":"traceutil/trace.go:171","msg":"trace[1467156300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"182.384879ms","start":"2026-03-20T05:35:10.104039Z","end":"2026-03-20T05:35:10.286424Z","steps":["trace[1467156300] 'agreement among raft nodes before linearized reading' (duration: 182.278886ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:10.286439Z","caller":"traceutil/trace.go:171","msg":"trace[1890788299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"180.391457ms","start":"2026-03-20T05:35:10.106027Z","end":"2026-03-20T05:35:10.286419Z","steps":["trace[1890788299] 'agreement among raft nodes before linearized reading' (duration: 180.049066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:10.286228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.617272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-20T05:35:10.286618Z","caller":"traceutil/trace.go:171","msg":"trace[1269259530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"180.014275ms","start":"2026-03-20T05:35:10.106582Z","end":"2026-03-20T05:35:10.286596Z","steps":["trace[1269259530] 'agreement among raft nodes before linearized reading' (duration: 179.281181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:10.28694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.949834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-20T05:35:10.286988Z","caller":"traceutil/trace.go:171","msg":"trace[358092514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"222.025006ms","start":"2026-03-20T05:35:10.06495Z","end":"2026-03-20T05:35:10.286975Z","steps":["trace[358092514] 'agreement among raft nodes before linearized reading' (duration: 221.906143ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:11.264162Z","caller":"traceutil/trace.go:171","msg":"trace[94790882] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"127.911194ms","start":"2026-03-20T05:35:11.13622Z","end":"2026-03-20T05:35:11.264131Z","steps":["trace[94790882] 'process raft request' (duration: 124.292871ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:12.503594Z","caller":"traceutil/trace.go:171","msg":"trace[1752238903] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"149.469764ms","start":"2026-03-20T05:35:12.354079Z","end":"2026-03-20T05:35:12.503549Z","steps":["trace[1752238903] 'process raft request' (duration: 147.691228ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:12.506817Z","caller":"traceutil/trace.go:171","msg":"trace[1488032225] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"152.501179ms","start":"2026-03-20T05:35:12.354293Z","end":"2026-03-20T05:35:12.506794Z","steps":["trace[1488032225] 'process raft request' (duration: 152.295173ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:12.506846Z","caller":"traceutil/trace.go:171","msg":"trace[413443247] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"152.078466ms","start":"2026-03-20T05:35:12.354707Z","end":"2026-03-20T05:35:12.506785Z","steps":["trace[413443247] 'process raft request' (duration: 152.008294ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:12.506955Z","caller":"traceutil/trace.go:171","msg":"trace[1552232205] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"151.671963ms","start":"2026-03-20T05:35:12.355262Z","end":"2026-03-20T05:35:12.506934Z","steps":["trace[1552232205] 'process raft request' (duration: 151.478407ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:13.544713Z","caller":"traceutil/trace.go:171","msg":"trace[183155117] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"264.07644ms","start":"2026-03-20T05:35:13.280608Z","end":"2026-03-20T05:35:13.544684Z","steps":["trace[183155117] 'process raft request' (duration: 263.911925ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:13.557265Z","caller":"traceutil/trace.go:171","msg":"trace[1638162887] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"261.814419ms","start":"2026-03-20T05:35:13.295435Z","end":"2026-03-20T05:35:13.557249Z","steps":["trace[1638162887] 'process raft request' (duration: 261.687335ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:22.702762Z","caller":"traceutil/trace.go:171","msg":"trace[1828093587] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"103.608561ms","start":"2026-03-20T05:35:22.599129Z","end":"2026-03-20T05:35:22.702738Z","steps":["trace[1828093587] 'process raft request' (duration: 60.9936ms)","trace[1828093587] 'compare' (duration: 42.522208ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:42.837922Z","caller":"traceutil/trace.go:171","msg":"trace[640866138] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"141.129171ms","start":"2026-03-20T05:35:42.696771Z","end":"2026-03-20T05:35:42.8379Z","steps":["trace[640866138] 'process raft request' (duration: 59.877144ms)","trace[640866138] 'compare' (duration: 80.71288ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:36:00.201073Z","caller":"traceutil/trace.go:171","msg":"trace[1551709289] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"159.601244ms","start":"2026-03-20T05:36:00.041444Z","end":"2026-03-20T05:36:00.201046Z","steps":["trace[1551709289] 'process raft request' (duration: 159.45539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:36:00.932996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.868784ms","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-03-20T05:36:00.933083Z","caller":"traceutil/trace.go:171","msg":"trace[1362473180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"104.006399ms","start":"2026-03-20T05:36:00.829056Z","end":"2026-03-20T05:36:00.933063Z","steps":["trace[1362473180] 'range keys from in-memory index tree' (duration: 103.70834ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:30.512458Z","caller":"traceutil/trace.go:171","msg":"trace[1104083970] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"135.775873ms","start":"2026-03-20T05:36:30.376662Z","end":"2026-03-20T05:36:30.512438Z","steps":["trace[1104083970] 'process raft request' (duration: 135.651759ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:39.480771Z","caller":"traceutil/trace.go:171","msg":"trace[360085342] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"100.588762ms","start":"2026-03-20T05:36:39.379869Z","end":"2026-03-20T05:36:39.480458Z","steps":["trace[360085342] 'process raft request' (duration: 23.408076ms)","trace[360085342] 'compare' (duration: 77.066783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:38:04.905661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.024789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-03-20T05:38:04.905875Z","caller":"traceutil/trace.go:171","msg":"trace[117776937] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1718; }","duration":"120.256666ms","start":"2026-03-20T05:38:04.785586Z","end":"2026-03-20T05:38:04.905842Z","steps":["trace[117776937] 'range keys from in-memory index tree' (duration: 119.875114ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:04.906061Z","caller":"traceutil/trace.go:171","msg":"trace[116827375] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"120.238496ms","start":"2026-03-20T05:38:04.785793Z","end":"2026-03-20T05:38:04.906032Z","steps":["trace[116827375] 'process raft request' (duration: 61.739804ms)","trace[116827375] 'compare' (duration: 58.163981ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:38:08.588528Z","caller":"traceutil/trace.go:171","msg":"trace[426213121] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"487.413468ms","start":"2026-03-20T05:38:08.101087Z","end":"2026-03-20T05:38:08.5885Z","steps":["trace[426213121] 'process raft request' (duration: 487.243332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:08.588918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:38:08.101067Z","time spent":"487.547012ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:38:09.302411Z","caller":"traceutil/trace.go:171","msg":"trace[1734224660] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"104.456076ms","start":"2026-03-20T05:38:09.19792Z","end":"2026-03-20T05:38:09.302376Z","steps":["trace[1734224660] 'process raft request' (duration: 49.702823ms)","trace[1734224660] 'compare' (duration: 54.615569ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:38:09.611722Z","caller":"traceutil/trace.go:171","msg":"trace[1813683882] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"112.47666ms","start":"2026-03-20T05:38:09.499214Z","end":"2026-03-20T05:38:09.611691Z","steps":["trace[1813683882] 'process raft request' (duration: 112.313165ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:17.921304Z","caller":"traceutil/trace.go:171","msg":"trace[316103374] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1867; }","duration":"209.666185ms","start":"2026-03-20T05:38:17.711614Z","end":"2026-03-20T05:38:17.92128Z","steps":["trace[316103374] 'read index received' (duration: 209.522031ms)","trace[316103374] 'applied index is now lower than readState.Index' (duration: 143.384µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:38:17.921402Z","caller":"traceutil/trace.go:171","msg":"trace[1003925231] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"278.664809ms","start":"2026-03-20T05:38:17.642702Z","end":"2026-03-20T05:38:17.921367Z","steps":["trace[1003925231] 'process raft request' (duration: 278.449522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:17.921462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.838751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-20T05:38:17.921483Z","caller":"traceutil/trace.go:171","msg":"trace[1941474547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1786; }","duration":"209.905993ms","start":"2026-03-20T05:38:17.71157Z","end":"2026-03-20T05:38:17.921476Z","steps":["trace[1941474547] 'agreement among raft nodes before linearized reading' (duration: 209.82095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:17.921593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.78709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:38:17.921647Z","caller":"traceutil/trace.go:171","msg":"trace[676069124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1786; }","duration":"167.873153ms","start":"2026-03-20T05:38:17.753758Z","end":"2026-03-20T05:38:17.921631Z","steps":["trace[676069124] 'agreement among raft nodes before linearized reading' (duration: 167.79105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:40.243721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.85038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:38:40.24393Z","caller":"traceutil/trace.go:171","msg":"trace[823997462] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1973; }","duration":"158.230358ms","start":"2026-03-20T05:38:40.08568Z","end":"2026-03-20T05:38:40.243911Z","steps":["trace[823997462] 'read index received' (duration: 22.092969ms)","trace[823997462] 'applied index is now lower than readState.Index' (duration: 136.136349ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:38:40.243991Z","caller":"traceutil/trace.go:171","msg":"trace[1284753254] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"189.680403ms","start":"2026-03-20T05:38:40.054273Z","end":"2026-03-20T05:38:40.243953Z","steps":["trace[1284753254] 'process raft request' (duration: 189.57175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:40.244099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.381853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-03-20T05:38:40.244188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.481828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-03-20T05:38:40.244305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.756957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:38:40.244869Z","caller":"traceutil/trace.go:171","msg":"trace[561277279] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"189.888131ms","start":"2026-03-20T05:38:40.054204Z","end":"2026-03-20T05:38:40.244092Z","steps":["trace[561277279] 'process raft request' (duration: 53.580946ms)","trace[561277279] 'compare' (duration: 135.743657ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:38:40.244944Z","caller":"traceutil/trace.go:171","msg":"trace[165160636] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1888; }","duration":"158.58039ms","start":"2026-03-20T05:38:40.085645Z","end":"2026-03-20T05:38:40.244225Z","steps":["trace[165160636] 'agreement among raft nodes before linearized reading' (duration: 158.372193ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:40.245144Z","caller":"traceutil/trace.go:171","msg":"trace[1859433222] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1888; }","duration":"153.614712ms","start":"2026-03-20T05:38:40.090641Z","end":"2026-03-20T05:38:40.244256Z","steps":["trace[1859433222] 'agreement among raft nodes before linearized reading' (duration: 153.475407ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:40.245465Z","caller":"traceutil/trace.go:171","msg":"trace[1676097884] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1888; }","duration":"135.932762ms","start":"2026-03-20T05:38:40.108434Z","end":"2026-03-20T05:38:40.244367Z","steps":["trace[1676097884] 'agreement among raft nodes before linearized reading' (duration: 135.774497ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:45.115477Z","caller":"traceutil/trace.go:171","msg":"trace[930842643] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1993; }","duration":"115.714463ms","start":"2026-03-20T05:38:44.999734Z","end":"2026-03-20T05:38:45.115449Z","steps":["trace[930842643] 'read index received' (duration: 43.695323ms)","trace[930842643] 'applied index is now lower than readState.Index' (duration: 72.01798ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:38:45.115538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.843689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363686"} {"level":"info","ts":"2026-03-20T05:38:45.115603Z","caller":"traceutil/trace.go:171","msg":"trace[1768042762] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1905; }","duration":"116.972412ms","start":"2026-03-20T05:38:44.998612Z","end":"2026-03-20T05:38:45.115584Z","steps":["trace[1768042762] 'range keys from in-memory index tree' (duration: 116.58804ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:45.11559Z","caller":"traceutil/trace.go:171","msg":"trace[165043591] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"116.672754ms","start":"2026-03-20T05:38:44.998857Z","end":"2026-03-20T05:38:45.11553Z","steps":["trace[165043591] 'process raft request' (duration: 44.602362ms)","trace[165043591] 'compare' (duration: 71.872395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:38:45.115789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.884808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-20T05:38:45.115846Z","caller":"traceutil/trace.go:171","msg":"trace[1913148160] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1906; }","duration":"116.102775ms","start":"2026-03-20T05:38:44.999721Z","end":"2026-03-20T05:38:45.115824Z","steps":["trace[1913148160] 'agreement among raft nodes before linearized reading' (duration: 115.849917ms)"],"step_count":1}