{"level":"warn","ts":"2026-03-24T14:47:26.052713Z","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-24T14:47:26.052824Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-24T14:47:26.052899Z","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-24T14:47:26.052917Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-03-24T14:47:26.053028Z","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-24T14:47:26.053862Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-03-24T14:47:26.053964Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-24T14:47:26.058358Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.16305ms"} {"level":"info","ts":"2026-03-24T14:47:26.063988Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-03-24T14:47:26.064068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-03-24T14:47:26.064099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-03-24T14:47:26.06412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-24T14:47:26.064134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.064178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-03-24T14:47:26.071596Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-24T14:47:26.073069Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-24T14:47:26.07446Z","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-24T14:47:26.078319Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-24T14:47:26.078674Z","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-24T14:47:26.078743Z","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-24T14:47:26.078794Z","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-24T14:47:26.078998Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-24T14:47:26.084644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-03-24T14:47:26.084983Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-03-24T14:47:26.086734Z","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-24T14:47:26.086979Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-03-24T14:47:26.087028Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-03-24T14:47:26.087534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-24T14:47:26.087645Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-24T14:47:26.46503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.465109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.46543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.465549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.465576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.465681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.465708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.466999Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.468022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:47:26.468024Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-24T14:47:26.468112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:47:26.468614Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-24T14:47:26.468682Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-24T14:47:26.468845Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.469255Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.46933Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.471178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-03-24T14:47:26.478693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-24T14:47:30.750641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.599142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:30.750781Z","caller":"traceutil/trace.go:171","msg":"trace[632197839] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"623.834877ms","start":"2026-03-24T14:47:30.126926Z","end":"2026-03-24T14:47:30.750761Z","steps":["trace[632197839] 'process raft request' (duration: 259.523358ms)","trace[632197839] 'compare' (duration: 363.42417ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:30.751224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.126907Z","time spent":"624.284602ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:47:30.814214Z","caller":"traceutil/trace.go:171","msg":"trace[1379590082] linearizableReadLoop","detail":"{readStateIndex:206; appliedIndex:205; }","duration":"250.690695ms","start":"2026-03-24T14:47:30.563503Z","end":"2026-03-24T14:47:30.814193Z","steps":["trace[1379590082] 'read index received' (duration: 250.613834ms)","trace[1379590082] 'applied index is now lower than readState.Index' (duration: 75.641µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:30.814265Z","caller":"traceutil/trace.go:171","msg":"trace[1534100932] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"687.046923ms","start":"2026-03-24T14:47:30.127193Z","end":"2026-03-24T14:47:30.81424Z","steps":["trace[1534100932] 'process raft request' (duration: 686.896592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:30.814363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.127168Z","time spent":"687.147274ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:47:30.814425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.899787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:30.814464Z","caller":"traceutil/trace.go:171","msg":"trace[835887628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:201; }","duration":"250.991278ms","start":"2026-03-24T14:47:30.563459Z","end":"2026-03-24T14:47:30.814451Z","steps":["trace[835887628] 'agreement among raft nodes before linearized reading' (duration: 250.841917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.319368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213411699809874421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:47:31.533119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.571261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:31.533453Z","caller":"traceutil/trace.go:171","msg":"trace[1989016756] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"777.609963ms","start":"2026-03-24T14:47:30.755817Z","end":"2026-03-24T14:47:31.533427Z","steps":["trace[1989016756] 'process raft request' (duration: 676.668868ms)","trace[1989016756] 'compare' (duration: 100.38804ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:31.533528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.755794Z","time spent":"777.703173ms","remote":"127.0.0.1:57774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:47:31.533705Z","caller":"traceutil/trace.go:171","msg":"trace[962586611] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"714.608598ms","start":"2026-03-24T14:47:30.819055Z","end":"2026-03-24T14:47:31.533663Z","steps":["trace[962586611] 'process raft request' (duration: 714.373476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.533831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.819038Z","time spent":"714.753329ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:47:31.534443Z","caller":"traceutil/trace.go:171","msg":"trace[436628690] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:206; }","duration":"716.181952ms","start":"2026-03-24T14:47:30.81823Z","end":"2026-03-24T14:47:31.534412Z","steps":["trace[436628690] 'read index received' (duration: 614.269339ms)","trace[436628690] 'applied index is now lower than readState.Index' (duration: 101.911253ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:31.534454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.819119Z","time spent":"715.331514ms","remote":"127.0.0.1:57544","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-24T14:47:31.534684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.641283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:31.534747Z","caller":"traceutil/trace.go:171","msg":"trace[1176784414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:203; }","duration":"707.727023ms","start":"2026-03-24T14:47:30.827002Z","end":"2026-03-24T14:47:31.534729Z","steps":["trace[1176784414] 'agreement among raft nodes before linearized reading' (duration: 707.606012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.534801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.826967Z","time spent":"707.821104ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-03-24T14:47:31.534705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.464124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-24T14:47:31.53492Z","caller":"traceutil/trace.go:171","msg":"trace[229646508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:203; }","duration":"716.726067ms","start":"2026-03-24T14:47:30.818175Z","end":"2026-03-24T14:47:31.534901Z","steps":["trace[229646508] 'agreement among raft nodes before linearized reading' (duration: 716.332063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.535006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.818157Z","time spent":"716.831839ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:47:31.535041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.965516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:31.535099Z","caller":"traceutil/trace.go:171","msg":"trace[1169933076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:203; }","duration":"435.054318ms","start":"2026-03-24T14:47:31.10003Z","end":"2026-03-24T14:47:31.535085Z","steps":["trace[1169933076] 'agreement among raft nodes before linearized reading' (duration: 434.968177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.535148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:31.100011Z","time spent":"435.125128ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-24T14:47:31.692641Z","caller":"traceutil/trace.go:171","msg":"trace[991475076] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"156.813151ms","start":"2026-03-24T14:47:31.535773Z","end":"2026-03-24T14:47:31.692586Z","steps":["trace[991475076] 'process raft request' (duration: 151.651163ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:31.692937Z","caller":"traceutil/trace.go:171","msg":"trace[1874047302] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"153.135048ms","start":"2026-03-24T14:47:31.539755Z","end":"2026-03-24T14:47:31.69289Z","steps":["trace[1874047302] 'process raft request' (duration: 152.887996ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:31.692946Z","caller":"traceutil/trace.go:171","msg":"trace[1987431908] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"152.154388ms","start":"2026-03-24T14:47:31.540766Z","end":"2026-03-24T14:47:31.69292Z","steps":["trace[1987431908] 'process raft request' (duration: 152.053647ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:31.693109Z","caller":"traceutil/trace.go:171","msg":"trace[280238184] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"152.390449ms","start":"2026-03-24T14:47:31.540675Z","end":"2026-03-24T14:47:31.693065Z","steps":["trace[280238184] 'process raft request' (duration: 152.067297ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:31.693307Z","caller":"traceutil/trace.go:171","msg":"trace[1746859509] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"154.161746ms","start":"2026-03-24T14:47:31.539124Z","end":"2026-03-24T14:47:31.693286Z","steps":["trace[1746859509] 'read index received' (duration: 148.425294ms)","trace[1746859509] 'applied index is now lower than readState.Index' (duration: 5.133447ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:31.693526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.968685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-24T14:47:31.693552Z","caller":"traceutil/trace.go:171","msg":"trace[581917219] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:207; }","duration":"154.030126ms","start":"2026-03-24T14:47:31.539514Z","end":"2026-03-24T14:47:31.693544Z","steps":["trace[581917219] 'agreement among raft nodes before linearized reading' (duration: 153.922055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.694046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.886163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-24T14:47:31.694114Z","caller":"traceutil/trace.go:171","msg":"trace[1979732284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:207; }","duration":"155.018935ms","start":"2026-03-24T14:47:31.539079Z","end":"2026-03-24T14:47:31.694098Z","steps":["trace[1979732284] 'agreement among raft nodes before linearized reading' (duration: 153.771513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:34.667999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.004104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:34.668216Z","caller":"traceutil/trace.go:171","msg":"trace[335276617] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:288; }","duration":"169.283907ms","start":"2026-03-24T14:47:34.49887Z","end":"2026-03-24T14:47:34.668154Z","steps":["trace[335276617] 'range keys from in-memory index tree' (duration: 168.856853ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.207813Z","caller":"traceutil/trace.go:171","msg":"trace[1100087641] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"103.226878ms","start":"2026-03-24T14:47:58.104568Z","end":"2026-03-24T14:47:58.207795Z","steps":["trace[1100087641] 'process raft request' (duration: 103.144638ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.207884Z","caller":"traceutil/trace.go:171","msg":"trace[678341923] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"113.167886ms","start":"2026-03-24T14:47:58.094688Z","end":"2026-03-24T14:47:58.207856Z","steps":["trace[678341923] 'process raft request' (duration: 71.297267ms)","trace[678341923] 'compare' (duration: 41.479876ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:58.386658Z","caller":"traceutil/trace.go:171","msg":"trace[1672913365] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:475; }","duration":"117.198503ms","start":"2026-03-24T14:47:58.269436Z","end":"2026-03-24T14:47:58.386634Z","steps":["trace[1672913365] 'read index received' (duration: 24.189824ms)","trace[1672913365] 'applied index is now lower than readState.Index' (duration: 93.007569ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:58.386785Z","caller":"traceutil/trace.go:171","msg":"trace[1112143573] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"119.641814ms","start":"2026-03-24T14:47:58.267107Z","end":"2026-03-24T14:47:58.386748Z","steps":["trace[1112143573] 'process raft request' (duration: 71.201467ms)","trace[1112143573] 'compare' (duration: 48.169934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:58.387044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.581136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-24T14:47:58.387122Z","caller":"traceutil/trace.go:171","msg":"trace[632972252] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:457; }","duration":"117.766068ms","start":"2026-03-24T14:47:58.269337Z","end":"2026-03-24T14:47:58.387103Z","steps":["trace[632972252] 'agreement among raft nodes before linearized reading' (duration: 117.401284ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.697168Z","caller":"traceutil/trace.go:171","msg":"trace[1340459206] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"299.73156ms","start":"2026-03-24T14:47:58.397414Z","end":"2026-03-24T14:47:58.697146Z","steps":["trace[1340459206] 'process raft request' (duration: 299.624619ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.697171Z","caller":"traceutil/trace.go:171","msg":"trace[140434454] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"302.879897ms","start":"2026-03-24T14:47:58.39426Z","end":"2026-03-24T14:47:58.69714Z","steps":["trace[140434454] 'process raft request' (duration: 302.418843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:58.697314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:58.394241Z","time spent":"303.007468ms","remote":"127.0.0.1:57544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:47:58.697487Z","caller":"traceutil/trace.go:171","msg":"trace[1155393447] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"299.991942ms","start":"2026-03-24T14:47:58.39747Z","end":"2026-03-24T14:47:58.697462Z","steps":["trace[1155393447] 'process raft request' (duration: 299.616548ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.697144Z","caller":"traceutil/trace.go:171","msg":"trace[995507117] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"299.76327ms","start":"2026-03-24T14:47:58.397355Z","end":"2026-03-24T14:47:58.697118Z","steps":["trace[995507117] 'process raft request' (duration: 299.592168ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.697701Z","caller":"traceutil/trace.go:171","msg":"trace[4943261] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"295.14909ms","start":"2026-03-24T14:47:58.402509Z","end":"2026-03-24T14:47:58.697658Z","steps":["trace[4943261] 'process raft request' (duration: 294.822197ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:58.697793Z","caller":"traceutil/trace.go:171","msg":"trace[360424272] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:481; }","duration":"206.475938ms","start":"2026-03-24T14:47:58.491304Z","end":"2026-03-24T14:47:58.69778Z","steps":["trace[360424272] 'read index received' (duration: 205.361408ms)","trace[360424272] 'applied index is now lower than readState.Index' (duration: 1.11337ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:58.697908Z","caller":"traceutil/trace.go:171","msg":"trace[1103680221] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"293.940708ms","start":"2026-03-24T14:47:58.40395Z","end":"2026-03-24T14:47:58.697891Z","steps":["trace[1103680221] 'process raft request' (duration: 293.629726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:58.698243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:58.397464Z","time spent":"300.104062ms","remote":"127.0.0.1:57900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:47:58.698329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:58.397341Z","time spent":"300.708898ms","remote":"127.0.0.1:57920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:47:58.697997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.67624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-24T14:47:58.698814Z","caller":"traceutil/trace.go:171","msg":"trace[290550404] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:467; }","duration":"207.503347ms","start":"2026-03-24T14:47:58.491294Z","end":"2026-03-24T14:47:58.698797Z","steps":["trace[290550404] 'agreement among raft nodes before linearized reading' (duration: 206.526019ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:02.013765Z","caller":"traceutil/trace.go:171","msg":"trace[1500671940] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"452.742199ms","start":"2026-03-24T14:48:01.561006Z","end":"2026-03-24T14:48:02.013748Z","steps":["trace[1500671940] 'process raft request' (duration: 452.623548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:02.013933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:01.560986Z","time spent":"452.8619ms","remote":"127.0.0.1:57506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:48:02.020228Z","caller":"traceutil/trace.go:171","msg":"trace[1926947568] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"259.546805ms","start":"2026-03-24T14:48:01.760668Z","end":"2026-03-24T14:48:02.020215Z","steps":["trace[1926947568] 'process raft request' (duration: 259.379754ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:02.020225Z","caller":"traceutil/trace.go:171","msg":"trace[671291476] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"154.653585ms","start":"2026-03-24T14:48:01.865551Z","end":"2026-03-24T14:48:02.020204Z","steps":["trace[671291476] 'read index received' (duration: 148.071188ms)","trace[671291476] 'applied index is now lower than readState.Index' (duration: 6.581187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:02.020385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.818217ms","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-24T14:48:02.020447Z","caller":"traceutil/trace.go:171","msg":"trace[1648075840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"154.921048ms","start":"2026-03-24T14:48:01.865512Z","end":"2026-03-24T14:48:02.020433Z","steps":["trace[1648075840] 'agreement among raft nodes before linearized reading' (duration: 154.788457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:02.020461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.575647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-24T14:48:02.020502Z","caller":"traceutil/trace.go:171","msg":"trace[1346780049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"152.661708ms","start":"2026-03-24T14:48:01.867832Z","end":"2026-03-24T14:48:02.020493Z","steps":["trace[1346780049] 'agreement among raft nodes before linearized reading' (duration: 152.530527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:02.020651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.936191ms","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-24T14:48:02.020702Z","caller":"traceutil/trace.go:171","msg":"trace[22346557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"153.008311ms","start":"2026-03-24T14:48:01.867681Z","end":"2026-03-24T14:48:02.020689Z","steps":["trace[22346557] 'agreement among raft nodes before linearized reading' (duration: 152.636458ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:04.325265Z","caller":"traceutil/trace.go:171","msg":"trace[2075399353] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"157.934509ms","start":"2026-03-24T14:48:04.167299Z","end":"2026-03-24T14:48:04.325234Z","steps":["trace[2075399353] 'process raft request' (duration: 157.578366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.484238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213411699809875154,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-24T14:48:06.666878Z","caller":"traceutil/trace.go:171","msg":"trace[1523485475] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"683.692532ms","start":"2026-03-24T14:48:05.983094Z","end":"2026-03-24T14:48:06.666786Z","steps":["trace[1523485475] 'read index received' (duration: 681.698855ms)","trace[1523485475] 'applied index is now lower than readState.Index' (duration: 1.992177ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:48:06.667523Z","caller":"traceutil/trace.go:171","msg":"trace[1598964048] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"688.992638ms","start":"2026-03-24T14:48:05.978499Z","end":"2026-03-24T14:48:06.667492Z","steps":["trace[1598964048] 'process raft request' (duration: 688.05018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.667659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.978482Z","time spent":"689.090479ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4877,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:48:06.667921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.813592ms","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":"info","ts":"2026-03-24T14:48:06.667987Z","caller":"traceutil/trace.go:171","msg":"trace[583541178] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:559; }","duration":"684.893182ms","start":"2026-03-24T14:48:05.983077Z","end":"2026-03-24T14:48:06.66797Z","steps":["trace[583541178] 'agreement among raft nodes before linearized reading' (duration: 684.797222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.668042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.983068Z","time spent":"684.951293ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-24T14:48:06.668298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.077524ms","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-24T14:48:06.668359Z","caller":"traceutil/trace.go:171","msg":"trace[317508406] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:559; }","duration":"685.173744ms","start":"2026-03-24T14:48:05.983171Z","end":"2026-03-24T14:48:06.668345Z","steps":["trace[317508406] 'agreement among raft nodes before linearized reading' (duration: 685.081874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.668413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.983151Z","time spent":"685.240005ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-03-24T14:48:06.668961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.647845ms","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":"info","ts":"2026-03-24T14:48:06.669024Z","caller":"traceutil/trace.go:171","msg":"trace[1960855798] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:559; }","duration":"262.722646ms","start":"2026-03-24T14:48:06.406286Z","end":"2026-03-24T14:48:06.669009Z","steps":["trace[1960855798] 'agreement among raft nodes before linearized reading' (duration: 262.629115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.66912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.833843ms","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-24T14:48:06.669173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.459591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-24T14:48:06.66923Z","caller":"traceutil/trace.go:171","msg":"trace[17247126] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:559; }","duration":"274.001915ms","start":"2026-03-24T14:48:06.395191Z","end":"2026-03-24T14:48:06.669193Z","steps":["trace[17247126] 'agreement among raft nodes before linearized reading' (duration: 273.821703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.669235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.810535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:06.669282Z","caller":"traceutil/trace.go:171","msg":"trace[2100926491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"543.596582ms","start":"2026-03-24T14:48:06.125651Z","end":"2026-03-24T14:48:06.669248Z","steps":["trace[2100926491] 'agreement among raft nodes before linearized reading' (duration: 543.35668ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:06.669289Z","caller":"traceutil/trace.go:171","msg":"trace[2005228882] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:559; }","duration":"627.886337ms","start":"2026-03-24T14:48:06.041384Z","end":"2026-03-24T14:48:06.66927Z","steps":["trace[2005228882] 'agreement among raft nodes before linearized reading' (duration: 627.817986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.669172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.806164ms","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-24T14:48:06.671255Z","caller":"traceutil/trace.go:171","msg":"trace[230910894] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:559; }","duration":"290.863782ms","start":"2026-03-24T14:48:06.380311Z","end":"2026-03-24T14:48:06.671174Z","steps":["trace[230910894] 'agreement among raft nodes before linearized reading' (duration: 288.694963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.669339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.125641Z","time spent":"543.686524ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:48:06.669448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.041353Z","time spent":"627.973398ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-24T14:48:06.669613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.079979ms","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-24T14:48:06.671889Z","caller":"traceutil/trace.go:171","msg":"trace[1547415571] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:559; }","duration":"630.261947ms","start":"2026-03-24T14:48:06.041485Z","end":"2026-03-24T14:48:06.671747Z","steps":["trace[1547415571] 'agreement among raft nodes before linearized reading' (duration: 627.877797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.67199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.041469Z","time spent":"630.461168ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-24T14:48:06.669668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.156333ms","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-24T14:48:06.672077Z","caller":"traceutil/trace.go:171","msg":"trace[659064151] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:559; }","duration":"688.563143ms","start":"2026-03-24T14:48:05.983476Z","end":"2026-03-24T14:48:06.672039Z","steps":["trace[659064151] 'agreement among raft nodes before linearized reading' (duration: 686.135763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.669681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.516109ms","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":"warn","ts":"2026-03-24T14:48:06.672162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.98347Z","time spent":"688.659815ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"info","ts":"2026-03-24T14:48:06.672171Z","caller":"traceutil/trace.go:171","msg":"trace[146882006] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:559; }","duration":"277.018761ms","start":"2026-03-24T14:48:06.395132Z","end":"2026-03-24T14:48:06.672151Z","steps":["trace[146882006] 'agreement among raft nodes before linearized reading' (duration: 274.484219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.669759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.62205ms","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-24T14:48:06.672488Z","caller":"traceutil/trace.go:171","msg":"trace[1268509976] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:559; }","duration":"277.323324ms","start":"2026-03-24T14:48:06.3951Z","end":"2026-03-24T14:48:06.672424Z","steps":["trace[1268509976] 'agreement among raft nodes before linearized reading' (duration: 274.614981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.669819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.022216ms","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":"warn","ts":"2026-03-24T14:48:06.669851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.168567ms","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-24T14:48:06.672856Z","caller":"traceutil/trace.go:171","msg":"trace[542056069] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:559; }","duration":"292.042292ms","start":"2026-03-24T14:48:06.380764Z","end":"2026-03-24T14:48:06.672806Z","steps":["trace[542056069] 'agreement among raft nodes before linearized reading' (duration: 289.019765ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:06.672941Z","caller":"traceutil/trace.go:171","msg":"trace[642489803] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:559; }","duration":"331.276774ms","start":"2026-03-24T14:48:06.341639Z","end":"2026-03-24T14:48:06.672916Z","steps":["trace[642489803] 'agreement among raft nodes before linearized reading' (duration: 327.729933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.673023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.34161Z","time spent":"331.389434ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-03-24T14:48:06.669855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.782873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-24T14:48:06.673269Z","caller":"traceutil/trace.go:171","msg":"trace[702915753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"548.235292ms","start":"2026-03-24T14:48:06.125007Z","end":"2026-03-24T14:48:06.673242Z","steps":["trace[702915753] 'agreement among raft nodes before linearized reading' (duration: 544.682122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.673329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.124997Z","time spent":"548.316903ms","remote":"127.0.0.1:57704","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-24T14:48:06.669859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.049225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:48:06.669961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.851295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-24T14:48:06.669979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.960652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-24T14:48:06.670016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.481764ms","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-24T14:48:06.670057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.506955ms","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":"warn","ts":"2026-03-24T14:48:06.670057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.786005ms","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-24T14:48:06.670035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.187836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:48:06.670091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.583745ms","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":"warn","ts":"2026-03-24T14:48:06.67014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.223746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:48:06.670132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.846046ms","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":"warn","ts":"2026-03-24T14:48:06.670173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.17165ms","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":"warn","ts":"2026-03-24T14:48:06.670198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.939675ms","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":"warn","ts":"2026-03-24T14:48:06.670638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.59847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:06.675383Z","caller":"traceutil/trace.go:171","msg":"trace[703845053] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:559; }","duration":"303.631464ms","start":"2026-03-24T14:48:06.371722Z","end":"2026-03-24T14:48:06.675354Z","steps":["trace[703845053] 'agreement among raft nodes before linearized reading' (duration: 298.396668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.676068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.371716Z","time spent":"304.33555ms","remote":"127.0.0.1:33480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-03-24T14:48:06.675587Z","caller":"traceutil/trace.go:171","msg":"trace[1743221313] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:559; }","duration":"633.322063ms","start":"2026-03-24T14:48:06.042243Z","end":"2026-03-24T14:48:06.675565Z","steps":["trace[1743221313] 'agreement among raft nodes before linearized reading' (duration: 627.758174ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:06.675767Z","caller":"traceutil/trace.go:171","msg":"trace[208069694] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:559; }","duration":"315.223414ms","start":"2026-03-24T14:48:06.360524Z","end":"2026-03-24T14:48:06.675747Z","steps":["trace[208069694] 'agreement among raft nodes before linearized reading' (duration: 309.460594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.676447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.360514Z","time spent":"315.90868ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-24T14:48:06.675748Z","caller":"traceutil/trace.go:171","msg":"trace[232639204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"564.932318ms","start":"2026-03-24T14:48:06.110806Z","end":"2026-03-24T14:48:06.675739Z","steps":["trace[232639204] 'agreement among raft nodes before linearized reading' (duration: 559.018047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.67661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.110793Z","time spent":"565.804385ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-24T14:48:06.675773Z","caller":"traceutil/trace.go:171","msg":"trace[159487776] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:559; }","duration":"315.229814ms","start":"2026-03-24T14:48:06.360533Z","end":"2026-03-24T14:48:06.675763Z","steps":["trace[159487776] 'agreement among raft nodes before linearized reading' (duration: 309.493604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.676725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.360529Z","time spent":"316.185662ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-03-24T14:48:06.675893Z","caller":"traceutil/trace.go:171","msg":"trace[1743746181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"335.903764ms","start":"2026-03-24T14:48:06.339979Z","end":"2026-03-24T14:48:06.675883Z","steps":["trace[1743746181] 'agreement among raft nodes before linearized reading' (duration: 329.914752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.676819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.339962Z","time spent":"336.850402ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-24T14:48:06.675905Z","caller":"traceutil/trace.go:171","msg":"trace[448375890] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:559; }","duration":"304.023916ms","start":"2026-03-24T14:48:06.37187Z","end":"2026-03-24T14:48:06.675894Z","steps":["trace[448375890] 'agreement among raft nodes before linearized reading' (duration: 298.230926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.677031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.371854Z","time spent":"305.160877ms","remote":"127.0.0.1:33480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-03-24T14:48:06.677051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.042238Z","time spent":"634.05617ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-03-24T14:48:06.675928Z","caller":"traceutil/trace.go:171","msg":"trace[2079443357] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:559; }","duration":"269.95335ms","start":"2026-03-24T14:48:06.405964Z","end":"2026-03-24T14:48:06.675917Z","steps":["trace[2079443357] 'agreement among raft nodes before linearized reading' (duration: 264.182809ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:06.675931Z","caller":"traceutil/trace.go:171","msg":"trace[1233602379] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:559; }","duration":"304.126118ms","start":"2026-03-24T14:48:06.371797Z","end":"2026-03-24T14:48:06.675923Z","steps":["trace[1233602379] 'agreement among raft nodes before linearized reading' (duration: 298.125726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.677201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.371756Z","time spent":"305.435058ms","remote":"127.0.0.1:33480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-03-24T14:48:06.675953Z","caller":"traceutil/trace.go:171","msg":"trace[522206590] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:559; }","duration":"269.667297ms","start":"2026-03-24T14:48:06.406276Z","end":"2026-03-24T14:48:06.675943Z","steps":["trace[522206590] 'agreement among raft nodes before linearized reading' (duration: 263.817716ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:06.675961Z","caller":"traceutil/trace.go:171","msg":"trace[2066432608] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:559; }","duration":"315.462156ms","start":"2026-03-24T14:48:06.360492Z","end":"2026-03-24T14:48:06.675954Z","steps":["trace[2066432608] 'agreement among raft nodes before linearized reading' (duration: 309.575015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.677615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.360487Z","time spent":"317.11571ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"info","ts":"2026-03-24T14:48:06.675722Z","caller":"traceutil/trace.go:171","msg":"trace[994628070] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:559; }","duration":"333.945687ms","start":"2026-03-24T14:48:06.341765Z","end":"2026-03-24T14:48:06.675711Z","steps":["trace[994628070] 'agreement among raft nodes before linearized reading' (duration: 327.235618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.677755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.341761Z","time spent":"335.966145ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 "} {"level":"info","ts":"2026-03-24T14:48:06.675985Z","caller":"traceutil/trace.go:171","msg":"trace[1013615925] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:559; }","duration":"295.763285ms","start":"2026-03-24T14:48:06.380211Z","end":"2026-03-24T14:48:06.675974Z","steps":["trace[1013615925] 'agreement among raft nodes before linearized reading' (duration: 289.951085ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:09.84467Z","caller":"traceutil/trace.go:171","msg":"trace[234831687] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"496.924383ms","start":"2026-03-24T14:48:09.347726Z","end":"2026-03-24T14:48:09.84465Z","steps":["trace[234831687] 'process raft request' (duration: 496.715335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:09.84492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.536332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-24T14:48:09.844964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:09.347711Z","time spent":"497.112311ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:48:09.845052Z","caller":"traceutil/trace.go:171","msg":"trace[1893978082] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:1; response_revision:574; }","duration":"211.690008ms","start":"2026-03-24T14:48:09.633305Z","end":"2026-03-24T14:48:09.844995Z","steps":["trace[1893978082] 'agreement among raft nodes before linearized reading' (duration: 211.450019ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:09.845533Z","caller":"traceutil/trace.go:171","msg":"trace[670415723] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"211.252971ms","start":"2026-03-24T14:48:09.63335Z","end":"2026-03-24T14:48:09.844603Z","steps":["trace[670415723] 'read index received' (duration: 210.97852ms)","trace[670415723] 'applied index is now lower than readState.Index' (duration: 273.381µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:09.845753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.199902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:09.845841Z","caller":"traceutil/trace.go:171","msg":"trace[739962125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"199.311797ms","start":"2026-03-24T14:48:09.646515Z","end":"2026-03-24T14:48:09.845827Z","steps":["trace[739962125] 'agreement among raft nodes before linearized reading' (duration: 199.197692ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:11.139561Z","caller":"traceutil/trace.go:171","msg":"trace[169526402] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"435.362866ms","start":"2026-03-24T14:48:10.704178Z","end":"2026-03-24T14:48:11.139541Z","steps":["trace[169526402] 'read index received' (duration: 435.286943ms)","trace[169526402] 'applied index is now lower than readState.Index' (duration: 74.983µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:48:11.1397Z","caller":"traceutil/trace.go:171","msg":"trace[75358885] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"440.27067ms","start":"2026-03-24T14:48:10.699395Z","end":"2026-03-24T14:48:11.139666Z","steps":["trace[75358885] 'process raft request' (duration: 440.003409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.139792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.582364ms","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-24T14:48:11.139824Z","caller":"traceutil/trace.go:171","msg":"trace[1716410512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"435.654007ms","start":"2026-03-24T14:48:10.704162Z","end":"2026-03-24T14:48:11.139816Z","steps":["trace[1716410512] 'agreement among raft nodes before linearized reading' (duration: 435.493741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.139852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.704155Z","time spent":"435.691269ms","remote":"127.0.0.1:57704","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-24T14:48:11.139857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.69937Z","time spent":"440.404225ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:48:11.139848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.997282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-24T14:48:11.139916Z","caller":"traceutil/trace.go:171","msg":"trace[2077002625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"435.085625ms","start":"2026-03-24T14:48:10.704813Z","end":"2026-03-24T14:48:11.139898Z","steps":["trace[2077002625] 'agreement among raft nodes before linearized reading' (duration: 434.885837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.139964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.704809Z","time spent":"435.146947ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:11.140028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.48677ms","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-24T14:48:11.140098Z","caller":"traceutil/trace.go:171","msg":"trace[433263304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:576; }","duration":"309.604644ms","start":"2026-03-24T14:48:10.830477Z","end":"2026-03-24T14:48:11.140081Z","steps":["trace[433263304] 'agreement among raft nodes before linearized reading' (duration: 309.417016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.140109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.071145ms","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-24T14:48:11.140144Z","caller":"traceutil/trace.go:171","msg":"trace[200110491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"435.146337ms","start":"2026-03-24T14:48:10.704986Z","end":"2026-03-24T14:48:11.140132Z","steps":["trace[200110491] 'agreement among raft nodes before linearized reading' (duration: 435.005241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.14018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.830447Z","time spent":"309.684968ms","remote":"127.0.0.1:57704","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-24T14:48:11.140192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.704962Z","time spent":"435.22377ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-24T14:48:11.909594Z","caller":"traceutil/trace.go:171","msg":"trace[139591627] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"766.312925ms","start":"2026-03-24T14:48:11.143257Z","end":"2026-03-24T14:48:11.90957Z","steps":["trace[139591627] 'process raft request' (duration: 766.097666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.909734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:11.14324Z","time spent":"766.421199ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:48:11.918665Z","caller":"traceutil/trace.go:171","msg":"trace[1003527767] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"774.916551ms","start":"2026-03-24T14:48:11.143731Z","end":"2026-03-24T14:48:11.918648Z","steps":["trace[1003527767] 'process raft request' (duration: 774.862239ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:11.918764Z","caller":"traceutil/trace.go:171","msg":"trace[8994740] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:599; }","duration":"304.809298ms","start":"2026-03-24T14:48:11.613942Z","end":"2026-03-24T14:48:11.918752Z","steps":["trace[8994740] 'read index received' (duration: 295.412159ms)","trace[8994740] 'applied index is now lower than readState.Index' (duration: 9.396168ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:11.918843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.894632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:11.918874Z","caller":"traceutil/trace.go:171","msg":"trace[463984606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"304.962554ms","start":"2026-03-24T14:48:11.613902Z","end":"2026-03-24T14:48:11.918864Z","steps":["trace[463984606] 'agreement among raft nodes before linearized reading' (duration: 304.893141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.918901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:11.613883Z","time spent":"305.012986ms","remote":"127.0.0.1:57482","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-24T14:48:11.918915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:11.143723Z","time spent":"775.033277ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:48:11.919029Z","caller":"traceutil/trace.go:171","msg":"trace[637484038] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"774.734645ms","start":"2026-03-24T14:48:11.144283Z","end":"2026-03-24T14:48:11.919018Z","steps":["trace[637484038] 'process raft request' (duration: 774.34104ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:11.918696Z","caller":"traceutil/trace.go:171","msg":"trace[1792997714] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"775.092729ms","start":"2026-03-24T14:48:11.143583Z","end":"2026-03-24T14:48:11.918675Z","steps":["trace[1792997714] 'process raft request' (duration: 774.918722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.91915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:11.143564Z","time spent":"775.561208ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:48:11.919085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:11.144274Z","time spent":"774.778636ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:48:14.484117Z","caller":"traceutil/trace.go:171","msg":"trace[1538628715] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"118.288105ms","start":"2026-03-24T14:48:14.365808Z","end":"2026-03-24T14:48:14.484096Z","steps":["trace[1538628715] 'process raft request' (duration: 112.274062ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:15.503423Z","caller":"traceutil/trace.go:171","msg":"trace[546461316] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"117.978428ms","start":"2026-03-24T14:48:15.385418Z","end":"2026-03-24T14:48:15.503397Z","steps":["trace[546461316] 'process raft request' (duration: 114.885189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:35.007892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.264633ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-24T14:49:42.883926Z","caller":"traceutil/trace.go:171","msg":"trace[2075624285] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"317.08128ms","start":"2026-03-24T14:49:42.566808Z","end":"2026-03-24T14:49:42.88389Z","steps":["trace[2075624285] 'read index received' (duration: 316.815293ms)","trace[2075624285] 'applied index is now lower than readState.Index' (duration: 264.537µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:42.884049Z","caller":"traceutil/trace.go:171","msg":"trace[1493785402] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"329.052651ms","start":"2026-03-24T14:49:42.554949Z","end":"2026-03-24T14:49:42.884002Z","steps":["trace[1493785402] 'process raft request' (duration: 328.718963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:42.884263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.435619ms","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-24T14:49:42.884334Z","caller":"traceutil/trace.go:171","msg":"trace[1714790165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"317.598274ms","start":"2026-03-24T14:49:42.566715Z","end":"2026-03-24T14:49:42.884313Z","steps":["trace[1714790165] 'agreement among raft nodes before linearized reading' (duration: 317.366898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:42.884388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:42.566679Z","time spent":"317.694205ms","remote":"127.0.0.1:57704","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-24T14:49:42.884465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:42.554924Z","time spent":"329.196335ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:49:42.884506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.148872ms","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-24T14:49:42.884752Z","caller":"traceutil/trace.go:171","msg":"trace[1343750494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"317.417439ms","start":"2026-03-24T14:49:42.567306Z","end":"2026-03-24T14:49:42.884723Z","steps":["trace[1343750494] 'agreement among raft nodes before linearized reading' (duration: 316.946737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:42.884789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.917438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:49:42.884832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:42.567289Z","time spent":"317.526281ms","remote":"127.0.0.1:57704","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-24T14:49:42.885126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.216708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-24T14:49:42.885199Z","caller":"traceutil/trace.go:171","msg":"trace[2090300540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"318.28799ms","start":"2026-03-24T14:49:42.566892Z","end":"2026-03-24T14:49:42.88518Z","steps":["trace[2090300540] 'agreement among raft nodes before linearized reading' (duration: 317.291325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:42.885236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:42.566869Z","time spent":"318.357772ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-24T14:49:42.885254Z","caller":"traceutil/trace.go:171","msg":"trace[254814105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"270.836261ms","start":"2026-03-24T14:49:42.614365Z","end":"2026-03-24T14:49:42.885201Z","steps":["trace[254814105] 'agreement among raft nodes before linearized reading' (duration: 269.865357ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.242005Z","caller":"traceutil/trace.go:171","msg":"trace[1479371701] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"225.11482ms","start":"2026-03-24T14:50:03.016857Z","end":"2026-03-24T14:50:03.241971Z","steps":["trace[1479371701] 'read index received' (duration: 224.69979ms)","trace[1479371701] 'applied index is now lower than readState.Index' (duration: 413.61µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:03.242131Z","caller":"traceutil/trace.go:171","msg":"trace[539819457] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"229.022381ms","start":"2026-03-24T14:50:03.01308Z","end":"2026-03-24T14:50:03.242103Z","steps":["trace[539819457] 'process raft request' (duration: 228.54134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.242293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.016572ms","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-24T14:50:03.242276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.365385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-public/\" range_end:\"/registry/services/specs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:50:03.242404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.627816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-03-24T14:50:03.242343Z","caller":"traceutil/trace.go:171","msg":"trace[1515602744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"223.110205ms","start":"2026-03-24T14:50:03.01922Z","end":"2026-03-24T14:50:03.242331Z","steps":["trace[1515602744] 'agreement among raft nodes before linearized reading' (duration: 222.92315ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.242439Z","caller":"traceutil/trace.go:171","msg":"trace[165833161] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1035; }","duration":"170.708898ms","start":"2026-03-24T14:50:03.071718Z","end":"2026-03-24T14:50:03.242427Z","steps":["trace[165833161] 'agreement among raft nodes before linearized reading' (duration: 170.658387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.242411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.084382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-24T14:50:03.242342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.091232ms","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-24T14:50:03.242494Z","caller":"traceutil/trace.go:171","msg":"trace[350746426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"176.197124ms","start":"2026-03-24T14:50:03.066274Z","end":"2026-03-24T14:50:03.242471Z","steps":["trace[350746426] 'agreement among raft nodes before linearized reading' (duration: 175.918188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.242521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.828386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-03-24T14:50:03.242537Z","caller":"traceutil/trace.go:171","msg":"trace[1777160324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"176.347108ms","start":"2026-03-24T14:50:03.066158Z","end":"2026-03-24T14:50:03.242505Z","steps":["trace[1777160324] 'agreement among raft nodes before linearized reading' (duration: 176.132693ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.242603Z","caller":"traceutil/trace.go:171","msg":"trace[1007199824] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1035; }","duration":"198.928857ms","start":"2026-03-24T14:50:03.043651Z","end":"2026-03-24T14:50:03.242579Z","steps":["trace[1007199824] 'agreement among raft nodes before linearized reading' (duration: 198.708052ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.242461Z","caller":"traceutil/trace.go:171","msg":"trace[1585281258] range","detail":"{range_begin:/registry/services/specs/kube-public/; range_end:/registry/services/specs/kube-public0; response_count:0; response_revision:1035; }","duration":"225.542899ms","start":"2026-03-24T14:50:03.016839Z","end":"2026-03-24T14:50:03.242382Z","steps":["trace[1585281258] 'agreement among raft nodes before linearized reading' (duration: 225.303413ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.505811Z","caller":"traceutil/trace.go:171","msg":"trace[846349975] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"258.16088ms","start":"2026-03-24T14:50:03.247621Z","end":"2026-03-24T14:50:03.505782Z","steps":["trace[846349975] 'process raft request' (duration: 247.465783ms)","trace[846349975] 'compare' (duration: 10.424211ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:03.549834Z","caller":"traceutil/trace.go:171","msg":"trace[2106203994] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"302.134822ms","start":"2026-03-24T14:50:03.247672Z","end":"2026-03-24T14:50:03.549807Z","steps":["trace[2106203994] 'read index received' (duration: 247.461404ms)","trace[2106203994] 'applied index is now lower than readState.Index' (duration: 54.672358ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:03.549989Z","caller":"traceutil/trace.go:171","msg":"trace[1263080851] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"302.192254ms","start":"2026-03-24T14:50:03.247747Z","end":"2026-03-24T14:50:03.549939Z","steps":["trace[1263080851] 'process raft request' (duration: 302.01526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.550043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.335017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-03-24T14:50:03.550101Z","caller":"traceutil/trace.go:171","msg":"trace[634067585] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1038; }","duration":"302.43098ms","start":"2026-03-24T14:50:03.247652Z","end":"2026-03-24T14:50:03.550083Z","steps":["trace[634067585] 'agreement among raft nodes before linearized reading' (duration: 302.298497ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.549848Z","caller":"traceutil/trace.go:171","msg":"trace[1975685163] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"302.195504ms","start":"2026-03-24T14:50:03.247629Z","end":"2026-03-24T14:50:03.549824Z","steps":["trace[1975685163] 'process raft request' (duration: 301.918647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.55016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:50:03.247643Z","time spent":"302.503391ms","remote":"127.0.0.1:57916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2941,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-03-24T14:50:03.550228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:50:03.247692Z","time spent":"302.375538ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:50:03.550274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.963284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:50:03.550277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.221294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-public/\" range_end:\"/registry/statefulsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:50:03.550326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:50:03.247612Z","time spent":"302.606184ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:50:03.550344Z","caller":"traceutil/trace.go:171","msg":"trace[1081849714] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1038; }","duration":"224.095887ms","start":"2026-03-24T14:50:03.326229Z","end":"2026-03-24T14:50:03.550325Z","steps":["trace[1081849714] 'agreement among raft nodes before linearized reading' (duration: 223.901043ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:03.550436Z","caller":"traceutil/trace.go:171","msg":"trace[239372286] range","detail":"{range_begin:/registry/statefulsets/kube-public/; range_end:/registry/statefulsets/kube-public0; response_count:0; response_revision:1038; }","duration":"285.395997ms","start":"2026-03-24T14:50:03.264972Z","end":"2026-03-24T14:50:03.550368Z","steps":["trace[239372286] 'agreement among raft nodes before linearized reading' (duration: 285.176232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.550862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.870826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-03-24T14:50:03.550957Z","caller":"traceutil/trace.go:171","msg":"trace[1068572903] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1038; }","duration":"302.01958ms","start":"2026-03-24T14:50:03.248916Z","end":"2026-03-24T14:50:03.550936Z","steps":["trace[1068572903] 'agreement among raft nodes before linearized reading' (duration: 301.14383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:03.551006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:50:03.248903Z","time spent":"302.092291ms","remote":"127.0.0.1:57916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14503,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"info","ts":"2026-03-24T14:50:03.862142Z","caller":"traceutil/trace.go:171","msg":"trace[1958277248] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"114.25332ms","start":"2026-03-24T14:50:03.747855Z","end":"2026-03-24T14:50:03.862108Z","steps":["trace[1958277248] 'process raft request' (duration: 114.037955ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:09.461362Z","caller":"traceutil/trace.go:171","msg":"trace[271485698] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"128.872697ms","start":"2026-03-24T14:50:09.332451Z","end":"2026-03-24T14:50:09.461324Z","steps":["trace[271485698] 'read index received' (duration: 128.651453ms)","trace[271485698] 'applied index is now lower than readState.Index' (duration: 219.824µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:09.461451Z","caller":"traceutil/trace.go:171","msg":"trace[1182435563] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"172.476698ms","start":"2026-03-24T14:50:09.288942Z","end":"2026-03-24T14:50:09.461419Z","steps":["trace[1182435563] 'process raft request' (duration: 172.172801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:09.461599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.132233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-24T14:50:09.461667Z","caller":"traceutil/trace.go:171","msg":"trace[1058972630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1052; }","duration":"129.272696ms","start":"2026-03-24T14:50:09.332375Z","end":"2026-03-24T14:50:09.461648Z","steps":["trace[1058972630] 'agreement among raft nodes before linearized reading' (duration: 129.140833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:09.461726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.019668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-03-24T14:50:09.461763Z","caller":"traceutil/trace.go:171","msg":"trace[1559406783] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:1052; }","duration":"112.12086ms","start":"2026-03-24T14:50:09.349633Z","end":"2026-03-24T14:50:09.461754Z","steps":["trace[1559406783] 'agreement among raft nodes before linearized reading' (duration: 111.985337ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:10.052282Z","caller":"traceutil/trace.go:171","msg":"trace[1984876796] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"111.323388ms","start":"2026-03-24T14:50:09.940936Z","end":"2026-03-24T14:50:10.052259Z","steps":["trace[1984876796] 'process raft request' (duration: 111.132084ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:50:17.796621Z","caller":"traceutil/trace.go:171","msg":"trace[571453929] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"106.539223ms","start":"2026-03-24T14:50:17.690052Z","end":"2026-03-24T14:50:17.796591Z","steps":["trace[571453929] 'read index received' (duration: 106.360509ms)","trace[571453929] 'applied index is now lower than readState.Index' (duration: 177.334µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:50:17.796714Z","caller":"traceutil/trace.go:171","msg":"trace[1912635110] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"131.560438ms","start":"2026-03-24T14:50:17.66512Z","end":"2026-03-24T14:50:17.796681Z","steps":["trace[1912635110] 'process raft request' (duration: 131.278202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:50:17.796939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.822331ms","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-24T14:50:17.797128Z","caller":"traceutil/trace.go:171","msg":"trace[238533293] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1075; }","duration":"107.054166ms","start":"2026-03-24T14:50:17.689996Z","end":"2026-03-24T14:50:17.79705Z","steps":["trace[238533293] 'agreement among raft nodes before linearized reading' (duration: 106.75937ms)"],"step_count":1}